ModbusAttr an Wechselrichter SolarEdge SE10k [gelöst]

[BenMarloe]

Aus meinem Wechselrichter konnte ich ein paar Mal mehrere Daten auslesen. Nach dem Hinzufügen weiterer Readings ging nur noch das erste Reading. Danach Disconnect, dann Reconnect und erneut wieder nur das erste Reading. Das Löschen von Readings hat die Lage nicht verbessert. Nur das erste Reading wird ausgelesen. In diesem Falle also "AC-Volt-A".
Hat jemand eine Idee? Was mache ich falsch? Hänge schon mehrere Tage an diesem Problem und habe die spärlichen Beiträge im Forum dazu auch schon gelesen.
Bitte helft mir.

Code Auswählen Erweitern


Internals:
   BUSY       0
   DEF        1 30 192.168.178.36 TCP
   DEST       192.168.178.36:502
   DeviceName 192.168.178.36:502
   INTERVAL   10
   IODev      KeSolarEdge
   LASTOPEN   1512773143.95136
   MODBUSID   1
   ModuleVersion 3.7.0 - 20.8.2017
   NAME       KeSolarEdge
   NEXT_OPEN  1512773158.82976
   NOTIFYDEV  global
   NR         367
   NTFY_ORDER 50-SOE
   PARTIAL
   PROTOCOL   TCP
   REQUESTHEX 00520000000601039c8f0001
   STATE      disconnected
   TRIGGERTIME 1512773148.62715
   TRIGGERTIME_FMT 2017-12-08 23:45:48
   TYPE       ModbusAttr
   devioLoglevel 3
   nextOpenDelay 60
   READINGS:
     2017-12-08 23:19:57   AC-Energy-All   44923
     2017-12-08 23:44:58   AC-Volt-A       228.6
     2017-12-08 23:44:58   state           disconnected
   defptr:
     1:
   gotReadings:
     AC-Volt-A  228.6
   helper:
     buffer
     lid        1
     lrecv      1512773098.75291
     lsend      1512773098.61972
   lastRead:
     h40079     1512773098.75712
     h40083     1512687323.82429
     h40093     1512687269.79689
     h40094     1512771597.51639
     h40101     1512685219.2463
Attributes:
   dev-h-defPoll 1
   dev-h-defShowGet 1
   enableControlSet 1
   maxTimeoutsToReconnect 3
   obj-h40079-expr $val / 10
   obj-h40079-reading AC-Volt-A
   obj-h40094-poll 1
   obj-h40094-reading AC-Energy-All


Sehen kann man immer einen Disconnect:

Code Auswählen Erweitern


2017.12.09 08:45:31 3: 192.168.178.36:502 disconnected, waiting to reappear (KeSolarEdge)
2017.12.09 08:45:31 3: 192.168.178.36:502 reappeared (KeSolarEdge)


[Marie]

Moin,


Ja dieses Verhalten beobachte ich bei mir im Wohnmobil auch...etwas Abhilfe schafft ein

Code Auswählen Erweitern



DEV-h-defpoll 1





Oder aber den poll des readings auf 1 setzen. Aber so richtig....
Ich denke mal da haben wir ein Verständnis Problem....oder einen bug, keine Ahnung


Gruß


Marie

[BenMarloe]

Hallo Marie,
danke für Deine Antwort. Ich fühle mich schon etwas besser.
Allerdings nehme ich an Du meintest

Code Auswählen Erweitern


   dev-h-defPoll 1

da Perl ja case sensitive ist.
bei

Code Auswählen Erweitern

   DEV-h-defpoll 1

bekomme ich nur wilde Fehlermeldungen.
Aber vielleicht liest das ja jemand der uns helfen kann.
In der Zwischenzeit habe ich das pollen sein lassen und ein at Object geschrieben, dass mit "get" die Daten ausliest. Geht zwar, ist aber wohl kaum im Sinne des Erfinders.

[StefanStrobel]

Klingt für mich eher nach Timing-Problemen.
Wenn Ihr einen Log-Auszug bei verbose 5 postet
(für sowohl das logische als auch das physische Device), kann ich vielleicht genaueres erkennen.

Gruss
   Stefan

[BenMarloe]

Hallo Stefan,

ich weiß nicht was ein logisches oder ein physisches Device ist, freue mich aber sehr über das Angebot.
Ich habe mein ModbusAttr-Device also auf Verbose 5 gesetzt und das ist List und das Logfile. Wenn ich weiteres zur Aufhellung beitragen kann, gerne.
List des Devices:

Code Auswählen Erweitern


Internals:
   BUSY       0
   DEF        1 30 192.168.178.36 TCP
   DEST       192.168.178.36:502
   DeviceName 192.168.178.36:502
   FD         5
   INTERVAL   900
   IODev      KeSolarEdge
   LASTOPEN   1513176936.40306
   MODBUSID   1
   ModuleVersion 3.7.0 - 20.8.2017
   NAME       KeSolarEdge
   NOTIFYDEV  global
   NR         366
   NTFY_ORDER 50-KeSolarEdge
   PARTIAL
   PROTOCOL   TCP
   REQUESTHEX 00d10000000601039c8f0001
   STATE      opened
   TRIGGERTIME 1513177931.46057
   TRIGGERTIME_FMT 2017-12-13 16:12:11
   TYPE       ModbusAttr
   devioLoglevel 3
   nextOpenDelay 60
   QUEUE:
   READINGS:
     2017-12-13 15:57:12   AC-Energy       66152
     2017-12-13 15:57:12   AC-Energy-Scale 0
     2017-12-13 15:57:11   AC-Power        27000
     2017-12-13 15:57:12   AC-Power-Scale  -2
     2017-12-13 15:57:12   AC-Volt-A       226.8
     2017-12-13 15:57:11   AC-Volt-Scale   -1
     2017-12-13 15:57:11   DC-Current      4070
     2017-12-13 15:57:11   DC-Current-Scale -5
     2017-12-13 15:57:12   DC-Power        3045
     2017-12-13 15:57:12   DC-Power-Scale  -3
     2017-12-13 15:57:12   DC-Voltage      7480
     2017-12-13 15:57:11   DC-Voltage-Scale -1
     2017-12-13 15:57:12   HeatSinkTemp    3093
     2017-12-13 15:57:12   Status          4
     2017-12-13 15:55:36   state           opened
   defptr:
     1:
   gotReadings:
     AC-Volt-A  226.8
   helper:
     buffer
     lid        1
     lrecv      1513177032.97203
     lsend      1513177032.96865
   lastRead:
     h40079     1513177032.97623
     h40082     1513177031.51114
     h40083     1513177031.9495
     h40084     1513177032.27729
     h40093     1513177032.38921
     h40094     1512894752.78521
     h40095     1513177032.16916
     h40096     1513177031.73266
     h40097     1513177031.62077
     h40098     1513177032.8675
     h40099     1513177031.8428
     h40100     1513177032.7219
     h40101     1513177032.50292
     h40103     1513177032.61126
     h40107     1513177032.0621
     h40108     1512911704.47342
Attributes:
   dev-h-defPoll 1
   dev-h-defShowGet 1
   enableControlSet 1
   maxTimeoutsToReconnect 3
   obj-h40079-expr $val / 10
   obj-h40079-reading AC-Volt-A
   obj-h40082-reading AC-Volt-Scale
   obj-h40082-unpack s>
   obj-h40083-reading AC-Power
   obj-h40084-reading AC-Power-Scale
   obj-h40084-unpack s>
   obj-h40093-len 2
   obj-h40093-reading AC-Energy
   obj-h40093-unpack l>
   obj-h40095-reading AC-Energy-Scale
   obj-h40096-reading DC-Current
   obj-h40097-reading DC-Current-Scale
   obj-h40097-unpack s>
   obj-h40098-reading DC-Voltage
   obj-h40099-reading DC-Voltage-Scale
   obj-h40099-unpack s>
   obj-h40100-reading DC-Power
   obj-h40101-reading DC-Power-Scale
   obj-h40101-unpack s>
   obj-h40103-reading HeatSinkTemp
   obj-h40107-reading Status
   room       Keller
   userattr   dev-h-defPoll dev-h-defShowGet enableControlSet maxTimeoutsToReconnect obj-h40079-expr obj-h40079-reading obj-h40082-reading obj-h40082-unpack obj-h40083-reading obj-h40084-expr obj-h40084-reading obj-h40084-unpack obj-h40093-len obj-h40093-reading obj-h40093-unpack obj-h40094-poll obj-h40094-reading obj-h40095-reading obj-h40096-reading obj-h40097-reading obj-h40097-unpack obj-h40098-reading obj-h40099-reading obj-h40099-unpack obj-h40100-reading obj-h40101-reading obj-h40101-unpack obj-h40103-reading obj-h40107-reading obj-h40108-reading silentReconnect verbose
   verbose    5


Auszug des Logfiles des Devices:

Code Auswählen Erweitern


2017-12-13_15:53:33 KeSolarEdge DISCONNECTED
2017-12-13_15:53:33 KeSolarEdge CONNECTED
2017-12-13_15:53:35 KeSolarEdge AC-Energy: 66150
2017-12-13_15:53:35 KeSolarEdge AC-Energy-Scale: 0
2017-12-13_15:53:36 KeSolarEdge AC-Power: 24000
2017-12-13_15:53:36 KeSolarEdge AC-Power-Scale: -3
2017-12-13_15:53:36 KeSolarEdge DC-Power: 24365
2017-12-13_15:53:36 KeSolarEdge DC-Power-Scale: -3
2017-12-13_15:53:36 KeSolarEdge DC-Voltage: 7482
2017-12-13_15:53:36 KeSolarEdge DC-Voltage-Scale: -1
2017-12-13_15:53:36 KeSolarEdge Status: 4
2017-12-13_15:55:36 KeSolarEdge DISCONNECTED
2017-12-13_15:55:36 KeSolarEdge CONNECTED
2017-12-13_15:56:36 KeSolarEdge AC-Energy: 66151
2017-12-13_15:56:36 KeSolarEdge AC-Energy-Scale: 0
2017-12-13_15:56:36 KeSolarEdge AC-Power: 3100
2017-12-13_15:56:36 KeSolarEdge AC-Power-Scale: -2
2017-12-13_15:56:36 KeSolarEdge DC-Power: 28426
2017-12-13_15:56:36 KeSolarEdge DC-Power-Scale: -3
2017-12-13_15:56:36 KeSolarEdge DC-Voltage: 7480
2017-12-13_15:56:36 KeSolarEdge DC-Voltage-Scale: -1
2017-12-13_15:56:36 KeSolarEdge Status: 4
2017-12-13_15:57:11 KeSolarEdge AC-Volt-Scale: -1
2017-12-13_15:57:11 KeSolarEdge DC-Current-Scale: -5
2017-12-13_15:57:11 KeSolarEdge DC-Current: 4070
2017-12-13_15:57:11 KeSolarEdge DC-Voltage-Scale: -1
2017-12-13_15:57:11 KeSolarEdge AC-Power: 27000
2017-12-13_15:57:12 KeSolarEdge Status: 4
2017-12-13_15:57:12 KeSolarEdge AC-Energy-Scale: 0
2017-12-13_15:57:12 KeSolarEdge AC-Power-Scale: -2
2017-12-13_15:57:12 KeSolarEdge AC-Energy: 66152
2017-12-13_15:57:12 KeSolarEdge DC-Power-Scale: -3
2017-12-13_15:57:12 KeSolarEdge HeatSinkTemp: 3093
2017-12-13_15:57:12 KeSolarEdge DC-Power: 3045
2017-12-13_15:57:12 KeSolarEdge DC-Voltage: 7480
2017-12-13_15:57:12 KeSolarEdge AC-Volt-A: 226.8
2017-12-13_15:58:36 KeSolarEdge AC-Energy: 66152
2017-12-13_15:58:37 KeSolarEdge AC-Energy-Scale: 0
2017-12-13_15:58:37 KeSolarEdge AC-Power: 22000
2017-12-13_15:58:37 KeSolarEdge AC-Power-Scale: -3
2017-12-13_15:58:37 KeSolarEdge DC-Power: 22335
2017-12-13_15:58:37 KeSolarEdge DC-Power-Scale: -3
2017-12-13_15:58:37 KeSolarEdge DC-Voltage: 7478
2017-12-13_15:58:37 KeSolarEdge DC-Voltage-Scale: -1
2017-12-13_15:58:37 KeSolarEdge Status: 4
2017-12-13_16:00:37 KeSolarEdge DISCONNECTED
2017-12-13_16:00:37 KeSolarEdge CONNECTED
2017-12-13_16:00:39 KeSolarEdge AC-Energy: 66153
2017-12-13_16:00:39 KeSolarEdge AC-Energy-Scale: 0
2017-12-13_16:00:39 KeSolarEdge AC-Power: 14000
2017-12-13_16:00:39 KeSolarEdge AC-Power-Scale: -3
2017-12-13_16:00:39 KeSolarEdge DC-Power: 14213
2017-12-13_16:00:39 KeSolarEdge DC-Power-Scale: -3
2017-12-13_16:00:39 KeSolarEdge DC-Voltage: 7483
2017-12-13_16:00:39 KeSolarEdge DC-Voltage-Scale: -1
2017-12-13_16:00:39 KeSolarEdge Status: 4


..ist in diesem Zusammenhang eventuell noch interessant, dass ich ähnliche Probleme mit HMLAN habe (Homematic über Ethernet)?

[StefanStrobel]

Hallo BenMarloe,

bei Modbus RTU wäre Dein ModbusAttr-Device das logische Device und Dein Modbus-Device für den seriellen Adapter das physische. Für beide sollte verbose 5 gesetzt werden wenn man Fehler sucht.
Bei Modbus TCP ist beides in einem Device.

Mit Logfile meinte ich das Fhem-Logfile. Dort landen bei verbose 5 die Debug-Meldungen.

dev-h-defPoll 1 ist nötig damit das Modul die Werte auch abfragt. Andernfalls werden nur die Werte automatisch abgefragt, die explizit ein -poll Attribut gesetzt haben. Das ist kein Bug sondern so gewollt:

obj-[cdih][1-9][0-9]*-poll
   if set to 1 then this obeject is included in the cyclic update request as specified in the define command. If not set, then the object can manually be requested with a get command,
   but it is not automatically updated each interval. Note that this setting can also be specified as default for all objects with the dev- atributes described later.

bzw.

dev-([cdih]-)*defPoll
    if set to 1 then all objects of this type will be included in the cyclic update by default.

Gruss
   Stefan

[BenMarloe]

Hallo Stefan,

vielen Dank für die schnelle Antwort.
Hier das fhem-2017-12.log vom gleichen Zeitraum. Ein physikalisches Device habe dann also nicht, da ich ja TCP verwende.

Code Auswählen Erweitern


2017.12.13 15:53:16 1: prg_brenner temp: temperature: 55.7
2017.12.13 15:53:33 3: 192.168.178.36:502 disconnected, waiting to reappear (KeSolarEdge)
2017.12.13 15:53:33 5: HttpUtils url=http://192.168.178.36:502/
2017.12.13 15:53:33 3: 192.168.178.36:502 reappeared (KeSolarEdge)
2017.12.13 15:53:34 5: KeSolarEdge: Get: Called with AC-Energy (h40093)
2017.12.13 15:53:34 4: KeSolarEdge: Send called with h40093, objLen 2 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:34 4: KeSolarEdge: Send adds fc 3 to 1, tid 196, for h40093 (AC-Energy), reqLen 2 at beginning of queue for immediate sending
2017.12.13 15:53:34 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -119.937731981277
2017.12.13 15:53:34 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -119.940572023392
2017.12.13 15:53:34 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:34 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 196 for AC-Energy (h40093), len 2, device KeSolarEdge (TCP), pdu 039c9d0002, V 3.7.0 - 20.8.2017
2017.12.13 15:53:34 5: SW: 00c40000000601039c9d0002
2017.12.13 15:53:34 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99962902069092 requested reading is AC-Energy
2017.12.13 15:53:35 5: KeSolarEdge: ReadAnswer got: 00c40000000701030400010266
2017.12.13 15:53:35 5: KeSolarEdge: ParseFrames got: 00c40000000701030400010266
2017.12.13 15:53:35 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 196, values 00010266HeaderLen 4, ActualLen 4, request was for h40093 (AC-Energy), len 2 for module KeSolarEdge
2017.12.13 15:53:35 5: KeSolarEdge: ParseObj called with 00010266 and start 40093, op read
2017.12.13 15:53:35 5: KeSolarEdge: ParseObj ObjInfo for h40093: reading=AC-Energy, unpack=l>, expr=, format=, map=
2017.12.13 15:53:35 5: KeSolarEdge: ParseObj unpacked 00010266 with l> to hex 3636313530 (66150)
2017.12.13 15:53:35 4: KeSolarEdge: ParseObj for AC-Energy assigns 66150
2017.12.13 15:53:35 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:35 5: KeSolarEdge: ReadAnswer done, reading is AC-Energy, value: 66150
2017.12.13 15:53:35 3: get KeSolarEdge AC-Energy : 66150
2017.12.13 15:53:35 5: KeSolarEdge: Get: Called with AC-Energy-Scale (h40095)
2017.12.13 15:53:35 4: KeSolarEdge: Send called with h40095, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:35 4: KeSolarEdge: Send adds fc 3 to 1, tid 216, for h40095 (AC-Energy-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:35 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0676119327545166
2017.12.13 15:53:35 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0676119327545166 forced
2017.12.13 15:53:35 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -1.83849501609802
2017.12.13 15:53:35 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:35 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 216 for AC-Energy-Scale (h40095), len 1, device KeSolarEdge (TCP), pdu 039c9f0001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:35 5: SW: 00d80000000601039c9f0001
2017.12.13 15:53:35 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99963212013245 requested reading is AC-Energy-Scale
2017.12.13 15:53:35 5: KeSolarEdge: ReadAnswer got: 00d8000000050103020000
2017.12.13 15:53:35 5: KeSolarEdge: ParseFrames got: 00d8000000050103020000
2017.12.13 15:53:35 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 216, values 0000HeaderLen 2, ActualLen 2, request was for h40095 (AC-Energy-Scale), len 1 for module KeSolarEdge
2017.12.13 15:53:35 5: KeSolarEdge: ParseObj called with 0000 and start 40095, op read
2017.12.13 15:53:35 5: KeSolarEdge: ParseObj ObjInfo for h40095: reading=AC-Energy-Scale, unpack=n, expr=, format=, map=
2017.12.13 15:53:35 5: KeSolarEdge: ParseObj unpacked 0000 with n to hex 30 (0)
2017.12.13 15:53:35 4: KeSolarEdge: ParseObj for AC-Energy-Scale assigns 0
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Energy-Scale, value: 0
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with AC-Power (h40083)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40083, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 231, for h40083 (AC-Power), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0445868968963623
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0445868968963623 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0293118953704834
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0293118953704834 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 231 for AC-Power (h40083), len 1, device KeSolarEdge (TCP), pdu 039c930001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 00e70000000601039c930001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99950289726257 requested reading is AC-Power
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 00e7000000050103025dc0
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 00e7000000050103025dc0
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 231, values 5dc0HeaderLen 2, ActualLen 2, request was for h40083 (AC-Power), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with 5dc0 and start 40083, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40083: reading=AC-Power, unpack=n, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked 5dc0 with n to hex 3234303030 (24000)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for AC-Power assigns 24000
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Power, value: 24000
2017.12.13 15:53:36 3: get KeSolarEdge AC-Power : 24000
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with AC-Power-Scale (h40084)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40084, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 168, for h40084 (AC-Power-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0678648948669434
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0678648948669434 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0635199546813965
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0635199546813965 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 168 for AC-Power-Scale (h40084), len 1, device KeSolarEdge (TCP), pdu 039c940001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 00a80000000601039c940001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99963593482971 requested reading is AC-Power-Scale
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 00a800000005010302fffd
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 00a800000005010302fffd
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 168, values fffdHeaderLen 2, ActualLen 2, request was for h40084 (AC-Power-Scale), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with fffd and start 40084, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40084: reading=AC-Power-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked fffd with s> to hex 2d33 (-3)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for AC-Power-Scale assigns -3
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Power-Scale, value: -3
2017.12.13 15:53:36 3: get KeSolarEdge AC-Power-Scale : -3
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Power (h40100)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40100, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 124, for h40100 (DC-Power), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0471358299255371
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0471358299255371 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0420279502868652
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0420279502868652 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 124 for DC-Power (h40100), len 1, device KeSolarEdge (TCP), pdu 039ca40001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 007c0000000601039ca40001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99956893920898 requested reading is DC-Power
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 007c000000050103025f2d
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 007c000000050103025f2d
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 124, values 5f2dHeaderLen 2, ActualLen 2, request was for h40100 (DC-Power), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with 5f2d and start 40100, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40100: reading=DC-Power, unpack=n, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked 5f2d with n to hex 3234333635 (24365)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for DC-Power assigns 24365
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Power, value: 24365
2017.12.13 15:53:36 3: get KeSolarEdge DC-Power : 24365
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Power-Scale (h40101)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40101, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 212, for h40101 (DC-Power-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0678768157958984
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0678768157958984 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0573928356170654
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0573928356170654 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 212 for DC-Power-Scale (h40101), len 1, device KeSolarEdge (TCP), pdu 039ca50001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 00d40000000601039ca50001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99959897994995 requested reading is DC-Power-Scale
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 00d400000005010302fffd
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 00d400000005010302fffd
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 212, values fffdHeaderLen 2, ActualLen 2, request was for h40101 (DC-Power-Scale), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with fffd and start 40101, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40101: reading=DC-Power-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked fffd with s> to hex 2d33 (-3)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for DC-Power-Scale assigns -3
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Power-Scale, value: -3
2017.12.13 15:53:36 3: get KeSolarEdge DC-Power-Scale : -3
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Voltage (h40098)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40098, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 157, for h40098 (DC-Voltage), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0467789173126221
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0467789173126221 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0305407047271729
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0305407047271729 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 157 for DC-Voltage (h40098), len 1, device KeSolarEdge (TCP), pdu 039ca20001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 009d0000000601039ca20001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99949216842651 requested reading is DC-Voltage
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 009d000000050103021d3a
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 009d000000050103021d3a
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 157, values 1d3aHeaderLen 2, ActualLen 2, request was for h40098 (DC-Voltage), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with 1d3a and start 40098, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40098: reading=DC-Voltage, unpack=n, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked 1d3a with n to hex 37343832 (7482)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for DC-Voltage assigns 7482
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Voltage, value: 7482
2017.12.13 15:53:36 3: get KeSolarEdge DC-Voltage : 7482
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Voltage-Scale (h40099)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40099, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 15, for h40099 (DC-Voltage-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0675590038299561
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0675590038299561 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0603690147399902
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0603690147399902 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 15 for DC-Voltage-Scale (h40099), len 1, device KeSolarEdge (TCP), pdu 039ca30001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 000f0000000601039ca30001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99963402748108 requested reading is DC-Voltage-Scale
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 000f00000005010302ffff
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 000f00000005010302ffff
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 15, values ffffHeaderLen 2, ActualLen 2, request was for h40099 (DC-Voltage-Scale), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with ffff and start 40099, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40099: reading=DC-Voltage-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked ffff with s> to hex 2d31 (-1)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for DC-Voltage-Scale assigns -1
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Voltage-Scale, value: -1
2017.12.13 15:53:36 3: get KeSolarEdge DC-Voltage-Scale : -1
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with Status (h40107)
2017.12.13 15:53:36 4: KeSolarEdge: Send called with h40107, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:53:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 36, for h40107 (Status), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0472097396850586
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0472097396850586 forced
2017.12.13 15:53:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0430431365966797
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0430431365966797 forced
2017.12.13 15:53:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:53:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 36 for Status (h40107), len 1, device KeSolarEdge (TCP), pdu 039cab0001, V 3.7.0 - 20.8.2017
2017.12.13 15:53:36 5: SW: 00240000000601039cab0001
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99961113929749 requested reading is Status
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer got: 0024000000050103020004
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got: 0024000000050103020004
2017.12.13 15:53:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 36, values 0004HeaderLen 2, ActualLen 2, request was for h40107 (Status), len 1 for module KeSolarEdge
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj called with 0004 and start 40107, op read
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj ObjInfo for h40107: reading=Status, unpack=n, expr=, format=, map=
2017.12.13 15:53:36 5: KeSolarEdge: ParseObj unpacked 0004 with n to hex 34 (4)
2017.12.13 15:53:36 4: KeSolarEdge: ParseObj for Status assigns 4
2017.12.13 15:53:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:53:36 5: KeSolarEdge: ReadAnswer done, reading is Status, value: 4
2017.12.13 15:53:36 3: get KeSolarEdge Status : 4
2017.12.13 15:55:36 3: 192.168.178.36:502 disconnected, waiting to reappear (KeSolarEdge)
2017.12.13 15:55:36 5: HttpUtils url=http://192.168.178.36:502/
2017.12.13 15:55:36 1: disconnected
2017.12.13 15:55:36 3: 192.168.178.36:502 reappeared (KeSolarEdge)
2017.12.13 15:56:02 1: prg_brenner temp: temperature: 54.6
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with AC-Energy (h40093)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40093, objLen 2 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 218, for h40093 (AC-Energy), reqLen 2 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -179.941762208939
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -179.949740886688
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 218 for AC-Energy (h40093), len 2, device KeSolarEdge (TCP), pdu 039c9d0002, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 00da0000000601039c9d0002
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99959397315979 requested reading is AC-Energy
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 00da0000000701030400010267
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 00da0000000701030400010267
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 218, values 00010267HeaderLen 4, ActualLen 4, request was for h40093 (AC-Energy), len 2 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with 00010267 and start 40093, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40093: reading=AC-Energy, unpack=l>, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked 00010267 with l> to hex 3636313531 (66151)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for AC-Energy assigns 66151
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Energy, value: 66151
2017.12.13 15:56:36 3: get KeSolarEdge AC-Energy : 66151
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with AC-Energy-Scale (h40095)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40095, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 155, for h40095 (AC-Energy-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.066267728805542
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.066267728805542 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0619838237762451
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0619838237762451 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 155 for AC-Energy-Scale (h40095), len 1, device KeSolarEdge (TCP), pdu 039c9f0001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 009b0000000601039c9f0001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99952793121338 requested reading is AC-Energy-Scale
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 009b000000050103020000
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 009b000000050103020000
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 155, values 0000HeaderLen 2, ActualLen 2, request was for h40095 (AC-Energy-Scale), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with 0000 and start 40095, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40095: reading=AC-Energy-Scale, unpack=n, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked 0000 with n to hex 30 (0)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for AC-Energy-Scale assigns 0
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Energy-Scale, value: 0
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with AC-Power (h40083)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40083, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 41, for h40083 (AC-Power), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.045637845993042
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.045637845993042 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0423038005828857
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0423038005828857 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 41 for AC-Power (h40083), len 1, device KeSolarEdge (TCP), pdu 039c930001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 00290000000601039c930001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99960780143738 requested reading is AC-Power
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 0029000000050103020c1c
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 0029000000050103020c1c
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 41, values 0c1cHeaderLen 2, ActualLen 2, request was for h40083 (AC-Power), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with 0c1c and start 40083, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40083: reading=AC-Power, unpack=n, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked 0c1c with n to hex 33313030 (3100)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for AC-Power assigns 3100
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Power, value: 3100
2017.12.13 15:56:36 3: get KeSolarEdge AC-Power : 3100
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with AC-Power-Scale (h40084)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40084, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 240, for h40084 (AC-Power-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0674428939819336
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0674428939819336 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.06036376953125
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.06036376953125 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 240 for AC-Power-Scale (h40084), len 1, device KeSolarEdge (TCP), pdu 039c940001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 00f00000000601039c940001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.9996018409729 requested reading is AC-Power-Scale
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 00f000000005010302fffe
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 00f000000005010302fffe
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 240, values fffeHeaderLen 2, ActualLen 2, request was for h40084 (AC-Power-Scale), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with fffe and start 40084, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40084: reading=AC-Power-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked fffe with s> to hex 2d32 (-2)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for AC-Power-Scale assigns -2
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is AC-Power-Scale, value: -2
2017.12.13 15:56:36 3: get KeSolarEdge AC-Power-Scale : -2
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with DC-Power (h40100)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40100, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 24, for h40100 (DC-Power), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0473949909210205
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0473949909210205 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0428109169006348
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0428109169006348 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 24 for DC-Power (h40100), len 1, device KeSolarEdge (TCP), pdu 039ca40001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 00180000000601039ca40001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99958896636963 requested reading is DC-Power
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 0018000000050103026f0a
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 0018000000050103026f0a
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 24, values 6f0aHeaderLen 2, ActualLen 2, request was for h40100 (DC-Power), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with 6f0a and start 40100, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40100: reading=DC-Power, unpack=n, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked 6f0a with n to hex 3238343236 (28426)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for DC-Power assigns 28426
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Power, value: 28426
2017.12.13 15:56:36 3: get KeSolarEdge DC-Power : 28426
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with DC-Power-Scale (h40101)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40101, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 109, for h40101 (DC-Power-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0524137020111084
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0524137020111084 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.00745582580566406
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.00745582580566406 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 109 for DC-Power-Scale (h40101), len 1, device KeSolarEdge (TCP), pdu 039ca50001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 006d0000000601039ca50001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99940896034241 requested reading is DC-Power-Scale
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 006d00000005010302fffd
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 006d00000005010302fffd
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 109, values fffdHeaderLen 2, ActualLen 2, request was for h40101 (DC-Power-Scale), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with fffd and start 40101, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40101: reading=DC-Power-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked fffd with s> to hex 2d33 (-3)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for DC-Power-Scale assigns -3
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Power-Scale, value: -3
2017.12.13 15:56:36 3: get KeSolarEdge DC-Power-Scale : -3
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with DC-Voltage (h40098)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40098, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 71, for h40098 (DC-Voltage), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0320219993591309
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0320219993591309 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0281867980957031
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0281867980957031 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 71 for DC-Voltage (h40098), len 1, device KeSolarEdge (TCP), pdu 039ca20001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 00470000000601039ca20001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99961686134338 requested reading is DC-Voltage
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 0047000000050103021d38
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 0047000000050103021d38
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 71, values 1d38HeaderLen 2, ActualLen 2, request was for h40098 (DC-Voltage), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with 1d38 and start 40098, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40098: reading=DC-Voltage, unpack=n, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked 1d38 with n to hex 37343830 (7480)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for DC-Voltage assigns 7480
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Voltage, value: 7480
2017.12.13 15:56:36 3: get KeSolarEdge DC-Voltage : 7480
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with DC-Voltage-Scale (h40099)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40099, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 211, for h40099 (DC-Voltage-Scale), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0678548812866211
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0678548812866211 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0644629001617432
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0644629001617432 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 211 for DC-Voltage-Scale (h40099), len 1, device KeSolarEdge (TCP), pdu 039ca30001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 00d30000000601039ca30001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99955105781555 requested reading is DC-Voltage-Scale
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 00d300000005010302ffff
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 00d300000005010302ffff
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 211, values ffffHeaderLen 2, ActualLen 2, request was for h40099 (DC-Voltage-Scale), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with ffff and start 40099, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40099: reading=DC-Voltage-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked ffff with s> to hex 2d31 (-1)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for DC-Voltage-Scale assigns -1
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is DC-Voltage-Scale, value: -1
2017.12.13 15:56:36 3: get KeSolarEdge DC-Voltage-Scale : -1
2017.12.13 15:56:36 5: KeSolarEdge: Get: Called with Status (h40107)
2017.12.13 15:56:36 4: KeSolarEdge: Send called with h40107, objLen 1 / reqLen - to id 1, op read, qlen 0
2017.12.13 15:56:36 4: KeSolarEdge: Send adds fc 3 to 1, tid 107, for h40107 (Status), reqLen 1 at beginning of queue for immediate sending
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0471670627593994
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, sleep 0.0471670627593994 forced
2017.12.13 15:56:36 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest 0.0390989780426025
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue / CheckDelay sendDelay (0.1) for KeSolarEdge not over, sleep 0.0390989780426025 forced
2017.12.13 15:56:36 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:56:36 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 107 for Status (h40107), len 1, device KeSolarEdge (TCP), pdu 039cab0001, V 3.7.0 - 20.8.2017
2017.12.13 15:56:36 5: SW: 006b0000000601039cab0001
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer called and remaining timeout is 1.99943900108337 requested reading is Status
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer got: 006b000000050103020004
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got: 006b000000050103020004
2017.12.13 15:56:36 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 107, values 0004HeaderLen 2, ActualLen 2, request was for h40107 (Status), len 1 for module KeSolarEdge
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj called with 0004 and start 40107, op read
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj ObjInfo for h40107: reading=Status, unpack=n, expr=, format=, map=
2017.12.13 15:56:36 5: KeSolarEdge: ParseObj unpacked 0004 with n to hex 34 (4)
2017.12.13 15:56:36 4: KeSolarEdge: ParseObj for Status assigns 4
2017.12.13 15:56:36 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:56:36 5: KeSolarEdge: ReadAnswer done, reading is Status, value: 4
2017.12.13 15:56:36 3: get KeSolarEdge Status : 4
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate called
2017.12.13 15:57:11 4: KeSolarEdge: update timer modified: will call GetUpdate in 900.0 seconds at 2017-12-13 16:12:11 - Interval 900
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate objects from attributes: h40103 h40098 h40101 h40084 h40096 h40107 h40095 h40097 h40099 h40083 h40100 h40082 h40079 h40093
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate full object list: h40079 h40082 h40083 h40084 h40093 h40095 h40096 h40097 h40098 h40099 h40100 h40101 h40103 h40107
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40079 => AC-Volt-A, poll = 1, last = 1513176132.92639
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request AC-Volt-A
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40082 => AC-Volt-Scale, poll = 1, last = 1513176131.50495
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request AC-Volt-Scale
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40083 => AC-Power, poll = 1, last = 1513176996.52662
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request AC-Power
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40084 => AC-Power-Scale, poll = 1, last = 1513176996.56613
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request AC-Power-Scale
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40093 => AC-Energy, poll = 1, last = 1513176996.42373
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request AC-Energy
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40095 => AC-Energy-Scale, poll = 1, last = 1513176996.46258
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request AC-Energy-Scale
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40096 => DC-Current, poll = 1, last = 1513176131.71948
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request DC-Current
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40097 => DC-Current-Scale, poll = 1, last = 1513176131.61059
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request DC-Current-Scale
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40098 => DC-Voltage, poll = 1, last = 1513176996.79503
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request DC-Voltage
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40099 => DC-Voltage-Scale, poll = 1, last = 1513176996.83781
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request DC-Voltage-Scale
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40100 => DC-Power, poll = 1, last = 1513176996.66803
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request DC-Power
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40101 => DC-Power-Scale, poll = 1, last = 1513176996.72279
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request DC-Power-Scale
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40103 => HeatSinkTemp, poll = 1, last = 1513176132.59902
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request HeatSinkTemp
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate check h40107 => Status, poll = 1, last = 1513176996.89762
2017.12.13 15:57:11 4: KeSolarEdge: GetUpdate will request Status
2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate tries to combine read commands
2017.12.13 15:57:11 5: KeSolarEdge: No Combine AC-Volt-A / h40079 with AC-Volt-Scale / h40082, span 4 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine AC-Volt-Scale / h40082 with AC-Power / h40083, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine AC-Power / h40083 with AC-Power-Scale / h40084, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine AC-Power-Scale / h40084 with AC-Energy / h40093, span 11 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine AC-Energy / h40093 with AC-Energy-Scale / h40095, span 3 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine AC-Energy-Scale / h40095 with DC-Current / h40096, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine DC-Current / h40096 with DC-Current-Scale / h40097, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine DC-Current-Scale / h40097 with DC-Voltage / h40098, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine DC-Voltage / h40098 with DC-Voltage-Scale / h40099, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine DC-Voltage-Scale / h40099 with DC-Power / h40100, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine DC-Power / h40100 with DC-Power-Scale / h40101, span 2 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine DC-Power-Scale / h40101 with HeatSinkTemp / h40103, span 3 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: No Combine HeatSinkTemp / h40103 with Status / h40107, span 5 > max 1
2017.12.13 15:57:11 5: KeSolarEdge: don't sort objList before sending requests
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40082, objLen 1 / reqLen 1 to id 1, op read, qlen 0
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 199, for h40082 (AC-Volt-Scale), reqLen 1
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -34.4885032176971
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -34.4925169944763
2017.12.13 15:57:11 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 199 for AC-Volt-Scale (h40082), len 1, device KeSolarEdge (TCP), pdu 039c920001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:11 5: SW: 00c70000000601039c920001
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40097, objLen 1 / reqLen 1 to id 1, op read, qlen 0
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 9, for h40097 (DC-Current-Scale), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40096, objLen 1 / reqLen 1 to id 1, op read, qlen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 226, for h40096 (DC-Current), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40099, objLen 1 / reqLen 1 to id 1, op read, qlen 2
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 4, for h40099 (DC-Voltage-Scale), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40083, objLen 1 / reqLen 1 to id 1, op read, qlen 3
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 192, for h40083 (AC-Power), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40107, objLen 1 / reqLen 1 to id 1, op read, qlen 4
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 212, for h40107 (Status), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40095, objLen 1 / reqLen 1 to id 1, op read, qlen 5
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 45, for h40095 (AC-Energy-Scale), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40084, objLen 1 / reqLen 1 to id 1, op read, qlen 6
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 125, for h40084 (AC-Power-Scale), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40093, objLen 2 / reqLen 2 to id 1, op read, qlen 7
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 121, for h40093 (AC-Energy), reqLen 2
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40101, objLen 1 / reqLen 1 to id 1, op read, qlen 8
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 92, for h40101 (DC-Power-Scale), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40103, objLen 1 / reqLen 1 to id 1, op read, qlen 9
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 189, for h40103 (HeatSinkTemp), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40100, objLen 1 / reqLen 1 to id 1, op read, qlen 10
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 114, for h40100 (DC-Power), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40098, objLen 1 / reqLen 1 to id 1, op read, qlen 11
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 81, for h40098 (DC-Voltage), reqLen 1
2017.12.13 15:57:11 4: KeSolarEdge: Send called with h40079, objLen 1 / reqLen 1 to id 1, op read, qlen 12
2017.12.13 15:57:11 4: KeSolarEdge: Send queues fc 3 to 1, tid 209, for h40079 (AC-Volt-A), reqLen 1
2017.12.13 15:57:11 5: KeSolarEdge: raw read: 00c700000005010302ffff
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got: 00c700000005010302ffff
2017.12.13 15:57:11 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 199, values ffffHeaderLen 2, ActualLen 2, request was for h40082 (AC-Volt-Scale), len 1 for module KeSolarEdge
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj called with ffff and start 40082, op read
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj ObjInfo for h40082: reading=AC-Volt-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj unpacked ffff with s> to hex 2d31 (-1)
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for AC-Volt-Scale assigns -1
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0708389282226562
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0708389282226562
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00173425674438477
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.0245509147644043
2017.12.13 15:57:11 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 9 for DC-Current-Scale (h40097), len 1, device KeSolarEdge (TCP), pdu 039ca10001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:11 5: SW: 00090000000601039ca10001
2017.12.13 15:57:11 5: KeSolarEdge: raw read: 000900000005010302fffb
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got: 000900000005010302fffb
2017.12.13 15:57:11 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 9, values fffbHeaderLen 2, ActualLen 2, request was for h40097 (DC-Current-Scale), len 1 for module KeSolarEdge
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj called with fffb and start 40097, op read
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj ObjInfo for h40097: reading=DC-Current-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj unpacked fffb with s> to hex 2d35 (-5)
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for DC-Current-Scale assigns -5
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0707628726959229
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0707628726959229
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00237607955932617
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.00801301002502441
2017.12.13 15:57:11 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 226 for DC-Current (h40096), len 1, device KeSolarEdge (TCP), pdu 039ca00001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:11 5: SW: 00e20000000601039ca00001
2017.12.13 15:57:11 5: KeSolarEdge: raw read: 00e2000000050103020fe6
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got: 00e2000000050103020fe6
2017.12.13 15:57:11 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 226, values 0fe6HeaderLen 2, ActualLen 2, request was for h40096 (DC-Current), len 1 for module KeSolarEdge
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj called with 0fe6 and start 40096, op read
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj ObjInfo for h40096: reading=DC-Current, unpack=n, expr=, format=, map=
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj unpacked 0fe6 with n to hex 34303730 (4070)
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for DC-Current assigns 4070
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0562348365783691
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0562348365783691
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.0024261474609375
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.00670814514160156
2017.12.13 15:57:11 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 4 for DC-Voltage-Scale (h40099), len 1, device KeSolarEdge (TCP), pdu 039ca30001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:11 5: SW: 00040000000601039ca30001
2017.12.13 15:57:11 5: KeSolarEdge: raw read: 000400000005010302ffff
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got: 000400000005010302ffff
2017.12.13 15:57:11 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 4, values ffffHeaderLen 2, ActualLen 2, request was for h40099 (DC-Voltage-Scale), len 1 for module KeSolarEdge
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj called with ffff and start 40099, op read
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj ObjInfo for h40099: reading=DC-Voltage-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj unpacked ffff with s> to hex 2d31 (-1)
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for DC-Voltage-Scale assigns -1
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0618929862976074
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0618929862976074
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00171709060668945
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.0062410831451416
2017.12.13 15:57:11 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 192 for AC-Power (h40083), len 1, device KeSolarEdge (TCP), pdu 039c930001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:11 5: SW: 00c00000000601039c930001
2017.12.13 15:57:11 5: KeSolarEdge: raw read: 00c0000000050103026978
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got: 00c0000000050103026978
2017.12.13 15:57:11 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 192, values 6978HeaderLen 2, ActualLen 2, request was for h40083 (AC-Power), len 1 for module KeSolarEdge
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj called with 6978 and start 40083, op read
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj ObjInfo for h40083: reading=AC-Power, unpack=n, expr=, format=, map=
2017.12.13 15:57:11 5: KeSolarEdge: ParseObj unpacked 6978 with n to hex 3237303030 (27000)
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for AC-Power assigns 27000
2017.12.13 15:57:11 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:11 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0634677410125732
2017.12.13 15:57:11 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0634677410125732
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00258421897888184
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.0070033073425293
2017.12.13 15:57:12 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 212 for Status (h40107), len 1, device KeSolarEdge (TCP), pdu 039cab0001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:12 5: SW: 00d40000000601039cab0001
2017.12.13 15:57:12 5: KeSolarEdge: raw read: 00d4000000050103020004
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got: 00d4000000050103020004
2017.12.13 15:57:12 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 212, values 0004HeaderLen 2, ActualLen 2, request was for h40107 (Status), len 1 for module KeSolarEdge
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj called with 0004 and start 40107, op read
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj ObjInfo for h40107: reading=Status, unpack=n, expr=, format=, map=
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj unpacked 0004 with n to hex 34 (4)
2017.12.13 15:57:12 4: KeSolarEdge: ParseObj for Status assigns 4
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0562198162078857
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0562198162078857
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.0022730827331543
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.00712800025939941
2017.12.13 15:57:12 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 45 for AC-Energy-Scale (h40095), len 1, device KeSolarEdge (TCP), pdu 039c9f0001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:12 5: SW: 002d0000000601039c9f0001
2017.12.13 15:57:12 5: KeSolarEdge: raw read: 002d000000050103020000
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got: 002d000000050103020000
2017.12.13 15:57:12 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 45, values 0000HeaderLen 2, ActualLen 2, request was for h40095 (AC-Energy-Scale), len 1 for module KeSolarEdge
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj called with 0000 and start 40095, op read
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj ObjInfo for h40095: reading=AC-Energy-Scale, unpack=n, expr=, format=, map=
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj unpacked 0000 with n to hex 30 (0)
2017.12.13 15:57:12 4: KeSolarEdge: ParseObj for AC-Energy-Scale assigns 0
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0704658031463623
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0704658031463623
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00171422958374023
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.0050661563873291
2017.12.13 15:57:12 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 125 for AC-Power-Scale (h40084), len 1, device KeSolarEdge (TCP), pdu 039c940001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:12 5: SW: 007d0000000601039c940001
2017.12.13 15:57:12 5: KeSolarEdge: raw read: 007d00000005010302fffe
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got: 007d00000005010302fffe
2017.12.13 15:57:12 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 125, values fffeHeaderLen 2, ActualLen 2, request was for h40084 (AC-Power-Scale), len 1 for module KeSolarEdge
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj called with fffe and start 40084, op read
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj ObjInfo for h40084: reading=AC-Power-Scale, unpack=s>, expr=, format=, map=
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj unpacked fffe with s> to hex 2d32 (-2)
2017.12.13 15:57:12 4: KeSolarEdge: ParseObj for AC-Power-Scale assigns -2
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.0694448947906494
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.0694448947906494
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00162005424499512
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.00555109977722168
2017.12.13 15:57:12 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 121 for AC-Energy (h40093), len 2, device KeSolarEdge (TCP), pdu 039c9d0002, V 3.7.0 - 20.8.2017
2017.12.13 15:57:12 5: SW: 00790000000601039c9d0002
2017.12.13 15:57:12 5: KeSolarEdge: raw read: 00790000000701030400010268
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got: 00790000000701030400010268
2017.12.13 15:57:12 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 121, values 00010268HeaderLen 4, ActualLen 4, request was for h40093 (AC-Energy), len 2 for module KeSolarEdge
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj called with 00010268 and start 40093, op read
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj ObjInfo for h40093: reading=AC-Energy, unpack=l>, expr=, format=, map=
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj unpacked 00010268 with l> to hex 3636313532 (66152)
2017.12.13 15:57:12 4: KeSolarEdge: ParseObj for AC-Energy assigns 66152
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got 1 readings from ParseObj
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest 0.070537805557251
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue / CheckDelay commDelay (0.1) for KeSolarEdge not over, try again in 0.070537805557251
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check commDelay (0.1) for KeSolarEdge: rest -0.00167107582092285
2017.12.13 15:57:12 5: KeSolarEdge: handle queue check sendDelay (0.1) for KeSolarEdge: rest -0.00990128517150879
2017.12.13 15:57:12 5: KeSolarEdge: HandleSendQueue: finished delay checking, proceed with sending
2017.12.13 15:57:12 4: KeSolarEdge: HandleSendQueue sends fc 3 to id 1, tid 92 for DC-Power-Scale (h40101), len 1, device KeSolarEdge (TCP), pdu 039ca50001, V 3.7.0 - 20.8.2017
2017.12.13 15:57:12 5: SW: 005c0000000601039ca50001
2017.12.13 15:57:12 5: KeSolarEdge: raw read: 005c00000005010302fffd
2017.12.13 15:57:12 5: KeSolarEdge: ParseFrames got: 005c00000005010302fffd
2017.12.13 15:57:12 4: KeSolarEdge: ParseFrames got fcode 3 from 1, tid 92, values fffdHeaderLen 2, ActualLen 2, request was for h40101 (DC-Power-Scale), len 1 for module KeSolarEdge
2017.12.13 15:57:12 5: KeSolarEdge: ParseObj called with fffd and start 40101, op read


[BenMarloe]

.. aber in diesem Zusammenhang könnte es interessant sein, dass ich zeitweise auch solche Probleme mit HMLAN, dem Ethernet-Modul von Homematic habe.
Verlange ich einfach zuviel von meinem Raspi?

[StefanStrobel]

Hallo BenMarloe,

Beim durchsehen des Logs fallen mehrere Sachen auf:

1) Du fragst die Readings zusätzlich zum automatischen Zyklus manuell mit get ab:

2017.12.13 15:53:34 5: KeSolarEdge: Get: Called with AC-Energy (h40093)
2017.12.13 15:53:35 5: KeSolarEdge: Get: Called with AC-Energy-Scale (h40095)
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with AC-Power (h40083)
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with AC-Power-Scale (h40084)
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Power (h40100)
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Power-Scale (h40101)
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Voltage (h40098)
2017.12.13 15:53:36 5: KeSolarEdge: Get: Called with DC-Voltage-Scale (h40099)
...

es wird aber jedes mal auch ein Wert zurückgeliefert. Fehlende Readings oder Fehler beim Empfang kann ich nicht erkennen.

2) Dann kommt der automatische Abfrage-Zyklus (das beim Define angegebene Intervall).
Der passt aber nicht zu Deiner oben geposteten Konfiguration, denn das tatsächliche Intervall ist auf 900 Sekunden eingestellt und nicht auf 30. Vermutlich hast Du inzwischen eine andere Konfiguration oder?:

2017.12.13 15:57:11 5: KeSolarEdge: GetUpdate called
2017.12.13 15:57:11 4: KeSolarEdge: update timer modified: will call GetUpdate in 900.0 seconds at 2017-12-13 16:12:11 - Interval 900

während des GetUpdate Zyklus werden aber auch alle Readings erfolgreich abgefragt:

2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for AC-Volt-Scale assigns -1
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for DC-Current-Scale assigns -5
2017.12.13 15:57:11 4: KeSolarEdge: ParseObj for DC-Current assigns 4070
...

Fehlende Readings oder Fehler beim Empfang kann ich auch hier nicht erkennen.

3) Zwischendurch beendet Dein SolarEdge zwei mal die Verbindung und Fhem baut sie danach wieder auf.

2017.12.13 15:53:33 3: 192.168.178.36:502 disconnected, waiting to reappear (KeSolarEdge)
2017.12.13 15:53:33 5: HttpUtils url=http://192.168.178.36:502/
2017.12.13 15:53:33 3: 192.168.178.36:502 reappeared (KeSolarEdge)
...
2017.12.13 15:55:36 3: 192.168.178.36:502 disconnected, waiting to reappear (KeSolarEdge)
2017.12.13 15:55:36 5: HttpUtils url=http://192.168.178.36:502/
2017.12.13 15:55:36 1: disconnected
2017.12.13 15:55:36 3: 192.168.178.36:502 reappeared (KeSolarEdge)

Das ist nicht weiter schlimm. Viele Modbus-TCP-Geräte machen das und ein Modbus-Master muss die Verbindung dann eben wieder aufbauen. Das ändert nichts an der Funktion.
Wenn die Meldungen stören, kann man diese mit dem Attribut silentReconnect unterdrücken.


Wo fehlen denn Readings?

Hast Du eventuell in der aktuellen Konfiguration ein event-on-change-reading mit event-min-intervall, so dass Readings, die sich nicht ändern, keinen Event auslösen? Dadurch könnte ja der Eindruck entstehen, dass die Readings nicht aktualisiert werden ...

Warum fragst Du die Readings sowohl über das eingebaute Intervall als auch nochmals explizit über get ab?

Gruss
   Stefan

[BenMarloe]

Hallo Stefan,

Danke für die Hilfestellung.
zu 1) Ich frage die Daten über Get ab, weil ich zeitweise nur auf den ersten Wert ein Update bekommen habe. Seit ich das Get habe ist der Effekt allerdings weg. Außerdem ist meine Get-Abfrage in der Zwischenzeit dynamisch, d.h. ich frage den Wechselrichter minütlich ab solage er an ist und im Nachtmodus nur noch alle 20 Minuten.

zu 2) Deine Beobachtung ist natürlich korrekt. Ich habe das Update auf 900s runtergestellt um nicht alles vollzumüllen. So Sachen wie Heatsink-Temp will ich trotzdem mitgeloggt haben, falls ich es mal brauche. Ich hätte das sagen sollen.

zu 3)Das prinzipielle Fehlen habe ich nicht mehr. Aber ich bekomme auf meine Get häufig falsche Antworten (0 oder "No FD"), die aber in der Zwischenzeit (bis auf DC-Power) durch Plausibiltätsprüfungen abfangen kann. Aber in der Summe wirkt das auch mich, als wäre da noch ein Fehler.

Beispiel: der Wert "0" um 15:45:53 ist falsch. Die Spannung an einem Wechselrichter sinkt nicht so schnell

Code Auswählen Erweitern

2017-12-14_15:41:52 dum_pvdcvolt 747.8
2017-12-14_15:43:52 dum_pvenergy 76897
2017-12-14_15:43:52 dum_pvpower 19
2017-12-14_15:43:52 dum_pvloss 0.3
2017-12-14_15:43:52 dum_pvdcvolt 748.1
2017-12-14_15:45:53 dum_pvdcvolt 0
2017-12-14_15:47:55 dum_pvenergy 76898
2017-12-14_15:47:55 dum_pvpower 6
2017-12-14_15:47:55 dum_pvloss 0.1
2017-12-14_15:47:55 dum_pvdcvolt 748.1


das GET, das ich dazu geschrieben habe sieht so aus.

Code Auswählen Erweitern


+*00:20:00 {
  if (Value("KeSolarEdge") eq "opened") {
    my $ACEnergy = fhem ("get KeSolarEdge AC-Energy");
    my $ACEnergyScale = fhem ("get KeSolarEdge AC-Energy-Scale");
    my $ACE = $ACEnergy * (10 ** $ACEnergyScale);
   if ($ACE > 0) {
      fhem ("set dum_pvenergy " . $ACE);
   }
    #Log 1, "at_Solaredge: PV-Energy " . $ACE . "\n";
    my $ACPower = fhem ("get KeSolarEdge AC-Power");
    my $ACPowerScale = fhem ("get KeSolarEdge AC-Power-Scale");
   my $ACP = 0;
    if (($ACPower ne "No FD") and ($ACPowerScale ne "No FD")) {
      $ACP = $ACPower * (10 ** $ACPowerScale);
      if ($ACP != 0) {
        fhem ("set dum_pvpower " . $ACP);
        #Log 1, "at_Solaredge: PV-Power " . $ACP . "\n";
      }
    }
    my $DCPower = fhem ("get KeSolarEdge DC-Power");
    my $DCPowerScale = fhem ("get KeSolarEdge DC-Power-Scale");
   my $DCP = 0;
    if (($DCPower ne "no FD") and ($DCPowerScale ne "no FD")) {
     $DCP = $DCPower * (10 ** $DCPowerScale);
     if ($DCP != 0) {
        fhem ("set dum_pvloss " . sprintf("%.1f", ($DCP - $ACP)));
     }
   }
    my $DCVoltage = fhem ("get KeSolarEdge DC-Voltage");
    my $DCVoltageScale = fhem ("get KeSolarEdge DC-Voltage-Scale");
   my $DCV = 0;
    if (($DCVoltage ne "no FD") and ($DCVoltageScale ne "no FD")) {
      $DCV = $DCVoltage * (10 ** $DCVoltageScale);
      fhem ("set dum_pvdcvolt " . $DCV);
   }
   my $Status = fhem ("get KeSolarEdge Status");
   if ($Status == 2) {
     fhem ("modify at_solaredge +*00:20:00");
   }
   else {
     fhem ("modify at_solaredge +*00:01:00");
   }
  }
  else {
    Log 1, Value("KeSolarEdge");
  }
}


und der Fehler (zumindest der Teil, den ich nich abgefangen habe, nähmlich AC-Energy) ist natürlich auch im Logfile zu finden:

Code Auswählen Erweitern


2017.12.14 15:43:53 3: get KeSolarEdge Status : 4
2017.12.14 15:45:53 3: get KeSolarEdge AC-Energy : No data
2017.12.14 15:45:53 3: get KeSolarEdge AC-Energy-Scale : No FD
2017.12.14 15:45:53 1: PERL WARNING: Argument "No FD" isn't numeric in exponentiation (**) at (eval 380452) line 5.


sowei also alles logisch - nur warum???

[BenMarloe]

Falls mir jemand helfen kann das zum laufen zu bringen würde ich die Erkenntnisse auch im Wiki als neue unterstützte Hardware "SolarEdge Se10k" veröffentlichen. In dem Zustand traue ich mich das aber nicht.

[Butzel]

ich beziehe mich auf https://wiki.fhem.de/wiki/SolarEdge_SE10k

Mein SolarEdge ist unter ModbusAttr opened, die Attribute alle gesetzt, indem ich sie in die FHEM.cfg eingebunden habe.
Allerdings streikt mein Verstand bei den Dummys. Das erste Beispiel

Code Auswählen Erweitern

define pv_energyday_log dummy ist zwar drin, die darunter stehenden aber nicht.
Muss ich die erweitern um "dummy" ??
Wer kann die Anleitung im WIKI erhellen und eventuell für Dummys erläutern ?

Danke sehr

[Butzel]

der Link bei "Mehr Daten sind im Sunspec Protokoll zu finden " funktioniert leider nicht

[StefanStrobel]

Hallo BenMarloe,

Könntest Du das Problem inzwischen lösen?
Falls Du noch Hilfe brauchst, wäre ein langer Auszug aus dem Log, in dem man die disconnects / reconnects sehen kann, sowie die vollständige Konfiguration hilfreich.
NoFD etc. verweisen auf eine verlorene Verbindung.
Eigentlich sollte das Modbus–Modul selbständig Verbindungen wieder aufbauen.
Auch der Umweg über die get–Befehle sollte nicht nötig sein. Wenn Du das Abfrage-Intervall ändern möchtest, kannst Du das mit set interval machen.
Wenn nicht alle Werte als Antwort kommen, kann das am Timing oder an der Kombination von Abfragen liegen. Beides kann man mit Attributen konfigurieren.
Wenn es dennoch falsche Werte gibt, kann man die mit -ignoreExpr ausfiltern ...

Gruß
    Stefan

[KOBIL]

Ich habe den Solar Edge SE7K gem. der Beschreibung SE10K aus dem FhemWiki fast erfolgreich eingebunden. Allerdings bekomme ich im LOGFILE die Fehlermeldung:
2018.02.18 17:46:55.020 1: PERL WARNING: Argument "" isn't numeric in subtraction (-) at (eval 464278) line 16.
2018.02.18 17:46:55.021 3: eval: { if (Value("SolarEdge") eq "opened") {
    my $ACEnergy = fhem ("get SolarEdge AC-Energy");
    my $ACEnergyScale = fhem ("get SolarEdge AC-Energy-Scale");
    my $ACE = 0;
   my $day = Value("dum_pv_energyday_read");
   my $week = Value("dum_pv_energyweek_read");
   my $month = Value("dum_pv_energymonth_read");
   my $year = Value("dum_pv_energyyear_read");
    if (($ACEnergy ne "No Data") and ($ACEnergyScale ne "No FD")) {
      my $ACE = $ACEnergy * (10 ** $ACEnergyScale);
     if ($ACE > 0) {
        fhem ("set dum_pvenergy " . $ACE);
        fhem ("set dum_pv_energytoday " . ($ACE - $day));
        fhem ("set dum_pv_energytoweek " . ($ACE - $week));
        fhem ("set dum_pv_energytomonth " . ($ACE - $month));
        fhem ("set dum_pv_energytoyear " . ($ACE - $year));
     }
    }
Natürlich geht die at Def noch weiter...  Wie kann ich den Fehler finden?

Mit dem Dummy bin ich nicht wirklich zurechtgekommen (vielleicht ist hier auch der Fehler):
Im FhemWiki steht:
define pv_energyday_log dummy
dum_pv_energyday_log /r dum_pv_energyday_read
dum_pv_energymonth_log

Was ist "/r", bei mir sieht die fhem.cfg so aus:
define dum_pv_energyday_log dummy
define dum_pv_energyday_read dummy
define dum_pv_energymonth_log dummy