Re: Rückmeldungen Nightly 1.9.251/252
Verfasst: Di Feb 08, 2022 11:44 am
@LenaK: Danke fuer PR#2009! Das duerfte in Zukunft helfen, auch beim AlphaESS-Modul!
Bitte mit der aktuellen Nightly nochmal testen. Nach dem Update unbedingt ein Reboot machen, um eventuell alte angesammelte Prozesse zu beenden.Kitmgue hat geschrieben: ↑Di Feb 08, 2022 9:58 am Mit der Nightly von gestern (75ab0a2) läuft irgendwas bzgl. SMA nicht so ganz rund:
Der Wechselrichter ist aber da und auch unter dieser IP-Adresse zuverlässig zu erreichen.Code: Alles auswählen
2022-02-08 10:56:24: **** FATAL ********************************* (LV0) 2022-02-08 10:56:24: **** FATAL Regulation loop needs 14 seconds (LV0) 2022-02-08 10:56:24: **** FATAL ********************************* (LV0) modules.common.fault_state.FaultState: ("modules.common.modbus <class 'modules.common.fault_state.FaultState'> ('modules.common.modbus Modbus Error: [Input/Output] Modbus Error: [Invalid Message] Incomplete message received, expected at least 8 bytes (0 received)', <FaultStateLevel.ERROR: 2>)", <FaultStateLevel.ERROR: 2>) str(e)) from e File "/var/www/html/openWB/packages/modules/common/modbus.py", line 97, in __read_registers self.delegate.read_holding_registers, address, types, byteorder, wordorder, **kwargs File "/var/www/html/openWB/packages/modules/common/modbus.py", line 115, in read_holding_registers energy = client.read_holding_registers(30529, ModbusDataType.UINT_32, unit=3) File "/var/www/html/openWB/modules/wr_tripower9000/tripower.py", line 37, in update_sma_modbus update_sma_modbus(filter("none".__ne__, sys.argv[2:])) File "/var/www/html/openWB/modules/wr_tripower9000/tripower.py", line 55, in update_sma update_sma() File "/var/www/html/openWB/modules/wr_tripower9000/tripower.py", line 61, in <module> Traceback (most recent call last): The above exception was the direct cause of the following exception: modules.common.fault_state.FaultState: ('modules.common.modbus Modbus Error: [Input/Output] Modbus Error: [Invalid Message] Incomplete message received, expected at least 8 bytes (0 received)', <FaultStateLevel.ERROR: 2>) raise FaultState.error(__name__+" "+str(response)) File "/var/www/html/openWB/packages/modules/common/modbus.py", line 81, in __read_registers Traceback (most recent call last): 2022-02-08 10:56:12: **** FATAL ********************************* (LV0) 2022-02-08 10:56:12: **** FATAL Regulation loop needs 21 seconds (LV0) 2022-02-08 10:56:12: **** FATAL ********************************* (LV0) 2022-02-08 10:56:09: Fetching SMA counter data timed out (LV0) 2022-02-08 10:56:00: **** FATAL ********************************* (LV0) 2022-02-08 10:56:00: **** FATAL Regulation loop needs 19 seconds (LV0) 2022-02-08 10:56:00: **** FATAL ********************************* (LV0) 2022-02-08 10:55:50: **** FATAL ********************************* (LV0) 2022-02-08 10:55:50: **** FATAL Regulation loop needs 19 seconds (LV0) 2022-02-08 10:55:50: **** FATAL ********************************* (LV0) pymodbus.exceptions.ConnectionException: Modbus Error: [Connection] Failed to connect[ModbusTcpClient(192.168.178.102:502)] raise ConnectionException("Failed to connect[%s]" % (self.__str__())) File "/usr/local/lib/python3.5/dist-packages/pymodbus/client/sync.py", line 119, in __enter__ self.delegate.__enter__() File "/var/www/html/openWB/packages/modules/common/modbus.py", line 48, in __enter__ with ModbusClient(ipaddress) as client: File "/var/www/html/openWB/modules/wr_tripower9000/tripower.py", line 33, in update_sma_modbus update_sma_modbus(filter("none".__ne__, sys.argv[2:])) File "/var/www/html/openWB/modules/wr_tripower9000/tripower.py", line 55, in update_sma update_sma() File "/var/www/html/openWB/modules/wr_tripower9000/tripower.py", line 61, in <module> Traceback (most recent call last): 2022-02-08 10:55:38: PID: 14819: pymodbus.client.sync: Connection to (192.168.178.102, 502) failed: timed out
Am Netz hat sich nichts verändert.Code: Alles auswählen
ping -t 192.168.178.102 Ping wird ausgeführt für 192.168.178.102 mit 32 Bytes Daten: Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit=5ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Antwort von 192.168.178.102: Bytes=32 Zeit<1ms TTL=64 Ping-Statistik für 192.168.178.102: Pakete: Gesendet = 10, Empfangen = 10, Verloren = 0 (0% Verlust), Ca. Zeitangaben in Millisek.: Minimum = 0ms, Maximum = 5ms, Mittelwert = 0ms
Ergänzung: mit dem Modbus Master Simulator komme ich einwandfrei auf den Wechselrichter drauf.
sieht wieder gut aus.
Code: Alles auswählen
2022-02-08 15:09:39: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:09:29: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:09:19: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:09:09: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:08:59: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:08:19: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:08:09: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:07:39: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:07:29: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:07:19: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:06:09: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:05:59: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:05:49: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:05:40: **** FATAL ********************************* (LV0)
2022-02-08 15:05:40: **** FATAL Regulation loop needs 9 seconds (LV0)
2022-02-08 15:05:40: **** FATAL ********************************* (LV0)
2022-02-08 15:05:30: **** FATAL ********************************* (LV0)
2022-02-08 15:05:30: **** FATAL Regulation loop needs 9 seconds (LV0)
2022-02-08 15:05:30: **** FATAL ********************************* (LV0)
2022-02-08 15:05:20: **** FATAL ********************************* (LV0)
2022-02-08 15:05:20: **** FATAL Regulation loop needs 9 seconds (LV0)
2022-02-08 15:05:20: **** FATAL ********************************* (LV0)
2022-02-08 15:05:09: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:05:03: ##### cron5min.sh finished ##### (LV0)
2022-02-08 15:05:01: ##### cron5min.sh started ##### (LV0)
2022-02-08 15:04:59: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:03:50: **** FATAL ********************************* (LV0)
2022-02-08 15:03:50: **** FATAL Regulation loop needs 9 seconds (LV0)
2022-02-08 15:03:50: **** FATAL ********************************* (LV0)
2022-02-08 15:01:59: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:01:29: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:01:19: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:00:59: **** FATAL ********************************* (LV0)
2022-02-08 15:00:59: **** FATAL Regulation loop needs 9 seconds (LV0)
2022-02-08 15:00:59: **** FATAL ********************************* (LV0)
2022-02-08 15:00:48: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:00:38: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:00:28: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:00:18: **** WARNING **** Regulation loop needs 8 seconds (LV0)
2022-02-08 15:00:10: **** FATAL ********************************* (LV0)
2022-02-08 15:00:10: **** FATAL Regulation loop needs 10 seconds (LV0)
2022-02-08 15:00:10: **** FATAL ********************************* (LV0)
Code: Alles auswählen
2022-02-08 15:14:11: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh
2022-02-08 15:14:10: **** FATAL ********************************* (LV0) at 61 cleanup /var/www/html/openWB/regel.sh
2022-02-08 15:14:10: **** FATAL Regulation loop needs 9 seconds (LV0) at 60 cleanup /var/www/html/openWB/regel.sh
2022-02-08 15:14:10: **** FATAL ********************************* (LV0) at 59 cleanup /var/www/html/openWB/regel.sh
2022-02-08 15:14:10: Überschuss -21; mindestens 1400 (LV1) at 104 nurpvlademodus nurpv.sh
2022-02-08 15:14:10: uberschuss -21 wattbezug 21 ladestatus 0 llsoll 0 pvwatt -568 mindestuberschussphasen 1400 wattkombiniert -21 schaltschwelle 230 (LV2) at 575 main /var/www/html/openWB/regel.sh
2022-02-08 15:14:10: anzahlphasen 1 (LV1) at 574 main /var/www/html/openWB/regel.sh
2022-02-08 15:14:10: LP1 Modbus 0 korrekt (LV1) at 236 evsemodbuscheck evsedintest.sh
2022-02-08 15:14:08: Timing Umschaltung: 720 / 240 (LV1) at 15 u1p3pswitch u1p3p.sh
2022-02-08 15:14:08: automatische Umschaltung aktiv (LV1) at 14 u1p3pswitch u1p3p.sh
2022-02-08 15:14:08: Zeit zum abfragen aller Werte 7 Sekunden (LV1) at 176 main /var/www/html/openWB/regel.sh
2022-02-08 15:14:07: chargestatlp1 0 chargestatlp2 0 chargestatlp3 0 (LV1) at 1275 loadvars loadvars.sh
2022-02-08 15:14:07: plugstatlp1 1 plugstatlp2 0 plugstatlp3 0 (LV1) at 1274 loadvars loadvars.sh
2022-02-08 15:14:07: lp1enabled 1 lp2enabled 1 lp3enabled 1 (LV1) at 1273 loadvars loadvars.sh
2022-02-08 15:14:07: EVU 1:V/1A 2: V/0A 3: V/0A (LV1) at 1272 loadvars loadvars.sh
2022-02-08 15:14:07: lla3 0 llv3 228.7 llas13 0 llas23 soclp1 88 soclp2 57 (LV1) at 1271 loadvars loadvars.sh
2022-02-08 15:14:07: lla2 0 llv2 228.4 llas12 0 llas22 sofortll 32 hausverbrauch 589 wattbezug 21 uberschuss -21 (LV1) at 1270 loadvars loadvars.sh
2022-02-08 15:14:07: lla1 0 llv1 226.9 llas11 0 llas21 mindestuberschuss 1400 abschaltuberschuss 6 lademodus 2 (LV1) at 1269 loadvars loadvars.sh
2022-02-08 15:14:07: pv1watt -568 pv2watt pvwatt -568 ladeleistung 0 llalt 0 nachtladen 0 nachtladen 1 minimalA 10 maximalA 32 (LV1) at 1268 loadvars loadvars.sh
2022-02-08 15:14:07: EVU RET: 0 (LV2) at 24 main modules/bezug_smashm/main.sh
2022-02-08 15:14:07: PID: 2589: legacy run server: Completed running command in 0.90s: ["modules.sma.device","counter",""]
2022-02-08 15:14:07: PID: 2589: root: Update completed successfully
2022-02-08 15:14:07: PID: 2589: ValueStore: Saving CounterState(power=21.8, voltages=[226.946, 228.393, 228.644], powers=[13.2, 7.3, 1.4], exported=149255.59999999998, currents=[1.846, 0.865, 0.902], imported=4716535.899999999, power_factors=[0.037, 0.092, 0.011], frequency=49.994)
2022-02-08 15:14:07: PID: 2589: root: Komponente SMA Smarthome Manager Zähler auslesen.
2022-02-08 15:14:07: PID: 2589: root: SMA-Datagramm: {'s2supplycounterunit': 'kVAh', 'q2consumecounter': 2092.9808, 'qconsume': 560.1, 'p2consumeunit': 'W', 'i3unit': 'A', 'u2unit': 'V', 'q2supplycounterunit': 'kVArh', 'frequency': 49.994, 'u3': 228.644, 'pconsumeunit': 'W', 'u3unit': 'V', 's1supplyunit': 'VA', 'u2': 228.393, 's2supply': 79.0, 'sconsume': 0.0, 'p2supplycounterunit': 'kWh', 's1consume': 0.0, 'q2supplycounter': 97.2429, 'q2supply': 0.0, 'qconsumecounter': 5731.3276, 'cosphi3': 0.011, 'p2consumecounterunit': 'kWh', 'p3supplycounterunit': 'kWh', 'i1': 1.846, 'ssupply': 559.6, 'qsupplyunit': 'VAr', 'psupply': 0.0, 'sconsumecounter': 66.6626, 'pconsumecounterunit': 'kWh', 'q1consumeunit': 'VAr', 's3supplycounter': 859.3038, 'psupplyunit': 'W', 'i2unit': 'A', 'sconsumeunit': 'VA', 's2consumecounter': 8.1015, 's1consumecounterunit': 'kVAh', 'q2consumecounterunit': 'kVArh', 'frequencyunit': 'Hz', 'cosphiunit': '°', 'qconsumecounterunit': 'kVArh', 'p2supply': 0.0, 'q3supplycounterunit': 'kVArh', 'p1supplyunit': 'W', 'cosphi1unit': '°', 'q1consumecounter': 2091.5428, 'p3consumeunit': 'W', 'sconsumecounterunit': 'kVAh', 'u1unit': 'V', 'p1supplycounterunit': 'kWh', 'q1consumecounterunit': 'kVArh', 'qsupplycounter': 214.7632, 'q3consume': 121.6, 'p2supplyunit': 'W', 'q3supplyunit': 'VAr', 'ssupplycounterunit': 'kVAh', 'pconsumecounter': 4716.5359, 'speedwire-version': '2.8.5.R|020805', 'p3consumecounter': 1425.0009, 'q1supplyunit': 'VAr', 'p1consumecounterunit': 'kWh', 's3supplycounterunit': 'kVAh', 'qsupplycounterunit': 'kVArh', 's2consume': 0.0, 'q3consumecounterunit': 'kVArh', 'q3supply': 0.0, 'q2consumeunit': 'VAr', 'qconsumeunit': 'VAr', 'p1consumeunit': 'W', 's1supplycounter': 916.0827, 'psupplycounterunit': 'kWh', 'cosphi2unit': '°', 's2supplyunit': 'VA', 'q1supplycounter': 89.9238, 'psupplycounter': 149.2556, 'q3supplycounter': 119.4607, 'u1': 226.946, 'p3consumecounterunit': 'kWh', 'p3consume': 1.4, 'p2consumecounter': 1707.5521, 'ssupplycounter': 2681.8063, 'p2consume': 7.3, 'q1consume': 359.3, 's1consumecounter': 112.4561, 'i3': 0.902, 'q2consume': 79.4, 'cosphi2': 0.092, 'p1consume': 13.2, 's3supplyunit': 'VA', 's1consumeunit': 'VA', 'q3consumecounter': 1807.3709, 'p3supplyunit': 'W', 's1supply': 359.0, 's2consumeunit': 'VA', 'p1supply': 0.0, 'ssupplyunit': 'VA', 's2supplycounter': 960.3323, 'pconsume': 21.8, 's2consumecounterunit': 'kVAh', 'p3supplycounter': 110.8227, 'q1supply': 0.0, 'i2': 0.865, 'q3consumeunit': 'VAr', 'p1consumecounter': 1687.2104, 'p3supply': 0.0, 'q2supplyunit': 'VAr', 's1supplycounterunit': 'kVAh', 's3consume': 0.0, 'p1supplycounter': 52.5785, 'q1supplycounterunit': 'kVArh', 'cosphi1': 0.037, 's3consumecounterunit': 'kVAh', 'p2supplycounter': 89.082, 's3consumeunit': 'VA', 'i1unit': 'A', 'qsupply': 0.0, 'cosphi': 0.039, 'cosphi3unit': '°', 'serial': 3009883967, 's3consumecounter': 0.0175, 's3supply': 121.6}
2022-02-08 15:14:06: PID: 2589: root: Beginning update
2022-02-08 15:14:06: PID: 2589: root: SMA serials: None
2022-02-08 15:14:06: PID: 2589: legacy run server: Received command ["modules.sma.device","counter",""]
2022-02-08 15:14:05: Calling SoC-Module <modules/soc_evcc/main.sh> (LV2) at 22 run_soc_module loadvars.sh
2022-02-08 15:14:04: PID: 31347: SMA ModbusTCP WR: Update completed successfully
2022-02-08 15:14:04: PID: 31347: ValueStore: Saving InverterState(currents=[0, 0, 0], counter=553472, power=-568)
2022-02-08 15:14:04: PID: 31347: SMA ModbusTCP WR: 192.168.178.102: power = 568 W, energy = 553472 Wh
2022-02-08 15:14:04: PID: 31347: pymodbus.payload: [b'\x00\x08', b'r\x00']
2022-02-08 15:14:04: PID: 31347: pymodbus.payload: [8, 29184]
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Getting transaction 2
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Adding transaction 2
2022-02-08 15:14:04: PID: 31347: pymodbus.factory: Factory Response[ReadHoldingRegistersResponse: 3]
2022-02-08 15:14:04: PID: 31347: pymodbus.framer.socket_framer: Processing: 0x0 0x2 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x0 0x8 0x72 0x0
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: RECV: 0x0 0x2 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x0 0x8 0x72 0x0
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-08 15:14:04: PID: 31347: pymodbus.client.sync: New Transaction state 'SENDING'
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: SEND: 0x0 0x2 0x0 0x0 0x0 0x6 0x3 0x3 0x77 0x41 0x0 0x2
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Running transaction 2
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Current transaction state - TRANSACTION_COMPLETE
2022-02-08 15:14:04: PID: 31347: pymodbus.payload: [b'\x00\x00', b'\x028']
2022-02-08 15:14:04: PID: 31347: pymodbus.payload: [0, 568]
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Getting transaction 1
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Adding transaction 1
2022-02-08 15:14:04: PID: 31347: pymodbus.factory: Factory Response[ReadHoldingRegistersResponse: 3]
2022-02-08 15:14:04: PID: 31347: pymodbus.framer.socket_framer: Processing: 0x0 0x1 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x0 0x0 0x2 0x38
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: RECV: 0x0 0x1 0x0 0x0 0x0 0x7 0x3 0x3 0x4 0x0 0x0 0x2 0x38
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Changing transaction state from 'SENDING' to 'WAITING FOR REPLY'
2022-02-08 15:14:04: PID: 31347: pymodbus.client.sync: New Transaction state 'SENDING'
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: SEND: 0x0 0x1 0x0 0x0 0x0 0x6 0x3 0x3 0x78 0x37 0x0 0x2
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Running transaction 1
2022-02-08 15:14:04: PID: 31347: pymodbus.transaction: Current transaction state - IDLE
2022-02-08 15:14:04: PID: 31347: SMA ModbusTCP WR: Beginning update
2022-02-08 15:14:01: **** Regulation loop start **** (LV1) at 70 main /var/www/html/openWB/regel.sh