Velux KLF200 mit Firmware 2.0.0.71 für io-homecontrol

SparcWolf · 02 Juli 2019, 10:08:03

Zitat von: buennerbernd am 01 Juli 2019, 15:42:51
Hallo Guido,

Das Log war auch sehr hilfreich.
Wenn ich das richtig deute, dann hast du nach der Fehlermeldung wrong Position 10s gewartet und dann in Abständen von 5s updateStatus gerufen.
Die gute Nachricht ist, dass updateStatus gute Werte zurück gibt. Auch der Wert remaining und damit auch targetArrival sind brauchbar.
Das bedeutet, du kannst bei der wrong Position Fehlermeldung sofort einmalig updateStatus aufrufen. Das Modul müsste dann schon bei der jetzigen Implementierung erkennen, dass der Rollladen noch in Bewegung ist und zur erwarteten Ankunftszeit von selbst noch einmal den Status abrufen. Du musst das nicht selbst alle 5s tun.
Kannst du das bei Gelegenheit mal testen, ob das so klappt?

Gruß, Stefan.

Hallo Stefan,
Ja, stimmt. Das DoIf wartet 10 Sekunden und wiederholt dann alle 5 Sekunden das Kommando 8 mal. Danach müsste das Rollo auf jeden Fall das Ziel erreicht haben.

Leider sehe ich das hier nicht. Wenn ich einmal updateStatus ausführe bleibt es bei dem übermittelten Status.
Nach Ablauf der TargetArrival Zeit passiert nichts.
Ich habe hier noch ein zweites UpdateStatus ausgeführt. Ohne dieses passiert nichts mehr (habe 30 Minuten gewartet)

Code Auswählen


2019-07-02_09:22:09 velux_4 pct 90
2019-07-02_09:22:10 velux_4 MPtarget: 5120
2019-07-02_09:22:10 velux_4 target: 90
2019-07-02_09:22:10 velux_4 execution: up
2019-07-02_09:22:10 velux_4 sessionID: 4501
2019-07-02_09:22:10 velux_4 sessionStatusOwner: PROGRAM
2019-07-02_09:22:10 velux_4 sessionInformationCode: 06000400
2019-07-02_09:22:10 velux_4 lastRunStatus: EXECUTION ACTIVE
2019-07-02_09:22:10 velux_4 lastMasterExecutionAddress: d523a4
2019-07-02_09:22:10 velux_4 lastControl: FHEM
2019-07-02_09:22:10 velux_4 sessionInformationCode: 0f000500
2019-07-02_09:22:10 velux_4 lastRunStatus: EXECUTION FAILED
2019-07-02_09:22:10 velux_4 lastStatusReply: REACHED WRONG POSITION
2019-07-02_09:22:10 velux_4 execution: stop

2019-07-02_09:22:13 velux_4 updateStatus
2019-07-02_09:22:13 velux_4 sessionID: 4502
2019-07-02_09:22:13 velux_4 sessionStatusOwner: USER
2019-07-02_09:22:13 velux_4 lastRunStatus: EXECUTION COMPLETED
2019-07-02_09:22:13 velux_4 lastStatusReply: COMMAND COMPLETED OK
2019-07-02_09:22:13 velux_4 lastMasterExecutionAddress: 9dbd87
2019-07-02_09:22:13 velux_4 lastControl: Stand Alone Automatic Controls
2019-07-02_09:22:13 velux_4 MP: 27648
2019-07-02_09:22:13 velux_4 pct: 46
2019-07-02_09:22:13 velux_4 46
2019-07-02_09:22:13 velux_4 execution: up
2019-07-02_09:22:13 velux_4 remaining: 11
2019-07-02_09:22:13 velux_4 targetArrival: 2019-07-02 09:22:24
2019-07-02_09:22:13 velux_4 operatingState: Executing

2019-07-02_09:22:53 velux_4 updateStatus
2019-07-02_09:22:53 velux_4 sessionID: 4503
2019-07-02_09:22:53 velux_4 MP: 5120
2019-07-02_09:22:53 velux_4 pct: 90
2019-07-02_09:22:53 velux_4 90
2019-07-02_09:22:53 velux_4 execution: stop
2019-07-02_09:22:53 velux_4 remaining: 0
2019-07-02_09:22:53 velux_4 operatingState: Done

Mir ist aufgefallen, dass es nach der Bedienung des Rollos über die Wandschalter bis zu 15 Minuten dauert, bis der korrekte Status im FHEM angezeigt wird.
Scheinbar gibt es intern irgendwo einen Automatismus dafür, der alle 15 Minuten aktiv wird. Kannst Du den beeinflussen?

Vielleicht hat Marco (firebladerx52) das gleiche Problem.

Gruß,
Guido.

SparcWolf · 02 Juli 2019, 10:15:41

Hallo Stefan,

... nach fast 35 Minuten hat FHEM dann doch den Status aktualisiert:

Code Auswählen


2019-07-02_09:33:21 velux_4 pct 30
2019-07-02_09:33:21 velux_4 MPtarget: 35840
2019-07-02_09:33:21 velux_4 target: 30
2019-07-02_09:33:21 velux_4 execution: down
2019-07-02_09:33:22 velux_4 sessionID: 4511
2019-07-02_09:33:22 velux_4 sessionStatusOwner: PROGRAM
2019-07-02_09:33:22 velux_4 sessionInformationCode: 06000400
2019-07-02_09:33:22 velux_4 lastRunStatus: EXECUTION ACTIVE
2019-07-02_09:33:22 velux_4 lastMasterExecutionAddress: d523a4
2019-07-02_09:33:22 velux_4 lastControl: FHEM
2019-07-02_09:33:22 velux_4 sessionInformationCode: 0f000500
2019-07-02_09:33:22 velux_4 lastRunStatus: EXECUTION FAILED
2019-07-02_09:33:22 velux_4 lastStatusReply: REACHED WRONG POSITION
2019-07-02_09:33:22 velux_4 execution: stop

2019-07-02_09:33:27 velux_4 updateStatus
2019-07-02_09:33:28 velux_4 sessionID: 4512
2019-07-02_09:33:28 velux_4 sessionStatusOwner: USER
2019-07-02_09:33:28 velux_4 lastRunStatus: EXECUTION COMPLETED
2019-07-02_09:33:28 velux_4 lastStatusReply: COMMAND COMPLETED OK
2019-07-02_09:33:28 velux_4 lastMasterExecutionAddress: 9dbd87
2019-07-02_09:33:28 velux_4 lastControl: Stand Alone Automatic Controls
2019-07-02_09:33:28 velux_4 MP: 15872
2019-07-02_09:33:28 velux_4 pct: 69
2019-07-02_09:33:28 velux_4 69
2019-07-02_09:33:28 velux_4 execution: down
2019-07-02_09:33:28 velux_4 remaining: 9
2019-07-02_09:33:28 velux_4 targetArrival: 2019-07-02 09:33:37
2019-07-02_09:33:28 velux_4 operatingState: Executing

2019-07-02_10:10:40 velux_4 MP: 35840
2019-07-02_10:10:40 velux_4 pct: 30
2019-07-02_10:10:40 velux_4 30
2019-07-02_10:10:40 velux_4 execution: stop
2019-07-02_10:10:40 velux_4 remaining: 0
2019-07-02_10:10:40 velux_4 operatingState: Done
2019-07-02_10:10:41 velux_4 sessionID: 4529

Grüße,
Guido.

buennerbernd · 02 Juli 2019, 17:15:31

Hallo Guido und Marco,

momentan habe ich für den Support nur ein iPhone und mein Gedächtnis zur Verfügung. Viele Grüße aus dem Urlaub in Venetien!

Für die regelmäßigen Updates des Status wird der House Status Monitor Service verwendet: lest hier bitte selbst mal zur Info Kapitel 8.1
https://velcdn.azureedge.net/~/media/com/api/klf200/technical%20specification%20for%20klf%20200%20api-ver3-16.pdf

Bisher nahm ich an, dass Aktualisierungen mindestens alle 15 min kommen, es sei denn, der Akku ist total runter.
Bei Guido verwundert das umso mehr, da kein Akku im Spiel ist.

Wenn ich wieder zu Hause bin, denke ich mal über ein Attribut nach, um das Update-Intervall zu setzen und dann werden die Werte optional vom Modul regelmäßig abgeholt, statt sich auf den House Status Monitor Service zu verlassen.

@Guido Ich werde dann auch versuchen, das wrong Position Problem beim Somfy IZYMO zu vertuschen. Dann werde ich bestimmt noch einmal mit Wünschen nach speziellen Logs auf dich zukommen. Das Gerät antwortet einfach anders als andere Geräte.

@Marco Kannst du bitte auch noch ein List von deinem Gerät schicken, wenn du vergeblich auf ein Update nach Benutzung der Fernbedienung wartest?

Übergangsweise könnt ihr euch mit

Code Auswählen

set updateStatus
helfen.

Viele Grüße vom Mittelmeer.
Stefan.

SparcWolf · 03 Juli 2019, 10:05:56

Hallo Stefan,

die Angelegenheit ist doch nicht dringend. Mit den Workarounds kann ich gut leben. Also genieße Deinen Urlaub.

Da sich die Izymos so seltsam verhalten kommt der House Status vielleicht durcheinander.
Wenn ich das Rollo mit den Schaltern bediene kommt die Rückmeldung ja nach ca 15 Minuten. Somit funkioniert der HSMS.
Meine anderen IO-Geräte ( Velux-CVP und Sunea-IO ) funktionieren wunderbar mit deinem Modul.

Ja klar. Melde Dich, wenn ich etwas testen soll oder sonst irgendwie helfen kann.
Vielleicht gibt es dann ja auch schon Erfahrungen von Jörg (pejonp) mit den Izymos.

Viele Grüße und erholsamen Urlaub,
Guido.

pejonp · 08 Juli 2019, 20:10:43

Hallo,

ich habe mal 2 io-homecontroll Rolladenaktoren getestet.

1.Versuch: Izymo Rollladenmikromepfänger io (1822660 https://endlich-sicher.de/izymo-rollademikromepfaenger-io).
Das Zusammenspiel von KLR 200 funktioniert nicht so richtig. Es werden die Positionen falsch angefahren und es gibt Fehlerhinweise die bestätigt werden müssen.

2.Versuch: Somfy® Funkempfänger inkl. Schalter Smoove Uno A/M io Pure (1811404 https://www.tandmore.de/Installation/Somfy/Sonstiges/Somfy-SmooveUnoA-M-io-Funkmotorsteuergeraet::283970.html). Beim "Produkt suchen" durch den KLR 200 wird der Schalter kurz betätigt, aber es wird danach kein Produkt gefunden. Ging wieder zurück.

###Izymo Rollladenmikromepfänger io (1822660)###

Ich habe mich jetzt für Izymo Rollladenmikromepfänger io (1822660) entschieden. Kann von KLR200, KLF200 über FHEM und auch einer alten KLR100 angesteuert werden.
Mein Rolladen ist 116 cm lang. Wenn ich 50% eingebe, fährt er zu weit zu und zeigt dann irgend einen Wert an.

Wenn man es weiß kann man je andere Werte nehmen oder versuchen umzurechnen.

Was ich durch Zufall mit der KLR 100 rausgefunden habe ist.
Wenn man mit der KLR 100 einen Stopbefehl sendet, wird angehalten und die richtige Position wird auf der Fernbedienung und dann auch in fhem (KLF200) beim Rolladen angezeigt.

Wenn man per fhem ein Stopbefehl sendet, dann wird danach die richtige Position zurückgeliefert.

Ich wollte den Izymo Rollladenmikromepfänger io mit einer Einwegfernbedienung (VELUX Funktaster KLI 311 - Fenster) koppeln.
Ging leider nicht. Mit KLR200 und KLR100 funktioniert es.

###Izymo Rollladenmikromepfänger io (1822660)###

Jörg

SparcWolf · 08 Juli 2019, 20:48:27

Hallo Jörg,

Ja, das ging mir genauso. Die Smoove ließ sich nicht mit den Velux Produkten koppeln.
Mit einer Composio IO von Somfy alles kein Problem. Auch die Übertragung von der Composio auf die Velux ging nicht.

War die Kalibrierung (2x rauf+runter) vielleicht zu ungenau? Die Izymos "lernen" die Laufzeit des Rollo.
Das könnte aber auch mit den Fehlern zusammenhängen.
Meine Izymos melden FHEM nach den Kommandos "EXECUTION FAILED" und "REACHED WRONG POSITION" zurück.

Den Somfy Support interessieren Probleme mit anderen Herstellern (Velux) nicht.
Bei Velux habe ich noch nicht nachgefragt, ob die was tun können.

Als Workaround habe ich mir 2 DOIF zusammengebastelt.

Code Auswählen


define doif_velux_err DOIF ( ["velux_:lastStatusReply"] and [$DEVICE:lastStatusReply] =~ "REACHED WRONG POSITION" ) (set $DEVICE updateStatus)
attr doif_velux_err do always
attr doif_velux_err wait 1

define doif_velux_poll DOIF ( [+00:00:30] ) (set model=Somfy.Roller.Shutter.Type.2 updateStatus )
attr doif_velux_poll do always

Gruß,
Guido.

pejonp · 09 Juli 2019, 09:07:22

Hallo Guido,

An Velux habe ich eine Mail mit den Problemen geschickt. Sie können da nichts machen, kennen aber die Probleme. Ihre Schwerpunkte liegen eher woanders.

Das Kalibrieren hat bei mir nicht so richtig was gebracht, ich weis auch nicht ob es zu 100% geklappt hat. Da ja der Motor durch die Endlagenschalter abschaltet.

Setzte mal nur einen Stopp

Code Auswählen


set $DEVICE stop

Befehl nach dem Halten des Rollos ab und keinen

Code Auswählen


set $DEVICE updateStatus

Bei mir wird dann die Position zurückgegeben.

Jörg

buennerbernd · 10 Juli 2019, 08:12:38

Zitat von: SparcWolf am 08 Juli 2019, 20:48:27
Hallo Jörg,

Ja, das ging mir genauso. Die Smoove ließ sich nicht mit den Velux Produkten koppeln.
Mit einer Composio IO von Somfy alles kein Problem. Auch die Übertragung von der Composio auf die Velux ging nicht.

War die Kalibrierung (2x rauf+runter) vielleicht zu ungenau? Die Izymos "lernen" die Laufzeit des Rollo.
Das könnte aber auch mit den Fehlern zusammenhängen.
Meine Izymos melden FHEM nach den Kommandos "EXECUTION FAILED" und "REACHED WRONG POSITION" zurück.

Den Somfy Support interessieren Probleme mit anderen Herstellern (Velux) nicht.
Bei Velux habe ich noch nicht nachgefragt, ob die was tun können.

Als Workaround habe ich mir 2 DOIF zusammengebastelt.
Code Auswählen Erweitern
define doif_velux_err DOIF ( ["velux_:lastStatusReply"] and [$DEVICE:lastStatusReply] =~ "REACHED WRONG POSITION" ) (set $DEVICE updateStatus) attr doif_velux_err do always attr doif_velux_err wait 1 define doif_velux_poll DOIF ( [+00:00:30] ) (set model=Somfy.Roller.Shutter.Type.2 updateStatus ) attr doif_velux_poll do always

Gruß,
Guido.

Hallo Guido,

Davon hätte ich jetzt gerne mal ein fhem log verbose 5 und ein Log vom IZYMO.
Am besten nur ein Gerät. Wichtig ist, dass das Auslösen der Bewegung drauf ist, einmal updateStatus während der Bewegung und einmal danach.

Dann sollte ich mir gut zusammenreimen können, was da vor sich geht.

Gruß,
Stefan.

SparcWolf · 13 Juli 2019, 12:37:57

Zitat von: pejonp am 09 Juli 2019, 09:07:22
Hallo Guido,

An Velux habe ich eine Mail mit den Problemen geschickt. Sie können da nichts machen, kennen aber die Probleme. Ihre Schwerpunkte liegen eher woanders.

Das Kalibrieren hat bei mir nicht so richtig was gebracht, ich weis auch nicht ob es zu 100% geklappt hat. Da ja der Motor durch die Endlagenschalter abschaltet.

Setzte mal nur einen Stopp

Code Auswählen Erweitern
set $DEVICE stop
Befehl nach dem Halten des Rollos ab und keinen
Code Auswählen Erweitern
set $DEVICE updateStatus
Bei mir wird dann die Position zurückgegeben.

Jörg

Hallo Jörg,
Ja, das ist bei mir genauso. "UpdateStatus" macht das aber auch.

Grüße,
Guido.

SparcWolf · 13 Juli 2019, 12:58:44

Zitat von: buennerbernd am 10 Juli 2019, 08:12:38
Hallo Guido,

Davon hätte ich jetzt gerne mal ein fhem log verbose 5 und ein Log vom IZYMO.
Am besten nur ein Gerät. Wichtig ist, dass das Auslösen der Bewegung drauf ist, einmal updateStatus während der Bewegung und einmal danach.

Dann sollte ich mir gut zusammenreimen können, was da vor sich geht.

Gruß,
Stefan.

Hallo Stefan,

Ok. Ich habe im FHEM global verbose auf 5 gesetzt und neu gestartet.
* updateStatus
* set pct 0
* updateStatus (unterwegs)
* updateStatus (Ende)

* fhem.log

Code Auswählen


2019.07.13 12:52:23 5: SYSMON sysmon: updateReadings.1060 
2019.07.13 12:52:23 5: Starting notify loop for sysmon, 1 event(s), first is cpu_freq: 600
2019.07.13 12:52:23 5: createNotifyHash
2019.07.13 12:52:23 5: Power: not on any display, ignoring notify
2019.07.13 12:52:23 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:23 5: SYSMON sysmon: Set.748 sysmon ?
2019.07.13 12:52:23 5: End notify loop for sysmon
2019.07.13 12:52:23 4: BlockingCall (SYSMON_blockingCall): created child (8026), uses telnetForBlockingFn_1563014855 to connect back
2019.07.13 12:52:23 4: Connection accepted from telnetForBlockingFn_1563014855_127.0.0.1_40836
2019.07.13 12:52:23 5: Cmd: >{BlockingRegisterTelnet($cl,15)}<
2019.07.13 12:52:23 5: SYSMON sysmon: blockingCall.954 sysmon, 
2019.07.13 12:52:23 5: SYSMON sysmon: Exec_Local.4151 Execute '[ -d /proc/ ] && echo 1 || echo 0'
2019.07.13 12:52:23 5: SYSMON sysmon: Exec_Local.4164 Result '1'
2019.07.13 12:52:23 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /proc/uptime'
2019.07.13 12:52:23 5: SYSMON sysmon: Exec_Local.4164 Result '4239134.88 16105670.96'
2019.07.13 12:52:23 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /proc/stat|grep 'cpu ''
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result 'cpu  3306911 0 4642006 1681707893 331637 0 153992 0 0 0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '[ -f /sys/devices/system/cpu/kernel_max ] && echo 1 || echo 0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/devices/system/cpu/kernel_max'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '3'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/class/thermal/thermal_zone0/temp 2>&1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '50464'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '[ -f /sys/class/hwmon/hwmon0/device/temp1_input ] && echo 1 || echo 0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /proc/loadavg'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '0.07 0.03 0.00 1/144 8036'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /proc/stat'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4156 Result '$VAR1 = 'cpu  3306912 0 4642015 1681707924 331637 0 153992 0 0 0
';
$VAR2 = 'cpu0 722117 0 1005685 417033192 203006 0 130635 0 0 0
';
$VAR3 = 'cpu1 909944 0 1182456 421630518 43017 0 7975 0 0 0
';
$VAR4 = 'cpu2 995515 0 1184216 421330598 27353 0 8493 0 0 0
';
$VAR5 = 'cpu3 679336 0 1269658 421713615 58260 0 6889 0 0 0
';
$VAR6 = 'intr 412300391 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2273062 36 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 275245 1195857 0 0 0 0 0 0 0 705 0 0 83309860 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1083019 7233 0 0 0 0 109260661 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 123823032 0 0 0 1
';
$VAR7 = 'ctxt 348914297
';
$VAR8 = 'btime 1558776009
';
$VAR9 = 'processes 2050353
';
$VAR10 = 'procs_running 1
';
$VAR11 = 'procs_blocked 0
';
$VAR12 = 'softirq 409537254 8015331 107610405 2098573 18227544 0 0 104177539 106244290 1458 63162114
';
'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'free -V'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result 'free from procps-ng 3.3.12'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'LANG=en free -w'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4156 Result '$VAR1 = '              total        used        free      shared     buffers       cache   available
';
$VAR2 = 'Mem:         948300      200604       45880       24040       59748      642068      667712
';
$VAR3 = 'Swap:        102396       58112       44284
';
'
2019.07.13 12:52:24 5: SYSMON sysmon: getNetworkInfo.2688 get eth0:eth0:Ethernet
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '/sbin/ifconfig eth0 2>&1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4156 Result '$VAR1 = 'eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
';
$VAR2 = '        inet 10.100.14.40  netmask 255.255.255.0  broadcast 10.100.14.255
';
$VAR3 = '        inet6 fe80::f0a8:79a0:7c06:2386  prefixlen 64  scopeid 0x20<link>
';
$VAR4 = '        ether b8:27:eb:13:d7:3e  txqueuelen 1000  (Ethernet)
';
$VAR5 = '        RX packets 5557156  bytes 966265271 (921.5 MiB)
';
$VAR6 = '        RX errors 0  dropped 9  overruns 0  frame 0
';
$VAR7 = '        TX packets 5599247  bytes 927080655 (884.1 MiB)
';
$VAR8 = '        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
';
$VAR9 = '
';
'
2019.07.13 12:52:24 5: SYSMON sysmon: getNetworkInfo.2700 SYSMON_getNetworkInfo>>>>>>>>>>>>>>>>$VAR1 = 'eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
';
$VAR2 = '        inet 10.100.14.40  netmask 255.255.255.0  broadcast 10.100.14.255
';
$VAR3 = '        inet6 fe80::f0a8:79a0:7c06:2386  prefixlen 64  scopeid 0x20<link>
';
$VAR4 = '        ether b8:27:eb:13:d7:3e  txqueuelen 1000  (Ethernet)
';
$VAR5 = '        RX packets 5557156  bytes 966265271 (921.5 MiB)
';
$VAR6 = '        RX errors 0  dropped 9  overruns 0  frame 0
';
$VAR7 = '        TX packets 5599247  bytes 927080655 (884.1 MiB)
';
$VAR8 = '        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
';
$VAR9 = '
';

2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '[ -f /sys/class/net/eth0/statistics/rx_bytes ] && echo 1 || echo 0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/class/net/eth0/statistics/rx_bytes'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '966265601'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/class/net/eth0/statistics/tx_bytes'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '927083289'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '[ -f /sys/class/net/eth0/speed ] && echo 1 || echo 0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/class/net/eth0/speed 2>/dev/null'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '1000'
2019.07.13 12:52:24 5: SYSMON sysmon: getNetworkInfo.2688 get wlan0:wlan0:WiFi
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '/sbin/ifconfig wlan0 2>&1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4156 Result '$VAR1 = 'wlan0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
';
$VAR2 = '        inet 192.168.100.104  netmask 255.255.255.0  broadcast 192.168.100.255
';
$VAR3 = '        inet6 fe80::7374:ba6:966:af52  prefixlen 64  scopeid 0x20<link>
';
$VAR4 = '        ether b8:27:eb:46:82:6b  txqueuelen 1000  (Ethernet)
';
$VAR5 = '        RX packets 9877348  bytes 605833894 (577.7 MiB)
';
$VAR6 = '        RX errors 0  dropped 8495415  overruns 0  frame 0
';
$VAR7 = '        TX packets 2155275  bytes 816749975 (778.9 MiB)
';
$VAR8 = '        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
';
$VAR9 = '
';
'
2019.07.13 12:52:24 5: SYSMON sysmon: getNetworkInfo.2700 SYSMON_getNetworkInfo>>>>>>>>>>>>>>>>$VAR1 = 'wlan0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
';
$VAR2 = '        inet 192.168.100.104  netmask 255.255.255.0  broadcast 192.168.100.255
';
$VAR3 = '        inet6 fe80::7374:ba6:966:af52  prefixlen 64  scopeid 0x20<link>
';
$VAR4 = '        ether b8:27:eb:46:82:6b  txqueuelen 1000  (Ethernet)
';
$VAR5 = '        RX packets 9877348  bytes 605833894 (577.7 MiB)
';
$VAR6 = '        RX errors 0  dropped 8495415  overruns 0  frame 0
';
$VAR7 = '        TX packets 2155275  bytes 816749975 (778.9 MiB)
';
$VAR8 = '        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
';
$VAR9 = '
';

2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '[ -f /sys/class/net/wlan0/statistics/rx_bytes ] && echo 1 || echo 0'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '1'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/class/net/wlan0/statistics/rx_bytes'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '605833894'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute 'cat /sys/class/net/wlan0/statistics/tx_bytes'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4164 Result '816749975'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4151 Execute '/sbin/iwconfig wlan0 2>/dev/null'
2019.07.13 12:52:24 5: SYSMON sysmon: Exec_Local.4156 Result '$VAR1 = 'wlan0     IEEE 802.11  ESSID:"Ueber_den_Wolken-CC"  
';
$VAR2 = '          Mode:Managed  Frequency:5.18 GHz  Access Point: 44:4E:6D:60:5E:36   
';
$VAR3 = '          Bit Rate=200 Mb/s   Tx-Power=31 dBm   
';
$VAR4 = '          Retry short limit:7   RTS thr:off   Fragment thr:off
';
$VAR5 = '          Power Management:on
';
$VAR6 = '          Link Quality=70/70  Signal level=-39 dBm  
';
$VAR7 = '          Rx invalid nwid:0  Rx invalid crypt:0  Rx invalid frag:0
';
$VAR8 = '          Tx excessive retries:27  Invalid misc:0   Missed beacon:0
';
$VAR9 = '
';
'
2019.07.13 12:52:24 5: Cmd: >{BlockingStart('15')}<
2019.07.13 12:52:24 5: Cmd: >{SYSMON_blockingFinish('name|sysmon|stat_cpu1|909944 0 1182456 421630518 43017 0 7975|stat_cpu_diff|172 0 78 23626 6 0 9|stat_cpu3_diff|2 0 18 5969 0 0 2|stat_cpu2_text|user: 0.10 %, nice: 0.00 %, sys: 0.35 %, idle: 99.55 %, io: 0.00 %, irq: 0.00 %, sirq: 0.00 %|stat_cpu|3306912 0 4642015 1681707924 331637 0 153992|uptime|4239134|cpu1_idle_stat|2.31 100.00 98.40|eth0|RX: 921.50 MB, TX: 884.14 MB, Total: 1805.64 MB|stat_cpu0_text|user: 0.19 %, nice: 0.00 %, sys: 0.27 %, idle: 99.32 %, io: 0.10 %, irq: 0.00 %, sirq: 0.12 %|stat_cpu0_diff|11 0 16 5884 6 0 7|eth0_tx|927083289|stat_cpu0_percent|0.19 0.00 0.27 99.32 0.10 0.00 0.12|stat_cpu1_percent|2.55 0.00 0.38 97.06 0.00 0.00 0.00|eth0_rx|966265601|cpu_temp_stat|36.48 60.69 50.55|starttime_text|25.05.2019 11:20:09|wlan0_ip|192.168.100.104|swap_used_stat|0.00 62.00 56.73|cpu3_idle_stat|17.64 100.00 98.21|idletime_text|48 days, 15 hours, 51 minutes (99.18 %)|cpu_core_count|4|fhemstarttime_text|13.07.2019 12:47:26|cpu_idle_stat|56.21 99.82 98.82|stat_cpu3_percent|0.03 0.00 0.30 99.63 0.00 0.00 0.03|wlan0|RX: 577.77 MB, TX: 778.91 MB, Total: 1356.68 MB|idletime|4204269 99.18 %|stat_cpu_percent|0.72 0.00 0.33 98.89 0.03 0.00 0.04|fhemstarttime|1563014846|stat_cpu3|679336 0 1269658 421713615 58260 0 6889|wlan0_speed|200|fhemuptime|298|stat_cpu0|722117 0 1005685 417033192 203006 0 130635|starttime|1558776009|wlan0_diff|RX: 0.01 MB, TX: 0.01 MB, Total: 0.02 MB|stat_cpu2|995515 0 1184216 421330598 27353 0 8493|swap|Total: 100.00 MB, Used: 56.75 MB,  56.75 %, Free: 43.25 MB|stat_cpu2_diff|6 0 21 5958 0 0 0|cpu0_idle_stat|3.23 99.98 99.19|eth0_ip|10.100.14.40|stat_cpu2_percent|0.10 0.00 0.35 99.55 0.00 0.00 0.00|loadavg|0.07 0.03 0.00|eth0_speed|1000|cpu_temp_avg|50.6|cpu_temp|50.46|fhemuptime_text|0 days, 00 hours, 04 minutes|ram|Total: 926.07 MB, Used: 195.90 MB, 21.15 %, Free: 44.80 MB|cpu2_idle_stat|39.32 100.00 99.47|stat_cpu1_diff|153 0 23 5815 0 0 0|ram_used_stat|69.94 283.02 195.83|stat_cpu3_text|user: 0.03 %, nice: 0.00 %, sys: 0.30 %, idle: 99.63 %, io: 0.00 %, irq: 0.00 %, sirq: 0.03 %|wlan0_tx|816749975|wlan0_rx|605833894|uptime_text|49 days, 01 hours, 32 minutes|eth0_diff|RX: 0.06 MB, TX: 0.13 MB, Total: 0.19 MB|stat_cpu1_text|user: 2.55 %, nice: 0.00 %, sys: 0.38 %, idle: 97.06 %, io: 0.00 %, irq: 0.00 %, sirq: 0.00 %|stat_cpu_text|user: 0.72 %, nice: 0.00 %, sys: 0.33 %, idle: 98.89 %, io: 0.03 %, irq: 0.00 %, sirq: 0.04 %')}<
2019.07.13 12:52:24 5: SYSMON sysmon: blockingFinish.1041 name|sysmon|stat_cpu1|909944 0 1182456 421630518 43017 0 7975|stat_cpu_diff|172 0 78 23626 6 0 9|stat_cpu3_diff|2 0 18 5969 0 0 2|stat_cpu2_text|user: 0.10 %, nice: 0.00 %, sys: 0.35 %, idle: 99.55 %, io: 0.00 %, irq: 0.00 %, sirq: 0.00 %|stat_cpu|3306912 0 4642015 1681707924 331637 0 153992|uptime|4239134|cpu1_idle_stat|2.31 100.00 98.40|eth0|RX: 921.50 MB, TX: 884.14 MB, Total: 1805.64 MB|stat_cpu0_text|user: 0.19 %, nice: 0.00 %, sys: 0.27 %, idle: 99.32 %, io: 0.10 %, irq: 0.00 %, sirq: 0.12 %|stat_cpu0_diff|11 0 16 5884 6 0 7|eth0_tx|927083289|stat_cpu0_percent|0.19 0.00 0.27 99.32 0.10 0.00 0.12|stat_cpu1_percent|2.55 0.00 0.38 97.06 0.00 0.00 0.00|eth0_rx|966265601|cpu_temp_stat|36.48 60.69 50.55|starttime_text|25.05.2019 11:20:09|wlan0_ip|192.168.100.104|swap_used_stat|0.00 62.00 56.73|cpu3_idle_stat|17.64 100.00 98.21|idletime_text|48 days, 15 hours, 51 minutes (99.18 %)|cpu_core_count|4|fhemstarttime_text|13.07.2019 12:47:26|cpu_idle_stat|56.21 99.82 98.82|stat_cpu3_percent|0.03 0.00 0.30 99.63 0.00 0.00 0.03|wlan0|RX: 577.77 MB, TX: 778.91 MB, Total: 1356.68 MB|idletime|4204269 99.18 %|stat_cpu_percent|0.72 0.00 0.33 98.89 0.03 0.00 0.04|fhemstarttime|1563014846|stat_cpu3|679336 0 1269658 421713615 58260 0 6889|wlan0_speed|200|fhemuptime|298|stat_cpu0|722117 0 1005685 417033192 203006 0 130635|starttime|1558776009|wlan0_diff|RX: 0.01 MB, TX: 0.01 MB, Total: 0.02 MB|stat_cpu2|995515 0 1184216 421330598 27353 0 8493|swap|Total: 100.00 MB, Used: 56.75 MB,  56.75 %, Free: 43.25 MB|stat_cpu2_diff|6 0 21 5958 0 0 0|cpu0_idle_stat|3.23 99.98 99.19|eth0_ip|10.100.14.40|stat_cpu2_percent|0.10 0.00 0.35 99.55 0.00 0.00 0.00|loadavg|0.07 0.03 0.00|eth0_speed|1000|cpu_temp_avg|50.6|cpu_temp|50.46|fhemuptime_text|0 days, 00 hours, 04 minutes|ram|Total: 926.07 MB, Used: 195.90 MB, 21.15 %, Free: 44.80 MB|cpu2_idle_stat|39.32 100.00 99.47|stat_cpu1_diff|153 0 23 5815 0 0 0|ram_used_stat|69.94 283.02 195.83|stat_cpu3_text|user: 0.03 %, nice: 0.00 %, sys: 0.30 %, idle: 99.63 %, io: 0.00 %, irq: 0.00 %, sirq: 0.03 %|wlan0_tx|816749975|wlan0_rx|605833894|uptime_text|49 days, 01 hours, 32 minutes|eth0_diff|RX: 0.06 MB, TX: 0.13 MB, Total: 0.19 MB|stat_cpu1_text|user: 2.55 %, nice: 0.00 %, sys: 0.38 %, idle: 97.06 %, io: 0.00 %, irq: 0.00 %, sirq: 0.00 %|stat_cpu_text|user: 0.72 %, nice: 0.00 %, sys: 0.33 %, idle: 98.89 %, io: 0.03 %, irq: 0.00 %, sirq: 0.04 %
2019.07.13 12:52:24 5: SYSMON sysmon: updateReadings.1060 
2019.07.13 12:52:24 5: Starting notify loop for sysmon, 6 event(s), first is stat_cpu_percent: 0.72 0.00 0.33 98.89 0.03 0.00 0.04
2019.07.13 12:52:24 5: Power: not on any display, ignoring notify
2019.07.13 12:52:24 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:24 5: SYSMON sysmon: Set.748 sysmon ?
2019.07.13 12:52:24 5: End notify loop for sysmon
2019.07.13 12:52:30 5: Cmd: >set velux_4 updateStatus<
2019.07.13 12:52:30 5: Starting notify loop for velux, 1 event(s), first is sessionID: 61442
2019.07.13 12:52:30 5: Power: not on any display, ignoring notify
2019.07.13 12:52:30 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:30 5: End notify loop for velux
2019.07.13 12:52:30 5: KLF200Node (velux_4) KLF200Node_GW_STATUS_REQUEST_REQ SessionID 61442 StatusType 3
2019.07.13 12:52:30 5: Starting notify loop for velux, 1 event(s), first is queueSize: 1
2019.07.13 12:52:30 5: Power: not on any display, ignoring notify
2019.07.13 12:52:30 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:30 5: End notify loop for velux
2019.07.13 12:52:30 5: KLF200 velux: unwrapped bytes     0305f002010400000000000000000000000000000000000000030000
2019.07.13 12:52:30 5: KLF200 velux: wrapped bytes c0001d0305f002010400000000000000000000000000000000000000030000efc0
2019.07.13 12:52:30 5: SW: c0001d0305f002010400000000000000000000000000000000000000030000efc0
2019.07.13 12:52:30 5: Starting notify loop for velux_4, 1 event(s), first is updateStatus
2019.07.13 12:52:30 5: Power: not on any display, ignoring notify
2019.07.13 12:52:30 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:30 5: End notify loop for velux_4
2019.07.13 12:52:30 5: KLF200 (velux) - received: 0306f00201
2019.07.13 12:52:30 5: KLF200 (velux) - ignored:  0306f00201
2019.07.13 12:52:30 5: KLF200 (velux) - received: 0307f00201040001030000000000009dbd870008
2019.07.13 12:52:30 5: velux: dispatch \003\a�\002\001\004\000\001\003\000\000\000\000\000\000���\000\010
2019.07.13 12:52:30 5: KLF200Node (velux) - received: 0307f00201040001030000000000009dbd870008
2019.07.13 12:52:30 5: KLF200Node (velux_4) GW_STATUS_REQUEST_NTF 0307 61442 1 4 0 1 3
2019.07.13 12:52:30 5: Starting notify loop for velux_4, 1 event(s), first is sessionID: 61442
2019.07.13 12:52:30 5: Power: not on any display, ignoring notify
2019.07.13 12:52:30 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:30 5: End notify loop for velux_4
2019.07.13 12:52:30 5: KLF200 (velux) - received: 0304f002
2019.07.13 12:52:30 5: KLF200 (velux) GW_SESSION_FINISHED_NTF 0304 61442
2019.07.13 12:52:30 5: KLF200 (velux) Dequeue: regex = (?^:^(\x04\x12|\x03\x05|\x03\x12|\x03\x10))
2019.07.13 12:52:30 5: KLF200 (velux) Dequeue: SessionID = 61442
2019.07.13 12:52:30 5: KLF200 (velux) Dequeue: 0305f002010400000000000000000000000000000000000000030000
2019.07.13 12:52:30 5: KLF200 (velux) Dequeue: mached
2019.07.13 12:52:30 5: Starting notify loop for velux, 1 event(s), first is queueSize: 0
2019.07.13 12:52:30 5: Power: not on any display, ignoring notify
2019.07.13 12:52:30 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:30 5: End notify loop for velux
2019.07.13 12:52:30 5: KLF200 (velux) - received: 0211040500000000f7fff7fff7fff7ff0000b7ee0000
2019.07.13 12:52:30 5: velux: dispatch \002\021\004\005\000\000\000\000��������\000\000��\000\000
2019.07.13 12:52:30 5: KLF200Node (velux) - received: 0211040500000000f7fff7fff7fff7ff0000b7ee0000
2019.07.13 12:52:30 5: KLF200Node (velux_4) GW_NODE_STATE_POSITION_CHANGED_NTF 0211 4 5 MP:0 T:0 FP1:63487 0 3085828096
2019.07.13 12:52:30 5: KLF200Node (velux_4) BulkUpdateMain MP:0 T:0 R:0 Done
2019.07.13 12:52:30 5: Starting notify loop for velux_4, 2 event(s), first is MP: 0
2019.07.13 12:52:30 5: Power: not on any display, ignoring notify
2019.07.13 12:52:30 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:31 5: End notify loop for velux_4
2019.07.13 12:52:33 5: Cmd: >set velux_4 pct 0<
2019.07.13 12:52:33 5: KLF200Node (velux_4) - set 0
2019.07.13 12:52:33 5: Starting notify loop for velux, 1 event(s), first is sessionID: 61443
2019.07.13 12:52:33 5: Power: not on any display, ignoring notify
2019.07.13 12:52:33 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:33 5: End notify loop for velux
2019.07.13 12:52:33 5: KLF200Node (velux_4) KLF200Node_GW_COMMAND_SEND_REQ SessionID 61443 raw 51200
2019.07.13 12:52:33 5: Starting notify loop for velux, 1 event(s), first is queueSize: 1
2019.07.13 12:52:33 5: Power: not on any display, ignoring notify
2019.07.13 12:52:33 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:33 5: End notify loop for velux
2019.07.13 12:52:33 5: KLF200 velux: unwrapped bytes     0300f0030805000000c800000000000000000000000000000000000000000000000000000000000000000001040000000000000000000000000000000000000000000000
2019.07.13 12:52:33 5: KLF200 velux: wrapped bytes c000450300f0030805000000c80000000000000000000000000000000000000000000000000000000000000000000104000000000000000000000000000000000000000000000075c0
2019.07.13 12:52:33 5: SW: c000450300f0030805000000c80000000000000000000000000000000000000000000000000000000000000000000104000000000000000000000000000000000000000000000075c0
2019.07.13 12:52:33 5: Starting notify loop for velux_4, 1 event(s), first is pct 0
2019.07.13 12:52:33 5: Power: not on any display, ignoring notify
2019.07.13 12:52:33 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:33 5: End notify loop for velux_4
2019.07.13 12:52:33 5: KLF200 (velux) - received: 0301f00301
2019.07.13 12:52:33 5: KLF200 (velux) GW_COMMAND_SEND_CFM 0301 61443 1
2019.07.13 12:52:33 5: KLF200 (velux) Dequeue: regex = (?^:^\x03\x00)
2019.07.13 12:52:33 5: KLF200 (velux) Dequeue: SessionID = 61443
2019.07.13 12:52:33 5: KLF200 (velux) Dequeue: 0300f0030805000000c800000000000000000000000000000000000000000000000000000000000000000001040000000000000000000000000000000000000000000000
2019.07.13 12:52:33 5: KLF200 (velux) Dequeue: mached
2019.07.13 12:52:33 5: Starting notify loop for velux, 1 event(s), first is queueSize: 0
2019.07.13 12:52:33 5: Power: not on any display, ignoring notify
2019.07.13 12:52:33 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:33 5: End notify loop for velux
2019.07.13 12:52:33 5: KLF200 (velux) - received: 021104020000c800f7fff7fff7fff7ff0000b7f10000
2019.07.13 12:52:33 5: velux: dispatch \002\021\004\002\000\000�\000��������\000\000��\000\000
2019.07.13 12:52:33 5: KLF200Node (velux) - received: 021104020000c800f7fff7fff7fff7ff0000b7f10000
2019.07.13 12:52:33 5: KLF200Node (velux_4) GW_NODE_STATE_POSITION_CHANGED_NTF 0211 4 2 MP:0 T:51200 FP1:63487 0 3086024704
2019.07.13 12:52:33 5: KLF200Node (velux_4) BulkUpdateMain MP:0 T:51200 R:0 'Not used'
2019.07.13 12:52:33 5: Starting notify loop for velux_4, 3 event(s), first is MPtarget: 51200
2019.07.13 12:52:33 5: Power: not on any display, ignoring notify
2019.07.13 12:52:33 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:33 5: End notify loop for velux_4
2019.07.13 12:52:33 5: KLF200 (velux) - received: 0302f0030804000000020106000400
2019.07.13 12:52:33 5: velux: dispatch \003\002�\003\010\004\000\000\000\002\001\006\000\004\000
2019.07.13 12:52:33 5: KLF200Node (velux) - received: 0302f0030804000000020106000400
2019.07.13 12:52:33 5: KLF200Node (velux_4) GW_COMMAND_RUN_STATUS_NTF 0302 61443 8 4 FP0:0 2 1 06000400
2019.07.13 12:52:33 5: Starting notify loop for velux_4, 6 event(s), first is sessionID: 61443
2019.07.13 12:52:33 5: Power: not on any display, ignoring notify
2019.07.13 12:52:33 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:34 5: End notify loop for velux_4
2019.07.13 12:52:34 5: KLF200 (velux) - received: 0302f003080400000001070f000500
2019.07.13 12:52:34 5: velux: dispatch \003\002�\003\010\004\000\000\000\001\a\017\000\005\000
2019.07.13 12:52:34 5: KLF200Node (velux) - received: 0302f003080400000001070f000500
2019.07.13 12:52:34 5: KLF200Node (velux_4) GW_COMMAND_RUN_STATUS_NTF 0302 61443 8 4 FP0:0 1 7 0f000500
2019.07.13 12:52:34 5: Starting notify loop for velux_4, 3 event(s), first is sessionInformationCode: 0f000500
2019.07.13 12:52:34 5: Power: not on any display, ignoring notify
2019.07.13 12:52:34 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:34 5: End notify loop for velux_4
2019.07.13 12:52:34 5: KLF200 (velux) - received: 0304f003
2019.07.13 12:52:34 5: KLF200 (velux) GW_SESSION_FINISHED_NTF 0304 61443
2019.07.13 12:52:34 5: KLF200 (velux) Dequeue: regex = (?^:^(\x04\x12|\x03\x05|\x03\x12|\x03\x10))
2019.07.13 12:52:34 5: KLF200 (velux) Dequeue: SessionID = 61443
2019.07.13 12:52:34 5: KLF200 (velux) - received: 021104050000c800f7fff7fff7fff7ff0000b7f10000
2019.07.13 12:52:34 5: velux: dispatch \002\021\004\005\000\000�\000��������\000\000��\000\000
2019.07.13 12:52:34 5: KLF200Node (velux) - received: 021104050000c800f7fff7fff7fff7ff0000b7f10000
2019.07.13 12:52:34 5: KLF200Node (velux_4) GW_NODE_STATE_POSITION_CHANGED_NTF 0211 4 5 MP:0 T:51200 FP1:63487 0 3086024704
2019.07.13 12:52:34 5: KLF200Node (velux_4) BulkUpdateMain MP:0 T:51200 R:0 Done
2019.07.13 12:52:34 5: Starting notify loop for velux_4, 1 event(s), first is execution: stop
2019.07.13 12:52:34 5: Power: not on any display, ignoring notify
2019.07.13 12:52:34 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:34 5: End notify loop for velux_4
2019.07.13 12:52:34 5: KLF200 (velux) - received: 0506
2019.07.13 12:52:34 1: KLF200 (velux) - unknown:  0506
2019.07.13 12:52:35 5: HttpUtils url=http://192.168.200.1/webservices/homeautoswitch.lua?sid=d3234a2062faff7b&switchcmd=getdevicelistinfos
2019.07.13 12:52:35 4: IP: proxy.dmz.net -> 192.168.2.100
2019.07.13 12:52:35 5: HttpUtils request header:
GET http://192.168.200.1/webservices/homeautoswitch.lua?sid=d3234a2062faff7b&switchcmd=getdevicelistinfos HTTP/1.0
Host: 192.168.200.1
User-Agent: fhem
Accept-Encoding: gzip,deflate

2019.07.13 12:52:35 4: http://192.168.200.1/webservices/homeautoswitch.lua?sid=d3234a2062faff7b&switchcmd=getdevicelistinfos: HTTP response code 200
2019.07.13 12:52:35 5: HttpUtils http://192.168.200.1/webservices/homeautoswitch.lua?sid=d3234a2062faff7b&switchcmd=getdevicelistinfos: Got data, length: 3251
2019.07.13 12:52:35 5: HttpUtils response header:
HTTP/1.1 200 OK
Date: Sat, 13 Jul 2019 10:52:35 GMT
Content-Type: text/xml; charset=utf-8
X-Cache: MISS from vdsl.dmz.net
X-Cache-Lookup: MISS from vdsl.dmz.net:3128
Connection: close
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0365913" id="19" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Technikraum/Rack</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>101990</power><energy>1752590</energy></powermeter><temperature><celsius>280</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Technikraum_Rack
2019.07.13 12:52:35 5:    identifier = 08761 0365913
2019.07.13 12:52:35 5:    name = Technikraum/Rack
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    celsius = 280
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    id = 19
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    energy = 1752590
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5:    power = 101990
2019.07.13 12:52:35 5: Starting notify loop for Technikraum_Rack, 15 event(s), first is AIN: 08761 0365913
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Technikraum_Rack
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0364984" id="20" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Technikraum/Trockner</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>578727</energy></powermeter><temperature><celsius>285</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Technikraum_Trockner
2019.07.13 12:52:35 5:    celsius = 285
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    name = Technikraum/Trockner
2019.07.13 12:52:35 5:    identifier = 08761 0364984
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5:    id = 20
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    energy = 578727
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    power = 0
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5: Starting notify loop for Technikraum_Trockner, 15 event(s), first is temperature: 28.5 C (measured)
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Technikraum_Trockner
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0364979" id="21" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Technikraum/Waschmaschine</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>237907</energy></powermeter><temperature><celsius>285</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Technikraum_Waschmaschine
2019.07.13 12:52:35 5:    celsius = 285
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    name = Technikraum/Waschmaschine
2019.07.13 12:52:35 5:    identifier = 08761 0364979
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5:    id = 21
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    energy = 237907
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    power = 0
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5: Starting notify loop for Technikraum_Waschmaschine, 15 event(s), first is temperature: 28.5 C (measured)
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Technikraum_Waschmaschine
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0379021" id="22" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Arbeitszimmer</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>147480</power><energy>1581789</energy></powermeter><temperature><celsius>235</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Arbeitszimmer
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    id = 22
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    celsius = 235
2019.07.13 12:52:35 5:    identifier = 08761 0379021
2019.07.13 12:52:35 5:    name = Arbeitszimmer
2019.07.13 12:52:35 5:    power = 147480
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    energy = 1581789
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5: Starting notify loop for Arbeitszimmer, 15 event(s), first is present: yes
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Arbeitszimmer
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0379025" id="23" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Wohnzimmer</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>8360</power><energy>775765</energy></powermeter><temperature><celsius>215</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Wohnzimmer
2019.07.13 12:52:35 5:    power = 8360
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    energy = 775765
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    id = 23
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    celsius = 215
2019.07.13 12:52:35 5:    identifier = 08761 0379025
2019.07.13 12:52:35 5:    name = Wohnzimmer
2019.07.13 12:52:35 5: Starting notify loop for Wohnzimmer, 15 event(s), first is power: 8.36 W
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Wohnzimmer
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0374415" id="24" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Kellerersatzraum(Neu)</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>793000</power><energy>17996989</energy></powermeter><temperature><celsius>270</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Kellerersatzraum
2019.07.13 12:52:35 5:    energy = 17996989
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5:    power = 793000
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    id = 24
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    name = Kellerersatzraum(Neu)
2019.07.13 12:52:35 5:    identifier = 08761 0374415
2019.07.13 12:52:35 5:    celsius = 270
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5: Starting notify loop for Kellerersatzraum, 15 event(s), first is energy: 17996989 Wh
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Kellerersatzraum
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="11657 0011584" id="25" functionbitmask="1280" fwversion="03.86" manufacturer="AVM" productname="FRITZ!DECT Repeater 100"><present>1</present><name>Repeater Wohnzimmer</name><temperature><celsius>235</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Wohnzimmer_Repeater
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    functionbitmask = 1280
2019.07.13 12:52:35 5:    fwversion = 03.86
2019.07.13 12:52:35 5:    productname = FRITZ!DECT Repeater 100
2019.07.13 12:52:35 5:    celsius = 235
2019.07.13 12:52:35 5:    name = Repeater Wohnzimmer
2019.07.13 12:52:35 5:    identifier = 11657 0011584
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    id = 25
2019.07.13 12:52:35 5: Starting notify loop for Wohnzimmer_Repeater, 9 event(s), first is tempadjust: 0.0 C
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Wohnzimmer_Repeater
2019.07.13 12:52:35 5: FRITZ200ET: dispatch <device identifier="08761 0510271" id="26" functionbitmask="2944" fwversion="03.87" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>Technikraum/Zirkulationspumpe</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>2280</power><energy>15220</energy></powermeter><temperature><celsius>285</celsius><offset>0</offset></temperature></device>
2019.07.13 12:52:35 5: Technikraum_Zirkulationspumpe
2019.07.13 12:52:35 5:    present = 1
2019.07.13 12:52:35 5:    id = 26
2019.07.13 12:52:35 5:    devicelock = 0
2019.07.13 12:52:35 5:    productname = FRITZ!DECT 200
2019.07.13 12:52:35 5:    lock = 0
2019.07.13 12:52:35 5:    celsius = 285
2019.07.13 12:52:35 5:    identifier = 08761 0510271
2019.07.13 12:52:35 5:    name = Technikraum/Zirkulationspumpe
2019.07.13 12:52:35 5:    power = 2280
2019.07.13 12:52:35 5:    functionbitmask = 2944
2019.07.13 12:52:35 5:    state = 1
2019.07.13 12:52:35 5:    fwversion = 03.87
2019.07.13 12:52:35 5:    energy = 15220
2019.07.13 12:52:35 5:    manufacturer = AVM
2019.07.13 12:52:35 5:    offset = 0
2019.07.13 12:52:35 5:    mode = manuell
2019.07.13 12:52:35 5: Starting notify loop for Technikraum_Zirkulationspumpe, 15 event(s), first is present: yes
2019.07.13 12:52:35 5: Power: not on any display, ignoring notify
2019.07.13 12:52:35 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:35 5: End notify loop for Technikraum_Zirkulationspumpe
2019.07.13 12:52:41 5: Cmd: >set velux_4 updateStatus<
2019.07.13 12:52:41 5: Starting notify loop for velux, 1 event(s), first is sessionID: 61444
2019.07.13 12:52:41 5: Power: not on any display, ignoring notify
2019.07.13 12:52:41 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:41 5: End notify loop for velux
2019.07.13 12:52:41 5: KLF200Node (velux_4) KLF200Node_GW_STATUS_REQUEST_REQ SessionID 61444 StatusType 3
2019.07.13 12:52:41 5: Starting notify loop for velux, 1 event(s), first is queueSize: 1
2019.07.13 12:52:41 5: Power: not on any display, ignoring notify
2019.07.13 12:52:41 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:41 5: End notify loop for velux
2019.07.13 12:52:41 5: KLF200 velux: unwrapped bytes     0305f004010400000000000000000000000000000000000000030000
2019.07.13 12:52:41 5: KLF200 velux: wrapped bytes c0001d0305f004010400000000000000000000000000000000000000030000e9c0
2019.07.13 12:52:41 5: SW: c0001d0305f004010400000000000000000000000000000000000000030000e9c0
2019.07.13 12:52:41 5: Starting notify loop for velux_4, 1 event(s), first is updateStatus
2019.07.13 12:52:41 5: Power: not on any display, ignoring notify
2019.07.13 12:52:41 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:41 5: End notify loop for velux_4
2019.07.13 12:52:41 5: KLF200 (velux) - received: 0306f00401
2019.07.13 12:52:41 5: KLF200 (velux) - ignored:  0306f00401
2019.07.13 12:52:41 5: KLF200 (velux) - received: 0307f0040104000103c8005200000e9dbd870008
2019.07.13 12:52:41 5: velux: dispatch \003\a�\004\001\004\000\001\003�\000R\000\000\016���\000\010
2019.07.13 12:52:41 5: KLF200Node (velux) - received: 0307f0040104000103c8005200000e9dbd870008
2019.07.13 12:52:41 5: KLF200Node (velux_4) GW_STATUS_REQUEST_NTF 0307 61444 1 4 0 1 3
2019.07.13 12:52:41 5: Starting notify loop for velux_4, 6 event(s), first is sessionID: 61444
2019.07.13 12:52:41 5: Power: not on any display, ignoring notify
2019.07.13 12:52:41 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:41 5: End notify loop for velux_4
2019.07.13 12:52:41 5: KLF200 (velux) - received: 0304f004
2019.07.13 12:52:41 5: KLF200 (velux) GW_SESSION_FINISHED_NTF 0304 61444
2019.07.13 12:52:41 5: KLF200 (velux) Dequeue: regex = (?^:^(\x04\x12|\x03\x05|\x03\x12|\x03\x10))
2019.07.13 12:52:41 5: KLF200 (velux) Dequeue: SessionID = 61444
2019.07.13 12:52:41 5: KLF200 (velux) Dequeue: 0305f004010400000000000000000000000000000000000000030000
2019.07.13 12:52:41 5: KLF200 (velux) Dequeue: mached
2019.07.13 12:52:41 5: Starting notify loop for velux, 1 event(s), first is queueSize: 0
2019.07.13 12:52:41 5: Power: not on any display, ignoring notify
2019.07.13 12:52:41 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:41 5: End notify loop for velux
2019.07.13 12:52:41 5: KLF200 (velux) - received: 021104045200c800f7fff7fff7fff7ff000eb7f90000
2019.07.13 12:52:41 5: velux: dispatch \002\021\004\004R\000�\000��������\000\016��\000\000
2019.07.13 12:52:41 5: KLF200Node (velux) - received: 021104045200c800f7fff7fff7fff7ff000eb7f90000
2019.07.13 12:52:41 5: KLF200Node (velux_4) GW_NODE_STATE_POSITION_CHANGED_NTF 0211 4 4 MP:20992 T:51200 FP1:63487 14 3086548992
2019.07.13 12:52:41 5: KLF200Node (velux_4) BulkUpdateMain MP:20992 T:51200 R:14 Executing
2019.07.13 12:52:41 5: Starting notify loop for velux_4, 7 event(s), first is MP: 20992
2019.07.13 12:52:41 5: Power: not on any display, ignoring notify
2019.07.13 12:52:41 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:41 5: End notify loop for velux_4
2019.07.13 12:52:43 5: PRESENCE (Feinstaubsensor) - stopping timer
2019.07.13 12:52:43 5: PRESENCE (Feinstaubsensor) - starting blocking call for mode lan-ping
2019.07.13 12:52:43 4: BlockingCall (PRESENCE_DoLocalPingScan): created child (8054), uses telnetForBlockingFn_1563014855 to connect back
2019.07.13 12:52:43 4: Connection accepted from telnetForBlockingFn_1563014855_127.0.0.1_40840
2019.07.13 12:52:43 5: Cmd: >{BlockingRegisterTelnet($cl,16)}<
2019.07.13 12:52:43 5: PRESENCE (Feinstaubsensor) - starting ping scan: Feinstaubsensor|192.168.100.107|0|4
2019.07.13 12:52:46 5: PRESENCE (Feinstaubsensor) - ping command returned with output:
PING 192.168.100.107 (192.168.100.107) 56(84) bytes of data.
64 bytes from 192.168.100.107: icmp_seq=1 ttl=255 time=20.0 ms
64 bytes from 192.168.100.107: icmp_seq=2 ttl=255 time=40.2 ms
64 bytes from 192.168.100.107: icmp_seq=3 ttl=255 time=5.09 ms
64 bytes from 192.168.100.107: icmp_seq=4 ttl=255 time=3.94 ms

--- 192.168.100.107 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 3.945/17.348/40.277/14.688 ms
2019.07.13 12:52:46 5: Cmd: >{BlockingStart('16')}<
2019.07.13 12:52:46 5: Cmd: >{PRESENCE_ProcessLocalScan('Feinstaubsensor|0|present')}<
2019.07.13 12:52:46 5: PRESENCE (Feinstaubsensor) - blocking scan result: Feinstaubsensor|0|present
2019.07.13 12:52:46 4: PRESENCE (Feinstaubsensor) - rescheduling next check in 30 seconds
2019.07.13 12:52:55 5: Cmd: >set velux_4 updateStatus<
2019.07.13 12:52:55 5: Starting notify loop for velux, 1 event(s), first is sessionID: 61445
2019.07.13 12:52:55 5: Power: not on any display, ignoring notify
2019.07.13 12:52:55 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:55 5: End notify loop for velux
2019.07.13 12:52:55 5: KLF200Node (velux_4) KLF200Node_GW_STATUS_REQUEST_REQ SessionID 61445 StatusType 3
2019.07.13 12:52:55 5: Starting notify loop for velux, 1 event(s), first is queueSize: 1
2019.07.13 12:52:55 5: Power: not on any display, ignoring notify
2019.07.13 12:52:55 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:55 5: End notify loop for velux
2019.07.13 12:52:55 5: KLF200 velux: unwrapped bytes     0305f005010400000000000000000000000000000000000000030000
2019.07.13 12:52:55 5: KLF200 velux: wrapped bytes c0001d0305f005010400000000000000000000000000000000000000030000e8c0
2019.07.13 12:52:55 5: SW: c0001d0305f005010400000000000000000000000000000000000000030000e8c0
2019.07.13 12:52:55 5: Starting notify loop for velux_4, 1 event(s), first is updateStatus
2019.07.13 12:52:55 5: Power: not on any display, ignoring notify
2019.07.13 12:52:55 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:55 5: End notify loop for velux_4
2019.07.13 12:52:55 5: KLF200 (velux) - received: 0306f00501
2019.07.13 12:52:55 5: KLF200 (velux) - ignored:  0306f00501
2019.07.13 12:52:55 5: KLF200 (velux) - received: 0307f0050104000103c800c80000009dbd870008
2019.07.13 12:52:55 5: velux: dispatch \003\a�\005\001\004\000\001\003�\000�\000\000\000���\000\010
2019.07.13 12:52:55 5: KLF200Node (velux) - received: 0307f0050104000103c800c80000009dbd870008
2019.07.13 12:52:55 5: KLF200Node (velux_4) GW_STATUS_REQUEST_NTF 0307 61445 1 4 0 1 3
2019.07.13 12:52:55 5: Starting notify loop for velux_4, 1 event(s), first is sessionID: 61445
2019.07.13 12:52:55 5: Power: not on any display, ignoring notify
2019.07.13 12:52:55 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:55 5: End notify loop for velux_4
2019.07.13 12:52:55 5: KLF200 (velux) - received: 0304f005
2019.07.13 12:52:55 5: KLF200 (velux) GW_SESSION_FINISHED_NTF 0304 61445
2019.07.13 12:52:55 5: KLF200 (velux) Dequeue: regex = (?^:^(\x04\x12|\x03\x05|\x03\x12|\x03\x10))
2019.07.13 12:52:55 5: KLF200 (velux) Dequeue: SessionID = 61445
2019.07.13 12:52:55 5: KLF200 (velux) Dequeue: 0305f005010400000000000000000000000000000000000000030000
2019.07.13 12:52:55 5: KLF200 (velux) Dequeue: mached
2019.07.13 12:52:55 5: Starting notify loop for velux, 1 event(s), first is queueSize: 0
2019.07.13 12:52:55 5: Power: not on any display, ignoring notify
2019.07.13 12:52:55 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:55 5: End notify loop for velux
2019.07.13 12:52:55 5: KLF200 (velux) - received: 02110405c800c800f7fff7fff7fff7ff0000b8070000
2019.07.13 12:52:55 5: velux: dispatch \002\021\004\005�\000�\000��������\000\000�\a\000\000
2019.07.13 12:52:55 5: KLF200Node (velux) - received: 02110405c800c800f7fff7fff7fff7ff0000b8070000
2019.07.13 12:52:55 5: KLF200Node (velux_4) GW_NODE_STATE_POSITION_CHANGED_NTF 0211 4 5 MP:51200 T:51200 FP1:63487 0 3087466496
2019.07.13 12:52:55 5: KLF200Node (velux_4) BulkUpdateMain MP:51200 T:51200 R:0 Done
2019.07.13 12:52:55 5: Starting notify loop for velux_4, 6 event(s), first is MP: 51200
2019.07.13 12:52:55 5: Power: not on any display, ignoring notify
2019.07.13 12:52:55 5: Temperatur: not on any display, ignoring notify
2019.07.13 12:52:55 5: End notify loop for velux_4

* IZYMO.log

Code Auswählen


2019-07-13_12:52:30 velux_4 updateStatus
2019-07-13_12:52:30 velux_4 sessionID: 61442
2019-07-13_12:52:30 velux_4 MP: 0
2019-07-13_12:52:30 velux_4 MPtarget: 0

2019-07-13_12:52:33 velux_4 pct 0
2019-07-13_12:52:33 velux_4 MPtarget: 51200
2019-07-13_12:52:33 velux_4 target: 0
2019-07-13_12:52:33 velux_4 execution: down
2019-07-13_12:52:33 velux_4 sessionID: 61443
2019-07-13_12:52:33 velux_4 sessionStatusOwner: PROGRAM
2019-07-13_12:52:33 velux_4 sessionInformationCode: 06000400
2019-07-13_12:52:33 velux_4 lastRunStatus: EXECUTION ACTIVE
2019-07-13_12:52:33 velux_4 lastMasterExecutionAddress: d523a4
2019-07-13_12:52:33 velux_4 lastControl: FHEM
2019-07-13_12:52:34 velux_4 sessionInformationCode: 0f000500
2019-07-13_12:52:34 velux_4 lastRunStatus: EXECUTION FAILED
2019-07-13_12:52:34 velux_4 lastStatusReply: REACHED WRONG POSITION
2019-07-13_12:52:34 velux_4 execution: stop

2019-07-13_12:52:41 velux_4 updateStatus
2019-07-13_12:52:41 velux_4 sessionID: 61444
2019-07-13_12:52:41 velux_4 sessionStatusOwner: USER
2019-07-13_12:52:41 velux_4 lastRunStatus: EXECUTION COMPLETED
2019-07-13_12:52:41 velux_4 lastStatusReply: COMMAND COMPLETED OK
2019-07-13_12:52:41 velux_4 lastMasterExecutionAddress: 9dbd87
2019-07-13_12:52:41 velux_4 lastControl: Stand Alone Automatic Controls
2019-07-13_12:52:41 velux_4 MP: 20992
2019-07-13_12:52:41 velux_4 pct: 59
2019-07-13_12:52:41 velux_4 59
2019-07-13_12:52:41 velux_4 execution: down
2019-07-13_12:52:41 velux_4 remaining: 14
2019-07-13_12:52:41 velux_4 targetArrival: 2019-07-13 12:52:55
2019-07-13_12:52:41 velux_4 operatingState: Executing

2019-07-13_12:52:55 velux_4 updateStatus
2019-07-13_12:52:55 velux_4 sessionID: 61445
2019-07-13_12:52:55 velux_4 MP: 51200
2019-07-13_12:52:55 velux_4 pct: 0
2019-07-13_12:52:55 velux_4 off
2019-07-13_12:52:55 velux_4 execution: stop
2019-07-13_12:52:55 velux_4 remaining: 0
2019-07-13_12:52:55 velux_4 operatingState: Done

Ich hoffe, das hilft Dir weiter.

Viele Grüße,
Guido.

buennerbernd · 14 Juli 2019, 11:22:49

Hallo Guido,

Danke für die Logs. Ist eine Menge Log zum Lesen.
Ich hoffe, ich komme die nächste Woche mal dazu, das auszuwerten und das Modul anzupassen.

Was ich im IZYMO.log sehe und was mich wundert ist, dass ich im Reading lastMasterExecutionAddress zwei unterschiedliche Adressen sehe. Welche Adresse kommt denn da bei dir, wenn du ein echtes Velux-Gerät mit FHEM bewegst?

Gruß, Stefan.

SparcWolf · 14 Juli 2019, 12:24:22

Hallo Stefan,

das SYSMON Modul ist leider sehr geschwätzig. Wenn Du das ausgeblendet hast sollte es übersichtlicher werden.

Habe mal das gleiche Szenario mit einer Velux CVP Kuppel durchgespielt:

Code Auswählen


2019-07-14_12:17:36 velux_0 off
2019-07-14_12:17:36 velux_0 MPtarget: 51200
2019-07-14_12:17:36 velux_0 target: 0
2019-07-14_12:17:36 velux_0 execution: down
2019-07-14_12:17:36 velux_0 sessionID: 7490
2019-07-14_12:17:36 velux_0 sessionStatusOwner: PROGRAM
2019-07-14_12:17:36 velux_0 sessionInformationCode: 06000300
2019-07-14_12:17:36 velux_0 lastRunStatus: EXECUTION ACTIVE
2019-07-14_12:17:36 velux_0 lastStatusReply: NODE WAITING FOR POWER
2019-07-14_12:17:36 velux_0 lastMasterExecutionAddress: d523a4
2019-07-14_12:17:36 velux_0 lastControl: FHEM
2019-07-14_12:17:36 velux_0 lastCommandOriginator: SAAC
2019-07-14_12:17:36 velux_0 execution: stop
2019-07-14_12:17:36 velux_0 operatingState: Waiting for power
2019-07-14_12:17:36 velux_0 remaining: 30
2019-07-14_12:17:36 velux_0 execution: down
2019-07-14_12:17:36 velux_0 targetArrival: 2019-07-14 12:18:06
2019-07-14_12:17:36 velux_0 operatingState: Executing

2019-07-14_12:17:47 velux_0 updateStatus
2019-07-14_12:17:47 velux_0 sessionInformationCode: 10000400
2019-07-14_12:17:47 velux_0 lastRunStatus: EXECUTION COMPLETED
2019-07-14_12:17:47 velux_0 lastStatusReply: COMMAND OVERRULED
2019-07-14_12:17:47 velux_0 sessionID: 7495
2019-07-14_12:17:47 velux_0 sessionStatusOwner: USER
2019-07-14_12:17:47 velux_0 lastStatusReply: COMMAND COMPLETED OK
2019-07-14_12:17:47 velux_0 lastMasterExecutionAddress: 9dbd87
2019-07-14_12:17:47 velux_0 lastControl: Stand Alone Automatic Controls
2019-07-14_12:17:47 velux_0 MP: 21388
2019-07-14_12:17:47 velux_0 pct: 58
2019-07-14_12:17:47 velux_0 58
2019-07-14_12:17:47 velux_0 remaining: 18
2019-07-14_12:17:47 velux_0 targetArrival: 2019-07-14 12:18:05

2019-07-14_12:18:15 velux_0 updateStatus
2019-07-14_12:18:15 velux_0 sessionID: 7502
2019-07-14_12:18:15 velux_0 MP: 51200
2019-07-14_12:18:15 velux_0 pct: 0
2019-07-14_12:18:15 velux_0 off
2019-07-14_12:18:15 velux_0 execution: stop
2019-07-14_12:18:15 velux_0 remaining: 0
2019-07-14_12:18:15 velux_0 operatingState: Done

Grüße,
Guido.

buennerbernd · 18 Juli 2019, 10:07:12

Zitat von: SparcWolf am 14 Juli 2019, 12:24:22
Hallo Stefan,

das SYSMON Modul ist leider sehr geschwätzig. Wenn Du das ausgeblendet hast sollte es übersichtlicher werden.

Habe mal das gleiche Szenario mit einer Velux CVP Kuppel durchgespielt:
...

Grüße,
Guido.

Hallo Guido,

Ich glaube, in deinem KLF200 Device namens velux ist ein falscher Wert im Reading address hinterlegt.
Ich vermute, dort steht d523a4 und der richtige Wert wäre 9dbd87.
Evtl. ist da was bei der Initialisierung schiefgegangen oder du hast die Box noch einmal aufgesetzt oder oder oder.

Dadurch stimmen bei dir die Readings lastControl und lastMasterExecutionAddress manchmal nicht.
Wenn dir das wichtig ist, dann mache mal folgendes:

Code Auswählen


deletereading velux address
deleteattr velux controlNames

Danach bewege mal dein Velux-Gerät aus FHEM heraus. Dann müssten sich diese Werte richtig vervollständigen.
Falls du im Attribut controlNames schon selbst Namen eingetragen hattest, dann musst du das noch einmal wiederholen.

Das alles hat aber nichts damit zu tun, warum die IZYMOs sich anders verhalten. Ich arbeite gerade an einem Update. Ich habe schon herausgefunden, warum sich der Status zur targetArrival Zeit unter manchen Umständen nicht aktualisiert. Das Update wird also für alle eine kleine Verbesserung sein.

Gruß, Stefan.

SparcWolf · 18 Juli 2019, 18:45:37

Hallo Stefan,

Danke für den Hinweis.
Ja, ich habe das KLF200 Device mehrfach zurückgesetzt.

Die Kommandos habe ich ausgeführt und die Adresse ist auch wie von Dir vorhergesagt.
Nur die Controlnames sind noch nicht alle wieder da. Manuell eingetragen habe ich eigentlich keine.

Code Auswählen


Internals:
   CHANGED    
   DEF        velux
   DeviceName velux:51200
   FD         4
   FUUID      5c7a6e03-f33f-9b8e-5925-294937e8549bc87f
   Host       velux
   NAME       velux
   NR         73
   PARTIAL    
   SCENES     
   SSL        1
   STATE      Logged in
   TIMEOUT    10
   TYPE       KLF200
   OLDREADINGS:
   READINGS:
     2019-07-18 18:28:57   address         9dbd87
     2019-06-29 17:29:21   connectionBroken 0
     2019-07-17 20:34:15   connectionsAfterBoot 1
     2019-03-02 12:50:32   hardwareVersion 6
     2019-07-13 12:49:44   lastError       
     2019-03-02 12:50:32   model           0.2.0.0.71.0
     2019-07-18 18:30:58   queueSize       0
     2019-03-23 11:50:27   scene           "Alle Kuppeln schliessen"
     2019-03-23 11:50:27   sceneID         5
     2019-03-23 11:50:27   sceneSessionID  10328
     2019-03-23 11:50:27   sceneStatus     OK - Request accepted
     2019-07-18 18:30:57   sessionID       59105
     2019-03-02 12:50:32   softwareVersion 0.2.0.0.71.0
     2019-07-17 20:34:15   state           Logged in
     2019-07-17 20:33:13   subState        Performing task in Command Handler
Attributes:
   controlNames 9dbd87-1:KLF200 Input,9dbd87-8:FHEM
   room       11_Homecontrol
   verbose    5
   webCmd     scene

Gruß,
Guido.

SparcWolf · 18 Juli 2019, 19:09:10

Hallo Stefan,

ist es bei Dir schon mal vorgekommen, dass die Queue vom KLF200 nicht abgearbeitet wurde?

Bei mir ist das in den letzten Tagen ein paar mal aufgetreten.
Die Queue hatte dann 1000 oder mehr Einträge. Das sich das so stark aufstaut liegt sicher auch an meinen DoIFs, die 4 IZYMOS alle 30s abfragen.
Leider konnte ich den Auslöser noch nicht identifizieren (vielleicht Verbindungsprobleme mit einem Device).
Abhilfe hat nur ein FHEM-Neustart gebracht.

Viele Grüße,
Guido.