Hi,
Ich hatte heute früh den Fall dass der Ack von einem Aktor nicht bei fhem agekommen ist und in den Internals "protEvt_AESerrReject 1 last_at:2016-04-28 06:00:04" auftauchte, sowie das device auf set_on hängengeblieben ist (über Stunden).
Sollte in so einem Fall CUL_HM nicht den Befehl erneut absetzen, oder zumindest ein statusRequest hinterherschicken? ( https://forum.fhem.de/index.php/topic,14286.msg95218.html#msg95218 )
Nachdem ich statusRequest manuell ausgeführt habe wurde der korrekte Zustand gemeldet.
Kann ich mithilfe von CUL_HM verhindern dass bei einem AES-Fehler evtl. mein Aktor nicht schaltet, oder weitere Bedingungen nicht ausgeführt werden weil der FHEM Status nicht aktualisiert wurde und auf set_XX hängt?
Oder muss ich das selber über watchdog oder ähnliches lösen :-\?
RSSI zum bevorzugten HMLAN: avg:-62.54 max:-59 lst:-63 min:-65
RSSI zum nächsten HMLAN: max:-63 avg:-63.97 lst:-64 min:-67
msgRepeat = 10
R-transmitTryMax = 6
Gruß
Claudiu
Hallo,
Dieses Verhalten beobachte ich seit ein paar Tagen auch bei meinen Aktoren seit ich AES aktiviert habe. Der set_... Befehl bleibt (ewig) hängen. Nach einem manuellen statusRequest wird der Befehl ausgeführt.
z.B.:
2016-05-12_06:00:00 WohnzimmerRolladenLinks set_on
2016-05-12_06:00:01 WohnzimmerRolladenLinks aesCommToDev: pending
2016-05-12_06:00:01 WohnzimmerRolladenLinks aesKeyNbr: 02
2016-05-12_06:00:01 WohnzimmerRolladenLinks aesCommToDev: pending
2016-05-12_06:00:01 WohnzimmerRolladenLinks aesKeyNbr: 02
2016-05-12_06:00:01 WohnzimmerRolladenLinks aesCommToDev: pending
2016-05-12_06:00:01 WohnzimmerRolladenLinks aesKeyNbr: 02
2016-05-12_06:00:02 WohnzimmerRolladenLinks aesKeyNbr: 02
Nach manuellem statusRequest:
2016-05-12_06:24:47 WohnzimmerRolladenLinks deviceMsg: off (to VCCU)
2016-05-12_06:24:47 WohnzimmerRolladenLinks level: 0
2016-05-12_06:24:47 WohnzimmerRolladenLinks motor: stop:off
2016-05-12_06:24:47 WohnzimmerRolladenLinks pct: 0
2016-05-12_06:24:47 WohnzimmerRolladenLinks off
2016-05-12_06:24:47 WohnzimmerRolladenLinks timedOn: off
2016-05-12_06:24:48 WohnzimmerRolladenLinks aesCommToDev: pending
2016-05-12_06:24:48 WohnzimmerRolladenLinks aesKeyNbr: 02
2016-05-12_06:24:48 WohnzimmerRolladenLinks aesCommToDev: pending
2016-05-12_06:24:48 WohnzimmerRolladenLinks aesKeyNbr: 02
2016-05-12_06:24:48 WohnzimmerRolladenLinks aesCommToDev: ok
2016-05-12_06:24:48 WohnzimmerRolladenLinks deviceMsg: off (to VCCU)
2016-05-12_06:24:48 WohnzimmerRolladenLinks level: 0
2016-05-12_06:24:48 WohnzimmerRolladenLinks motor: up:off
2016-05-12_06:24:48 WohnzimmerRolladenLinks pct: 0
2016-05-12_06:24:48 WohnzimmerRolladenLinks off
2016-05-12_06:24:48 WohnzimmerRolladenLinks timedOn: off
2016-05-12_06:25:20 WohnzimmerRolladenLinks deviceMsg: on (to VCCU)
2016-05-12_06:25:20 WohnzimmerRolladenLinks level: 100
2016-05-12_06:25:20 WohnzimmerRolladenLinks motor: stop:on
2016-05-12_06:25:20 WohnzimmerRolladenLinks pct: 100
2016-05-12_06:25:20 WohnzimmerRolladenLinks on
2016-05-12_06:25:20 WohnzimmerRolladenLinks timedOn: off
Die zugehörigen Homematic Nachrichten:
2016.05.12 06:00:01.011 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C056E0F d:FF r:FFD3 m:05 A011 1F2B4A 20B182 0201C80000
2016.05.12 06:00:01.203 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C056ED7 d:FF r:FFD3 m:05 A011 1F2B4A 20B182 0201C80000
2016.05.12 06:00:01.267 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C056F03 d:FF r:FFB1 m:05 E011 1F2B4A 20B182 0201C80000
2016.05.12 06:00:01.331 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C056F56 d:FF r:FFAD m:05 A002 20B182 1F2B4A 04B1E72299CB9D02
2016.05.12 06:00:01.395 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C056F83 d:FF r:FFAF m:05 E002 20B182 1F2B4A 04B1E72299CB9D02
2016.05.12 06:00:01.875 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C057162 d:FF r:FFD3 m:05 A002 20B182 1F2B4A 04B1E72299CB9D02
2016.05.12 06:00:01.907 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C05718F d:FF r:FFB1 m:05 E002 20B182 1F2B4A 04B1E72299CB9D02
Dann nach manuellem statusRequest:
2016.05.12 06:24:47.882 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C1E24 d:FF r:FFD3 m:06 A001 1F2B4A 20B182 010E
2016.05.12 06:24:47.914 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C1E50 d:FF r:FFB1 m:06 E001 1F2B4A 20B182 010E
2016.05.12 06:24:48.010 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C1EA3 d:FF r:FFAD m:06 A410 20B182 1F2B4A 060100004F
2016.05.12 06:24:48.042 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C1ECF d:FF r:FFB1 m:06 E410 20B182 1F2B4A 060100004F
2016.05.12 06:24:48.074 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C1EE9 d:FF r:FFD3 m:06 8002 1F2B4A 20B182 00
2016.05.12 06:24:48.138 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C1F1B d:FF r:FFD3 m:06 8002 1F2B4A 20B182 00
2016.05.12 06:24:48.394 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C2029 d:FF r:FFD3 m:07 A011 1F2B4A 20B182 0201C80000
2016.05.12 06:24:48.458 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C2056 d:FF r:FFB1 m:07 E011 1F2B4A 20B182 0201C80000
2016.05.12 06:24:48.522 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C20A8 d:FF r:FFAD m:07 A002 20B182 1F2B4A 0495288B659F5B02
2016.05.12 06:24:48.586 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C20D5 d:FF r:FFB1 m:07 E002 20B182 1F2B4A 0495288B659F5B02
2016.05.12 06:24:48.714 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C2171 d:FF r:FFAC m:07 8002 20B182 1F2B4A 010100104E909042E3
2016.05.12 06:24:48.778 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C219D d:FF r:FFB2 m:07 C002 20B182 1F2B4A 010100104E909042E3
2016.05.12 06:25:20.109 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C9C15 d:FF r:FFAD m:08 A410 20B182 1F2B4A 0601C800
2016.05.12 06:25:20.173 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1C9C42 d:FF r:FFB1 m:08 E410 20B182 1F2B4A 0601C800
2016.05.12 06:25:20.205 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1C9C5B d:FF r:FFD3 m:08 8002 1F2B4A 20B182 00
2016.05.12 06:25:24.558 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1CAD5A d:FF r:FFAD m:08 A410 20B182 1F2B4A 0601C800
2016.05.12 06:25:24.590 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1CAD86 d:FF r:FFB0 m:08 E410 20B182 1F2B4A 0601C800
2016.05.12 06:25:24.622 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1CAD9F d:FF r:FFD3 m:08 8002 1F2B4A 20B182 00
2016.05.12 06:25:29.230 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1CBFB6 d:FF r:FFAB m:08 A410 20B182 1F2B4A 0601C800
2016.05.12 06:25:29.294 0: HMLAN_Parse: HMLAN1 R:E20B182 stat:0000 t:1C1CBFE2 d:FF r:FFB0 m:08 E410 20B182 1F2B4A 0601C800
2016.05.12 06:25:29.326 0: HMLAN_Parse: HMLAN1 R:E1F2B4A stat:0000 t:1C1CBFFC d:FF r:FFD3 m:08 8002 1F2B4A 20B182 00
In den Device Internals steht:
protEvt_AESerrReject 1 last_at:2016-05-12 06:00:02
Wo könnte das Problem liegen?
Kann ich noch etwas ausprobieren / aufzeichnen, um das Problem einzugrenzen?
Das Problem tritt nicht bei jedem Befehl auf, eher gelegentlich und auf sämtliche Aktoren verteilt.
Vielen Dank im Voraus für Hinweise!
Gruß,
David
Ja, das Problem ist ziemlich blöd wenn man per notify oder doif auf den state reagieren will.
Das Problem tritt bei mir auch mindestens bei einem AES-Aktor pro Tag auf, allerdings wie bei dir sporadisch, nicht schön :-(
Hab schon überlegt auf den set_ state zu reagieren und automatisch ein statusRequest nach einem Timeout abzuschicken.
Gruß
Claudiu
Um etwas präziser zu sein: das Kommando wurde ausgeführt. Der state wurde in fhem nicht aktualisiert. Korrekt?
Es erleichtert viel, hier präzise zu sein.
Im Log sehe ich, dass kein ACK kommt. Fhem weiss also nicht, ob geschaltet wurde.
Sehen ich es richtig, daß ein Repeater im Spiel ist?
Klar bleibt fhem ewig hängen. Warum sollte es sich aendern?
Nun, was koennte fhem tun. Wenn ein ACK nicht kommt kann man ein status request nach schieben. Aber wenn das device nicht antwortet kann es auch tot sein. Es beschweren sich immer wieder Leute denen zu viele messages automatisch geschickt werden.
Nun, man koennte alles was set_ hat prüfen. Das muss verschwinden. Ich werde einmal nachdenken.
Zitat von: martinp876 am 12 Mai 2016, 21:25:03
Um etwas präziser zu sein: das Kommando wurde ausgeführt. Der state wurde in fhem nicht aktualisiert. Korrekt?
Yes, ganz genau :-)
So wie ich das beobachten konnte waren alle Aktoren die auf set_ hingen tortzdem immer im korrekten Schaltzustand! (~ 5 Beobachtungen)
Zitat von: martinp876 am 12 Mai 2016, 21:25:03
Sehen ich es richtig, daß ein Repeater im Spiel ist?
Bei mir zumindest nicht, nur 3 HMLAN's über vccu.
Zitat von: martinp876 am 12 Mai 2016, 21:25:03
Nun, was koennte fhem tun. Wenn ein ACK nicht kommt kann man ein status request nach schieben. Aber wenn das device nicht antwortet kann es auch tot sein. Es beschweren sich immer wieder Leute denen zu viele messages automatisch geschickt werden.
Nun, man koennte alles was set_ hat prüfen. Das muss verschwinden. Ich werde einmal nachdenken.
Ich denke allerdings will trotzdem niemand einen "undefinierten" Zustand.
Vor allem vermute ich das msgRepeat in so einem Fall ja keine Wirkung zeigt oder?
d.h. es müsste in so einem Fall eigtl. erstmal ein statusRequest hinterher und evtl. noch falls der Aktor das Kommando nicht richtig umgesetzt hat auch noch entsprechend msgRepeat erneut gesendet werden?
Gruß
Claudiu
Die automatismen sind mit Vorsicht einzusetzen . Kommandos kann man viele schicken. Auch verzögert. Es können Taster dazwischen kommen...
Noch mal schicken ist kritisch. Machbar scheint es einen statusrequest abzusetzen
Hallo,
Zitat von: martinp876 am 12 Mai 2016, 21:25:03
Um etwas präziser zu sein: das Kommando wurde ausgeführt. Der state wurde in fhem nicht aktualisiert. Korrekt?
Es erleichtert viel, hier präzise zu sein.
Bei mir (WohnzimmerRolladenLinks) wurde das set_on Kommando (um 6:00 Uhr) nicht ausgeführt, der Rollladen blieb unten.
Erst bei der Ausführung des manuellen statusRequest (um 6:24 Uhr) wurde auch das set_on ausgeführt und der Rollladen fuhr hoch.
Zitat von: martinp876 am 12 Mai 2016, 21:25:03
Sehen ich es richtig, daß ein Repeater im Spiel ist?
Ja, um den Aktor WohnzimmerRolladenLinks zuverlässig zu erreichen, setze ich einen Repeater ein, da die direkte Verbindung zum HMLAN unzuverlässig war. (Neubau - 2 Stahlbetondecken :( )
Zitat von: martinp876 am 12 Mai 2016, 21:25:03
Nun, was koennte fhem tun. Wenn ein ACK nicht kommt kann man ein status request nach schieben. Aber wenn das device nicht antwortet kann es auch tot sein. Es beschweren sich immer wieder Leute denen zu viele messages automatisch geschickt werden.
Nun, man koennte alles was set_ hat prüfen. Das muss verschwinden. Ich werde einmal nachdenken.
Nur zu meinem Verständnis:
Ist es richtig, dass fhem lediglich das set_on Kommando absetzt und der HMLAN dann autark den AES Schlüsselaustausch beginnt? Da dieser dann nicht erfolgreich durchgeführt wird (aesCommToDev: pending) bleibt der Zustand set_on bei fhem stehen? Im erfolgreichen Fall wird "aesCommToDev: ok" erreicht und der Aktor führt den Befehl aus?
Liesse sich in fhem erkennen, dass "aesCommToDev: ok" nach einer gewissen Zeit nicht erreicht wird, so dass dann ein statusRequest gesendet würde?
Gruß,
David
Das ist ein ganz anderer Fall. Hier brauche ich ein list vor dem statusrequest.
Das Set hat nichts direkt mit AES zu tun. Nach dem senden kommt ein ACK mit status. Darauf wird das Set entfernt. Ist AES im Spiel wird dies vom aktor initiiert und vom hmlan beantwortet. Klappt es nicht kommt kein ACK. Dann bleibt set stehen.
Wenn aber Kommandos pending stehen ist dies ein anderes problem
Zitat von: martinp876 am 12 Mai 2016, 22:05:41
Die automatismen sind mit Vorsicht einzusetzen . Kommandos kann man viele schicken. Auch verzögert. Es können Taster dazwischen kommen...
Noch mal schicken ist kritisch. Machbar scheint es einen statusrequest abzusetzen
Allerdings sollte doch zumindest msgRepeat versucht werden durchzusetzen, auch bei AES, oder?
Hatte das in den letzten Tagen häufiger dass nach dem ersten fehlgeschlagenen AES-Command nichtsmehr passierte.
Auch bei mehreren Aktoren gleichzeitig (welche gleichzeitig einen Command von einen notify o.ä. erhalten).
Habe nun erstmal bei allen Aktoren AES deaktiviert, da fhem eigentlich nichts mit Roulette zutun hat :-)
Nun wird zumindest wieder zuverlässig geschalten.
Gruß
Claudiu
Msgrepeat ist erst einmal dafür gedacht verlorengegangene messages zu wiederholen. Also wenn kein ACK kommt. Kommt ein Nack hat das device die Nachricht empfangen, verarbeitet diese aber nicht. Warum soll man wiederholen? Macht keinen Sinn, kostet Zeit und belastet den Funk. Wenn man AES sendet und die Antwort ist inkorrekt dann wird auch wiederholen nichts bringen. Auch hier gilt: sollte keine Antwort kommen wiederholen. Kommt "Fehler" wird gestoppt. AES ist falsch wenn der Key nicht passt. Da nützt wiederholen nix.
Ist also die Frage ob eine message ausbleibt und fhem nicht wiederholt. Das wäre ein Fehler.
Hast du logs hierzu?
Zitat von: martinp876 am 31 Mai 2016, 20:43:39
Hast du logs hierzu?
Habe hier noch ein momentan nicht verwendetes Gerät am Tisch liegen, werde die Tage versuchen es damit zu loggen.
Hallo,
Ich habe das Schaltverhalten mit AES nun ein paar Wochen bei mir beobachtet: insbesondere beim gleichzeitigen Schalten von mehr als zwei Aktoren wird der Schaltbefehl nicht mehr ausgeführt und der state bleibt bei set_ "hängen". Mit Wartezeiten läßt sich das Verhalten entspannen.
Beispiel: das gleichzeitige Schalten von 5 Aktoren (bei mir 5 Gartenlampen)
fhem("set Lampe1 on");
fhem("set Lampe2 on");
fhem("set Lampe3 on");
fhem("set Lampe4 on");
fhem("set Lampe5 on");
Ohne AES kein Problem, alle Lampen werden geschaltet.
Mit AES bleiben Lampe3 - Lampe5 bei set_on "hängen" und werden nicht geschaltet. Ein manueller StatusRequest bei Lampe3-Lampe5 führt zum Schalten.
Verbesserung des Schaltverhaltens:
fhem("sleep 1;set Lampe1 on;");
fhem("sleep 2;set Lampe2 on;");
fhem("sleep 3;set Lampe3 on;");
fhem("sleep 4;set Lampe4 on;");
fhem("sleep 5;set Lampe5 on;");
Nun werden die Lampen nacheinander geschaltet.
Bei AES dauert die Funkübertragung "länger", ist wird ja auch mehr übertragen. Ist bei AES grundsätzlich ein zeitlicher Abstand zwischen Schaltkommandos einzuhalten? Wer ist bei Hintereinanderausführung von Schaltbefehlen für die Abwicklung der ordnungsgemäßen Übertragung zuständig? FHEM? HMLAN?
Vielen Dank für Hinweise!
Gruß,
David
Hallo
Ich möchhte diesen Thread nochmal aufwärmen.
Ich beobachte bei mir das selbe Verhalten seitdem ich AES aktiviert habe.
Sporadisch wird ein kommando nicht ausgeführt und im Aktor finde ich dann ein
protEvt_AESerrReject 1 last_at:2016-10-12 06:30:01
Gibt es Neuigkeiten oder Tipps dazu?
Gruß
Daniel
Hallo zusammen!
Ich habe bei mir (vermeintlich) das selbe Problem beim gleichzeitigen Schalten mehrerer Rollläden. Beschrieben hatte ich das ursprünglich hier: https://forum.fhem.de/index.php/topic,58009.0.html (https://forum.fhem.de/index.php/topic,58009.0.html)
Zur Info: Ursprünglich habe ich culfw 1.66 genutzt, ich habe keinen Repeater und nutze eine VCCU. Das Fehlerbild variiert leicht, manchmal bleibt der Aktor zuerst auf "set_xxx" und geht dann irgendwann auf Missing ACK, manchmal bleiben auch die Events in der Queue hängen und ich werde sie nur durch ein Clear los.
Aktuell diskutiere ich das Thema hier mit den Machern der HM-optimierten culfw: https://forum.fhem.de/index.php/topic,24436.msg500594.html#msg500594 (https://forum.fhem.de/index.php/topic,24436.msg500594.html#msg500594)
Das Problem tritt auch mit dieser FW und den zugehörigen FHEM Modulen auf.
Viele Grüße,
weini
Habe jetzt bei mir nochmal entsprechende Logs gezogen. Es wurden 3 Rollläden gleichzeitig gestartet, davon hat einer reagiert, die beiden anderen nicht:
Logfile:
2016.10.16 10:26:07.042 3: CUL_HM set BrZi_ErkerLi_Rollo on
2016.10.16 10:26:07.045 4: CUL_send: nanoCULHomeMaticAs 0E 1C A011 D3AA78 4AD281 0201C80000
2016.10.16 10:26:07.122 4: CUL_send: nanoCULHomeMaticAs 0E 19 A011 D3AA78 4AD26B 0201C80000
2016.10.16 10:26:07.199 3: CUL_HM set BrZi_ErkerRe_Rollo on
2016.10.16 10:26:07.202 4: CUL_send: nanoCULHomeMaticAs 0E 19 A011 D3AA78 4AD28C 0201C80000
2016.10.16 10:26:07.224 4: CUL_Parse: nanoCULHomeMatic A 11 1C A002 4AD281 D3AA78 0445C90FFCFE600200 -74
2016.10.16 10:26:07.326 4: CUL_send: nanoCULHomeMaticAs 19 1C A003 D3AA78 4AD281 c990dfaeebe9d53c0e0083f41039a627
2016.10.16 10:26:07.486 4: CUL_Parse: nanoCULHomeMatic A 11 19 A002 4AD26B D3AA78 042CB09F22E8C20211 -65.5
2016.10.16 10:26:07.588 4: CUL_send: nanoCULHomeMaticAs 19 19 A003 D3AA78 4AD26B 0103f4882f9baec610b9e9e709d55edb
2016.10.16 10:26:11.884 4: CUL_HM_Resend: BrZi_ErkerRe_Rollo nr 2
2016.10.16 10:26:11.885 4: CUL_send: nanoCULHomeMaticAs 0E 19 A011 D3AA78 4AD28C 0201C80000
2016.10.16 10:26:12.063 4: CUL_Parse: nanoCULHomeMatic A 11 19 A002 4AD28C D3AA78 04D1C32CCA9D0F020D -67.5
2016.10.16 10:26:12.165 4: CUL_send: nanoCULHomeMaticAs 19 19 A003 D3AA78 4AD28C 1ce3221e84ca6eb89593ce47d1eec7f8
2016.10.16 10:26:12.481 4: CUL_Parse: nanoCULHomeMatic A 12 19 8002 4AD28C D3AA78 01010010434BEF773211 -65.5
2016.10.16 10:26:45.029 4: CUL_Parse: nanoCULHomeMatic A 0D 1A A410 4AD28C D3AA78 0601C8000E -67
2016.10.16 10:26:45.131 4: CUL_send: nanoCULHomeMaticAs 0A 1A 8002 D3AA78 4AD28C 00
List BrZi_ErkerRe_Rollo (hat funktioniert)
Internals:
DEF 4AD28C
IODev nanoCULHomeMatic
LASTInputDev nanoCULHomeMatic
MSGCNT 24
NAME BrZi_ErkerRe_Rollo
NOTIFYDEV global
NR 292
NTFY_ORDER 50-BrZi_ErkerRe_Rollo
STATE auf
TYPE CUL_HM
lastMsg No:1A - t:10 s:4AD28C d:D3AA78 0601C800
nanoCULHomeMatic_MSGCNT 24
nanoCULHomeMatic_RAWMSG A0D1AA4104AD28CD3AA780601C800::-67:nanoCULHomeMatic
nanoCULHomeMatic_RSSI -67
nanoCULHomeMatic_TIME 2016-10-16 10:26:45
protLastRcv 2016-10-16 10:26:45
protResnd 5 last_at:2016-10-16 10:26:11
protSnd 25 last_at:2016-10-16 10:26:45
protState CMDs_done
rssi_at_nanoCULHomeMatic avg:-66.95 min:-74 max:-60.5 lst:-67 cnt:24
rssi_nanoCULHomeMatic avg:-68.66 min:-71 max:-67 lst:-67 cnt:6
Readings:
2016-10-16 10:26:12 CommandAccepted yes
2016-10-11 19:47:00 D-firmware 2.8
2016-10-11 19:47:00 D-serialNr NEQ0394501
2016-10-11 18:51:21 PairedTo 0xD3AA78
2016-10-11 18:51:34 R-driveDown 27 s
2016-10-11 18:51:34 R-driveTurn 0.5 s
2016-10-11 18:51:34 R-driveUp 28 s
2016-10-11 18:51:21 R-pairCentral 0xD3AA78
2016-10-11 18:51:34 R-sign on
2016-10-11 18:51:21 RegL_00. 02:01 0A:D3 0B:AA 0C:78 15:FF 18:00 00:00
2016-10-11 18:51:34 RegL_01. 08:01 09:00 0A:00 0B:01 0C:0E 0D:01 0E:18 0F:05 10:00 30:06 57:24 56:00 00:00
2016-10-16 10:26:12 aesCommToDev ok
2016-10-16 10:26:12 aesKeyNbr 02
2016-10-16 10:26:45 deviceMsg on (to hm_VCCU)
2016-10-16 10:26:45 level 100
2016-10-16 10:26:45 motor stop:on
2016-10-16 10:26:45 pct 100
2016-10-16 10:26:45 recentStateType info
2016-10-16 10:26:45 state on
2016-10-16 10:26:45 timedOn off
Helper:
HM_CMDNR 26
cSnd 11D3AA784AD28C0201000000,11D3AA784AD28C0201C80000
dlvlCmd ++A011D3AA784AD28C0201C80000
mId 006A
rxType 1
Dir:
cur stop
rct up
Expert:
def 1
det 0
raw 1
tpl 0
Io:
newChn +4AD28C,00,01,00
nextSend 1476606405.13027
rxt 0
vccu hm_VCCU
p:
4AD28C
00
01
00
prefIO:
nanoCULHomeMatic
Mrssi:
mNo 1A
Io:
nanoCULHomeMatic -65
Prt:
bErr 0
sProc 0
Rspwait:
Q:
qReqConf
qReqStat
Role:
chn 1
dev 1
prs 1
Rpt:
IO nanoCULHomeMatic
flg A
ts 1476606405.03697
ack:
HASH(0x415e758)
1A8002D3AA784AD28C00
Rssi:
At_nanoculhomematic:
avg -66.9583333333333
cnt 24
lst -67
max -60.5
min -74
Nanoculhomematic:
avg -68.6666666666667
cnt 6
lst -67
max -67
min -71
Shadowreg:
Tmpl:
Attributes:
IODev nanoCULHomeMatic
IOgrp hm_VCCU:nanoCULHomeMatic
alias Bar Rechts
autoReadReg 4_reqStatus
devStateIcon auf:fts_shutter_10:zu zu:fts_shutter_100:auf
eventMap on:auf off:zu
expert 2_raw
firmware 2.8
group Rollläden
isSleepingRoom 0
model HM-LC-Bl1PBU-FM
peerIDs 00000000,
room Barzimmer
serialNr NEQ0394501
subType blindActuator
userattr isSleepingRoom
verbose 4
webCmd auf:zu:stop:pct
List BrZi_ErkerMi_Rollo (hat nicht funktioniert)
Internals:
DEF 4AD26B
IODev nanoCULHomeMatic
LASTInputDev nanoCULHomeMatic
MSGCNT 23
NAME BrZi_ErkerMi_Rollo
NOTIFYDEV global
NR 291
NTFY_ORDER 50-BrZi_ErkerMi_Rollo
STATE set_on
TYPE CUL_HM
lastMsg No:19 - t:02 s:4AD26B d:D3AA78 042CB09F22E8C202
nanoCULHomeMatic_MSGCNT 23
nanoCULHomeMatic_RAWMSG A1119A0024AD26BD3AA78042CB09F22E8C202::-65.5:nanoCULHomeMatic
nanoCULHomeMatic_RSSI -65.5
nanoCULHomeMatic_TIME 2016-10-16 10:26:07
protLastRcv 2016-10-16 10:26:07
protSnd 26 last_at:2016-10-16 10:26:07
protState CMDs_processing...
rssi_at_nanoCULHomeMatic max:-63 lst:-65.5 avg:-66 min:-71.5 cnt:23
rssi_nanoCULHomeMatic avg:-70.59 min:-72 lst:-71 max:-68 cnt:5
Readings:
2016-10-16 10:13:27 CommandAccepted yes
2016-10-11 19:47:00 D-firmware 2.8
2016-10-11 19:47:00 D-serialNr NEQ0394531
2016-10-11 19:47:45 PairedTo 0xD3AA78
2016-10-11 19:47:47 R-driveDown 27 s
2016-10-11 19:47:47 R-driveTurn 0.5 s
2016-10-11 19:47:47 R-driveUp 28 s
2016-10-11 19:47:45 R-pairCentral 0xD3AA78
2016-10-11 19:47:47 R-sign on
2016-10-11 19:47:45 RegL_00. 02:01 0A:D3 0B:AA 0C:78 15:FF 18:00 00:00
2016-10-11 19:47:47 RegL_01. 08:01 09:00 0A:00 0B:01 0C:0E 0D:01 0E:18 0F:05 10:00 30:06 57:24 56:00 00:00
2016-10-16 10:13:27 aesCommToDev ok
2016-10-16 10:26:07 aesKeyNbr 02
2016-10-16 10:13:40 deviceMsg off (to hm_VCCU)
2016-10-16 10:13:40 level 0
2016-10-16 10:13:40 motor stop:off
2016-10-16 10:13:40 pct 0
2016-10-16 10:13:40 recentStateType info
2016-10-16 10:26:07 state set_on
2016-10-16 10:13:40 timedOn off
cmdStack:
Helper:
AESreqAck 3113A6E7
HM_CMDNR 25
cSnd 11D3AA784AD26B0201000000,11D3AA784AD26B0201C80000
dlvl C8
dlvlCmd ++A011D3AA784AD26B0201C80000
mId 006A
rxType 1
Dir:
cur stop
rct down
Expert:
def 1
det 0
raw 1
tpl 0
Io:
newChn +4AD26B,00,01,00
nextSend 1476606367.58742
rxt 0
vccu hm_VCCU
p:
4AD26B
00
01
00
prefIO:
nanoCULHomeMatic
Mrssi:
mNo 19
Io:
nanoCULHomeMatic -63.5
Prt:
bErr 0
sProc 1
Q:
qReqConf
qReqStat
Role:
chn 1
dev 1
prs 1
Rssi:
At_nanoculhomematic:
avg -66
cnt 23
lst -65.5
max -63
min -71.5
Nanoculhomematic:
avg -70.6
cnt 5
lst -71
max -68
min -72
Shadowreg:
Tmpl:
Attributes:
IODev nanoCULHomeMatic
IOgrp hm_VCCU:nanoCULHomeMatic
alias Bar Mitte
autoReadReg 4_reqStatus
devStateIcon auf:fts_shutter_10:zu zu:fts_shutter_100:auf
eventMap on:auf off:zu
expert 2_raw
firmware 2.8
group Rollläden
isSleepingRoom 0
model HM-LC-Bl1PBU-FM
peerIDs 00000000,
room Barzimmer
serialNr NEQ0394531
subType blindActuator
userattr isSleepingRoom
verbose 0
webCmd auf:zu:stop:pct
List BrZi_ErkerLi_Rollo (hat nicht funktioniert)
Internals:
DEF 4AD281
IODev nanoCULHomeMatic
LASTInputDev nanoCULHomeMatic
MSGCNT 23
NAME BrZi_ErkerLi_Rollo
NOTIFYDEV global
NR 290
NTFY_ORDER 50-BrZi_ErkerLi_Rollo
STATE set_on
TYPE CUL_HM
lastMsg No:1C - t:02 s:4AD281 d:D3AA78 0445C90FFCFE6002
nanoCULHomeMatic_MSGCNT 23
nanoCULHomeMatic_RAWMSG A111CA0024AD281D3AA780445C90FFCFE6002::-74:nanoCULHomeMatic
nanoCULHomeMatic_RSSI -74
nanoCULHomeMatic_TIME 2016-10-16 10:26:07
protLastRcv 2016-10-16 10:26:07
protResnd 4 last_at:2016-10-16 10:13:31
protSnd 25 last_at:2016-10-16 10:26:07
protState CMDs_processing...
rssi_at_nanoCULHomeMatic max:-65 lst:-74 avg:-71.95 min:-79.5 cnt:23
rssi_nanoCULHomeMatic cnt:5 avg:-70 min:-74 max:-66 lst:-74
Readings:
2016-10-16 10:13:31 CommandAccepted yes
2016-10-11 19:47:00 D-firmware 2.8
2016-10-11 19:47:00 D-serialNr NEQ0394497
2016-10-11 19:47:40 PairedTo 0xD3AA78
2016-10-11 19:47:41 R-driveDown 27 s
2016-10-11 19:47:41 R-driveTurn 0.5 s
2016-10-11 19:47:41 R-driveUp 28 s
2016-10-11 19:47:40 R-pairCentral 0xD3AA78
2016-10-11 19:47:41 R-sign on
2016-10-11 19:47:40 RegL_00. 02:01 0A:D3 0B:AA 0C:78 15:FF 18:00 00:00
2016-10-11 19:47:41 RegL_01. 08:01 09:00 0A:00 0B:01 0C:0E 0D:01 0E:18 0F:05 10:00 30:06 57:24 56:00 00:00
2016-10-16 10:13:31 aesCommToDev ok
2016-10-16 10:26:07 aesKeyNbr 02
2016-10-16 10:13:44 deviceMsg off (to hm_VCCU)
2016-10-16 10:13:44 level 0
2016-10-16 10:13:44 motor stop:off
2016-10-16 10:13:44 pct 0
2016-10-16 10:13:44 recentStateType info
2016-10-16 10:26:06 state set_on
2016-10-16 10:13:44 timedOn off
cmdStack:
Helper:
AESreqAck CFEA4B23
HM_CMDNR 28
cSnd 11D3AA784AD2810201000000,11D3AA784AD2810201C80000
dlvl C8
dlvlCmd ++A011D3AA784AD2810201C80000
mId 006A
rxType 1
Dir:
cur stop
rct down
Expert:
def 1
det 0
raw 1
tpl 0
Io:
newChn +4AD281,00,01,00
nextSend 1476606367.325
rxt 0
vccu hm_VCCU
p:
4AD281
00
01
00
prefIO:
nanoCULHomeMatic
Mrssi:
mNo 1C
Io:
nanoCULHomeMatic -72
Prt:
bErr 0
sProc 1
Q:
qReqConf
qReqStat
Role:
chn 1
dev 1
prs 1
Rssi:
At_nanoculhomematic:
avg -71.9565217391304
cnt 23
lst -74
max -65
min -79.5
Nanoculhomematic:
avg -70
cnt 5
lst -74
max -66
min -74
Shadowreg:
Tmpl:
Attributes:
IODev nanoCULHomeMatic
IOgrp hm_VCCU:nanoCULHomeMatic
alias Bar Links
autoReadReg 4_reqStatus
devStateIcon auf:fts_shutter_10:zu zu:fts_shutter_100:auf
eventMap on:auf off:zu
expert 2_raw
firmware 2.8
group Rollläden
isSleepingRoom 0
model HM-LC-Bl1PBU-FM
peerIDs 00000000,
room Barzimmer
serialNr NEQ0394497
subType blindActuator
userattr isSleepingRoom
verbose 4
webCmd auf:zu:stop:pct
List vom CUL
Internals:
CMDS BCFiAZEkGMKUYRTVWXefltx
Clients :CUL_HM:HMS:CUL_IR:STACKABLE_CC:
DEF /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A700CTOG-if00-port0@38400 0000
DeviceName /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A700CTOG-if00-port0@38400
FD 14
FHTID 0000
NAME nanoCULHomeMatic
NR 37
NR_CMD_LAST_H 65
PARTIAL
RAWMSG A0D1AA4104AD28CD3AA780601C8000E
RSSI -67
STATE Initialized
TYPE CUL
VERSION V 1.66 nanoCUL868
initString X21
Ar
nanoCULHomeMatic_MSGCNT 912
nanoCULHomeMatic_TIME 2016-10-16 10:26:45
owner_CCU hm_VCCU
Matchlist:
1:CUL_HM ^A....................
8:HMS ^810e04....(1|5|9).a001
D:CUL_IR ^I............
H:STACKABLE_CC ^\*
Readings:
2016-10-10 18:44:02 Xmit-Events Warning-HighLoad:1 init:2 disconnected:1 ok:1 ERROR-Overload:1
2016-09-09 13:30:35 ccconf freq:868.300MHz bWidth:101KHz rAmpl:33dB sens:8dB
2016-10-15 13:09:01 cmds B C F i A Z E k G M K U Y R T V W X e f l t x
2016-10-10 18:44:02 cond ok
2016-10-10 18:53:24 credit10ms 1014
2016-10-10 18:52:37 hmSioDly -1
2016-10-10 18:34:36 prot_ERROR-Overload last
2016-10-10 18:30:49 prot_Warning-HighLoad last
2016-10-10 18:30:16 prot_disconnected last
2016-10-10 18:30:17 prot_init last
2016-10-10 18:44:02 prot_ok last
2016-10-09 19:49:02 scF 0.999757851177839
2016-10-16 10:26:45 state Initialized
2016-05-18 08:13:12 uptime 0 07:00:34
XMIT_TIME:
1476604065.2135
1476604065.39399
1476604070.23392
1476604070.56891
1476604451.11886
1476604453.37031
1476604457.60107
1476604462.91244
1476604465.34327
1476604466.40287
1476604470.64859
1476604472.16634
1476604472.66194
1476604476.49539
1476604490.98707
1476604491.58799
1476604495.2843
1476604495.58201
1476605147.29007
1476605147.4839
1476605147.77515
1476605148.12069
1476605161.06095
1476605161.40968
1476605173.09374
1476605173.277
1476605176.77921
1476605177.07793
1476605188.33833
1476605188.52283
1476605195.95617
1476605199.79427
1476605200.09308
1476605209.27394
1476605209.45427
1476605210.29124
1476605317.26246
1476605337.41658
1476605607.01523
1476605607.075
1476605607.13528
1476605607.25975
1476605611.18593
1476605611.37094
1476605612.08786
1476605612.27313
1476605620.65453
1476605624.0014
1476605625.89423
1476606272.20385
1476606275.12399
1476606279.25047
1476606283.60287
1476606287.45358
1476606291.45649
1476606296.92099
1476606302.12983
1476606367.04456
1476606367.12226
1476606367.20175
1476606367.23387
1476606367.49538
1476606371.88522
1476606372.07201
1476606405.03794
Helper:
0a0301:
QUEUE:
0a0302:
QUEUE:
35f3dc:
QUEUE:
361e23:
QUEUE:
3f86f8:
QUEUE:
42250a:
QUEUE:
4569c9:
QUEUE:
4569d2:
QUEUE:
47b685:
QUEUE:
481c40:
QUEUE:
4ab1b6:
QUEUE:
4ad222:
QUEUE:
4ad26b:
QUEUE:
4ad279:
QUEUE:
4ad27a:
QUEUE:
4ad281:
QUEUE:
4ad28c:
QUEUE:
4ad2c0:
QUEUE:
4b29d6:
QUEUE:
4c1afa:
QUEUE:
Attributes:
addvaltrigger 1
devStateIcon Initialized:usb@green Open:usb@red
hmId D3AA78
model nanoCUL
rfmode HomeMatic
verbose 4
Hallo Testwillige,
in der hier https://forum.fhem.de/index.php/topic,24436.msg489979.html#msg489979 (https://forum.fhem.de/index.php/topic,24436.msg489979.html#msg489979) unter den Modulen in FHEM_module_changed_TS_5.zip zu findenden 10_CUL_HM.pm habe ich eine Korrektur gegen die Hänger der Sendewarteschlange bei aktivem AES eingebaut.
Das sollte auch ohne TS-Firmware gegen die Hänger helfen.
Vor Austausch der 10_CUL_HM.pm empfiehlt sich natürlich ein Backup der alten Datei.
Gruß, Ansgar.
Hallo Ansgar!
Habe die neue Version nochmal getestet (nur 10_CUL_HM.pm aus der v5, alles andere Standard, culfw 1.66). Läuft leider wieder überwiegend auf MISSING ACK (2 von 8 Rollläden sind gelaufen).
Hier das Log:
2016.10.19 21:46:27.107 3: CUL_HM set ArZi_Rollo on
2016.10.19 21:46:27.109 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4B29D6 0201C80000
2016.10.19 21:46:27.204 3: CUL_HM set BrZi_ErkerLi_Rollo on
2016.10.19 21:46:27.206 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD281 0201C80000
2016.10.19 21:46:27.303 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD26B 0201C80000
2016.10.19 21:46:27.398 3: CUL_HM set BrZi_ErkerRe_Rollo on
2016.10.19 21:46:27.400 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD28C 0201C80000
2016.10.19 21:46:27.495 3: CUL_HM set Kuch_Rollo on
2016.10.19 21:46:27.497 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD2C0 0201C80000
2016.10.19 21:46:27.592 3: CUL_HM set WoZi_ErkerLi_Rollo on
2016.10.19 21:46:27.594 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD27A 0201C80000
2016.10.19 21:46:27.689 3: CUL_HM set WoZi_ErkerMi_Rollo on
2016.10.19 21:46:27.691 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD222 0201C80000
2016.10.19 21:46:27.787 3: CUL_HM set WoZi_ErkerRe_Rollo on
2016.10.19 21:46:27.789 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD279 0201C80000
2016.10.19 21:46:27.834 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4B29D6 D3AA78 04AF8BFD653F6E00F2 -81
2016.10.19 21:46:27.841 1: PERL WARNING: Illegal hexadecimal digit 's' ignored at ./FHEM/10_CUL_HM.pm line 6578.
2016.10.19 21:46:27.936 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4B29D6 dcdbb8c895bf35d7360a524bd6dc3e4d
2016.10.19 21:46:28.017 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD281 D3AA78 04CC449BFBB21902FD -75.5
2016.10.19 21:46:28.119 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD281 e47791a5d00d8bd2ffe087a8afdb69f1
2016.10.19 21:46:28.202 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD26B D3AA78 046D442DF9FBF202EF -82.5
2016.10.19 21:46:28.303 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD26B de83458a3129d03dff5063f48755d3cf
2016.10.19 21:46:28.386 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD28C D3AA78 04CC25BB28ADC202F8 -78
2016.10.19 21:46:28.487 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD28C 3ab0ff672640cb6085941caf9433bd12
2016.10.19 21:46:28.570 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD2C0 D3AA78 0465FCC216BFF8021D -59.5
2016.10.19 21:46:28.672 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD2C0 daec307f7d280b7c88b471ccefacb2f4
2016.10.19 21:46:28.753 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD27A D3AA78 0401AF18C57A730227 -54.5
2016.10.19 21:46:28.855 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD27A 9810c9936b53ac95bfcfa4898d93eda5
2016.10.19 21:46:28.945 4: CUL_HM_Resend: BrZi_ErkerLi_Rollo nr 2
2016.10.19 21:46:28.947 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD281 0201C80000
2016.10.19 21:46:28.959 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD2C0 0201C80000
2016.10.19 21:46:29.011 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:46:29.013 4: CUL_send: nanoCULHomeMaticAs 0B 21 A001 D3AA78 481C40 010E
2016.10.19 21:46:29.030 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD279 D3AA78 044504DF4123C80020 -58
2016.10.19 21:46:29.132 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD279 0250c617bcf0eb82c43ef8f802af51ee
2016.10.19 21:46:29.221 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD26B 0201C80000
2016.10.19 21:46:29.235 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD281 D3AA78 0436707080A65E02FC -76
2016.10.19 21:46:29.336 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD281 511292df1326cc66190d50a875649fd8
2016.10.19 21:46:29.426 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD2C0 D3AA78 04835B88AABE2C021C -60
2016.10.19 21:46:29.528 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD2C0 f339f1e536de75c1494eb89078d13101
2016.10.19 21:46:30.718 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD279 0201C80000
2016.10.19 21:46:30.900 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD279 D3AA78 04ED334E6D7EDB001E -59
2016.10.19 21:46:31.002 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD279 1ef2880fedc4e8806ef7677486d4b9e2
2016.10.19 21:46:31.098 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD222 0201C80000
2016.10.19 21:46:31.175 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4B29D6 0201C80000
2016.10.19 21:46:31.189 4: CUL_Parse: nanoCULHomeMatic A 12 03 8002 4AD279 D3AA78 0101001053C1E066FC1D -59.5
2016.10.19 21:46:36.155 4: CUL_HM_Resend: BrZi_ErkerRe_Rollo nr 2
2016.10.19 21:46:36.157 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD28C 0201C80000
2016.10.19 21:46:36.169 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD27A 0201C80000
2016.10.19 21:46:36.181 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD2C0 0201C80000
2016.10.19 21:46:36.193 4: CUL_send: nanoCULHomeMaticAs 0B 21 A001 D3AA78 481C40 010E
2016.10.19 21:46:36.205 4: CUL_HM_Resend: BrZi_ErkerLi_Rollo nr 3
2016.10.19 21:46:36.206 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD281 0201C80000
2016.10.19 21:46:36.250 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD26B 0201C80000
2016.10.19 21:46:36.328 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4B29D6 0201C80000
2016.10.19 21:46:36.512 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD222 D3AA78 04163E8194C2B600FB -76.5
2016.10.19 21:46:36.613 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD222 cf161d82fdc6740cb97dacd022f0f97d
2016.10.19 21:46:36.696 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4B29D6 D3AA78 04B2A34BE9D66200F5 -79.5
2016.10.19 21:46:36.798 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4B29D6 4705627af0acdbb457e1ab3789d7f352
2016.10.19 21:46:36.887 4: CUL_Parse: nanoCULHomeMatic A 0F 21 A410 481C40 D3AA78 0601C8003FC818 -62
2016.10.19 21:46:36.988 4: CUL_send: nanoCULHomeMaticAs 0A 21 8002 D3AA78 481C40 00
2016.10.19 21:46:37.108 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:46:37.110 4: CUL_send: nanoCULHomeMaticAs 0E 22 A011 D3AA78 481C40 0201C80000
2016.10.19 21:46:37.334 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD222 0201C80000
2016.10.19 21:46:37.462 4: CUL_Parse: nanoCULHomeMatic A 0F 22 8002 481C40 D3AA78 0101C8003EC81B -60.5
2016.10.19 21:46:37.606 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD222 D3AA78 0475AAD823A96C00F8 -78
2016.10.19 21:46:37.708 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD222 f7f856c925dbc2d9a1fbde492d479aa9
2016.10.19 21:46:40.956 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD27A 0201C80000
2016.10.19 21:46:41.134 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD27A D3AA78 0451F3CADE26E20228 -54
2016.10.19 21:46:41.235 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD27A f9c31d0822b05977bc541c5138b9bc68
2016.10.19 21:46:41.332 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4B29D6 0201C80000
2016.10.19 21:46:41.344 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD2C0 0201C80000
2016.10.19 21:46:41.968 4: CUL_HM_Resend: BrZi_ErkerLi_Rollo nr 4
2016.10.19 21:46:41.969 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD281 0201C80000
2016.10.19 21:46:42.017 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD26B 0201C80000
2016.10.19 21:46:42.110 4: CUL_HM_Resend: BrZi_ErkerRe_Rollo nr 3
2016.10.19 21:46:42.111 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD28C 0201C80000
2016.10.19 21:46:42.179 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD281 D3AA78 04D09EAB559B6A02F9 -77.5
2016.10.19 21:46:42.280 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD281 e7bcc504db218d76508dfdde90e5c901
2016.10.19 21:46:42.376 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD26B D3AA78 04E488DE1E0AC702EF -82.5
2016.10.19 21:46:42.478 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD26B dfea47b5bef2697f0e32143b513d1fe9
2016.10.19 21:46:42.581 4: CUL_Parse: nanoCULHomeMatic A 12 03 8002 4AD281 D3AA78 010100104F4AABCEF4F2 -81
2016.10.19 21:46:47.546 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD222 0201C80000
2016.10.19 21:46:47.598 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:46:47.600 4: CUL_send: nanoCULHomeMaticAs 0B 23 A001 D3AA78 481C40 010E
2016.10.19 21:46:47.778 4: CUL_HM_Resend: BrZi_ErkerRe_Rollo nr 4
2016.10.19 21:46:47.779 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD28C 0201C80000
2016.10.19 21:46:47.955 4: CUL_send: nanoCULHomeMaticAs 0E 03 A011 D3AA78 4AD27A 0201C80000
2016.10.19 21:46:48.772 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD222 D3AA78 0411FAE94F243500F7 -78.5
2016.10.19 21:46:48.874 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD222 d8d8bab3a9d9029be81881bd76ef97f8
2016.10.19 21:46:48.964 4: CUL_Parse: nanoCULHomeMatic A 0F 23 A410 481C40 D3AA78 0601C8003DC81B -60.5
2016.10.19 21:46:49.066 4: CUL_send: nanoCULHomeMaticAs 0A 23 8002 D3AA78 481C40 00
2016.10.19 21:46:49.186 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:46:49.189 4: CUL_send: nanoCULHomeMaticAs 0E 24 A011 D3AA78 481C40 0201C80000
2016.10.19 21:46:49.405 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD28C D3AA78 04D07E6C8240BB02F1 -81.5
2016.10.19 21:46:49.507 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD28C bc298665fd54960238b436fa30afb813
2016.10.19 21:46:49.598 4: CUL_Parse: nanoCULHomeMatic A 11 03 A002 4AD27A D3AA78 0455D63B59763B0225 -55.5
2016.10.19 21:46:49.699 4: CUL_send: nanoCULHomeMaticAs 19 03 A003 D3AA78 4AD27A 3e3c5db6dd25a4d79a2630cd10ec0707
2016.10.19 21:46:49.962 4: CUL_Parse: nanoCULHomeMatic A 0F 24 8002 481C40 D3AA78 0101C8003DC81A -61
2016.10.19 21:46:56.207 4: CUL_Parse: nanoCULHomeMatic A 0F 25 A410 481C40 D3AA78 0601C8003FC817 -62.5
2016.10.19 21:46:56.309 4: CUL_send: nanoCULHomeMaticAs 0A 25 8002 D3AA78 481C40 00
2016.10.19 21:46:56.428 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:46:56.431 4: CUL_send: nanoCULHomeMaticAs 0E 26 A011 D3AA78 481C40 0201C80000
2016.10.19 21:46:56.653 4: CUL_Parse: nanoCULHomeMatic A 0F 26 8002 481C40 D3AA78 0101C8003EC81B -60.5
2016.10.19 21:47:02.899 4: CUL_Parse: nanoCULHomeMatic A 0F 27 A410 481C40 D3AA78 0601C8003EC81C -60
2016.10.19 21:47:03.001 4: CUL_send: nanoCULHomeMaticAs 0A 27 8002 D3AA78 481C40 00
2016.10.19 21:47:03.120 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:03.122 4: CUL_send: nanoCULHomeMaticAs 0E 28 A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:03.345 4: CUL_Parse: nanoCULHomeMatic A 0F 28 8002 481C40 D3AA78 0101C8003CC81A -61
2016.10.19 21:47:04.615 4: CUL_Parse: nanoCULHomeMatic A 0D 04 A410 4AD279 D3AA78 0601C80020 -58
2016.10.19 21:47:04.716 4: CUL_send: nanoCULHomeMaticAs 0A 04 8002 D3AA78 4AD279 00
2016.10.19 21:47:09.589 4: CUL_Parse: nanoCULHomeMatic A 0F 29 A410 481C40 D3AA78 0601C8003FC81B -60.5
2016.10.19 21:47:09.690 4: CUL_send: nanoCULHomeMaticAs 0A 29 8002 D3AA78 481C40 00
2016.10.19 21:47:09.810 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:09.812 4: CUL_send: nanoCULHomeMaticAs 0E 2A A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:10.044 4: CUL_Parse: nanoCULHomeMatic A 0F 2A 8002 481C40 D3AA78 0101C8003FC81B -60.5
2016.10.19 21:47:14.722 4: CUL_Parse: nanoCULHomeMatic A 0D 04 A410 4AD27A D3AA78 0601C80024 -56
2016.10.19 21:47:14.823 4: CUL_send: nanoCULHomeMaticAs 0A 04 8002 D3AA78 4AD27A 00
2016.10.19 21:47:16.433 4: CUL_Parse: nanoCULHomeMatic A 0D 04 A410 4AD281 D3AA78 0601C800F3 -80.5
2016.10.19 21:47:16.534 4: CUL_send: nanoCULHomeMaticAs 0A 04 8002 D3AA78 4AD281 00
2016.10.19 21:47:18.177 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:47:18.179 4: CUL_send: nanoCULHomeMaticAs 0B 2B A001 D3AA78 481C40 010E
2016.10.19 21:47:18.464 4: CUL_Parse: nanoCULHomeMatic A 0F 2B A410 481C40 D3AA78 0601C8003CC81B -60.5
2016.10.19 21:47:18.566 4: CUL_send: nanoCULHomeMaticAs 0A 2B 8002 D3AA78 481C40 00
2016.10.19 21:47:18.689 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:18.692 4: CUL_send: nanoCULHomeMaticAs 0E 2C A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:19.408 4: CUL_Parse: nanoCULHomeMatic A 0F 2C 8002 481C40 D3AA78 0101C8003EC81D -59.5
2016.10.19 21:47:19.547 4: CUL_Parse: nanoCULHomeMatic A 0D 04 A410 4AD281 D3AA78 0601C800F6 -79
2016.10.19 21:47:19.648 4: CUL_send: nanoCULHomeMaticAs 0A 04 8002 D3AA78 4AD281 00
2016.10.19 21:47:19.660 4: CUL_HM BrZi_ErkerLi_Rollo dupe: repeat 2 ack, dont process
2016.10.19 21:47:25.475 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:47:25.477 4: CUL_send: nanoCULHomeMaticAs 0B 2D A001 D3AA78 481C40 010E
2016.10.19 21:47:25.655 4: CUL_Parse: nanoCULHomeMatic A 0F 2D A410 481C40 D3AA78 0601C80048C824 -56
2016.10.19 21:47:25.757 4: CUL_send: nanoCULHomeMaticAs 0A 2D 8002 D3AA78 481C40 00
2016.10.19 21:47:25.876 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:25.878 4: CUL_send: nanoCULHomeMaticAs 0E 2E A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:26.100 4: CUL_Parse: nanoCULHomeMatic A 0F 2E 8002 481C40 D3AA78 0101C8003FC822 -57
2016.10.19 21:47:32.169 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:47:32.171 4: CUL_send: nanoCULHomeMaticAs 0B 2F A001 D3AA78 481C40 010E
2016.10.19 21:47:32.348 4: CUL_Parse: nanoCULHomeMatic A 0F 2F A410 481C40 D3AA78 0601C8003FC815 -63.5
2016.10.19 21:47:32.449 4: CUL_send: nanoCULHomeMaticAs 0A 2F 8002 D3AA78 481C40 00
2016.10.19 21:47:32.569 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:32.571 4: CUL_send: nanoCULHomeMaticAs 0E 30 A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:32.793 4: CUL_Parse: nanoCULHomeMatic A 0F 30 8002 481C40 D3AA78 0101C80038C81F -58.5
2016.10.19 21:47:38.875 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:47:38.878 4: CUL_send: nanoCULHomeMaticAs 0B 31 A001 D3AA78 481C40 010E
2016.10.19 21:47:39.055 4: CUL_Parse: nanoCULHomeMatic A 0F 31 A410 481C40 D3AA78 0601C8003FC816 -63
2016.10.19 21:47:39.157 4: CUL_send: nanoCULHomeMaticAs 0A 31 8002 D3AA78 481C40 00
2016.10.19 21:47:39.316 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:39.319 4: CUL_send: nanoCULHomeMaticAs 0E 32 A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:39.622 4: CUL_Parse: nanoCULHomeMatic A 0F 32 8002 481C40 D3AA78 0101C8003DC819 -61.5
2016.10.19 21:47:45.693 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:47:45.695 4: CUL_send: nanoCULHomeMaticAs 0B 33 A001 D3AA78 481C40 010E
2016.10.19 21:47:45.872 4: CUL_Parse: nanoCULHomeMatic A 0F 33 A410 481C40 D3AA78 0601C8003FC817 -62.5
2016.10.19 21:47:45.973 4: CUL_send: nanoCULHomeMaticAs 0A 33 8002 D3AA78 481C40 00
2016.10.19 21:47:46.093 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:46.095 4: CUL_send: nanoCULHomeMaticAs 0E 34 A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:46.319 4: CUL_Parse: nanoCULHomeMatic A 0F 34 8002 481C40 D3AA78 0101C80040C819 -61.5
2016.10.19 21:47:52.397 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:47:52.400 4: CUL_send: nanoCULHomeMaticAs 0B 35 A001 D3AA78 481C40 010E
2016.10.19 21:47:52.576 4: CUL_Parse: nanoCULHomeMatic A 0F 35 A410 481C40 D3AA78 0601C8003DC81D -59.5
2016.10.19 21:47:52.678 4: CUL_send: nanoCULHomeMaticAs 0A 35 8002 D3AA78 481C40 00
2016.10.19 21:47:52.798 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:47:52.800 4: CUL_send: nanoCULHomeMaticAs 0E 36 A011 D3AA78 481C40 0201C80000
2016.10.19 21:47:53.026 4: CUL_Parse: nanoCULHomeMatic A 0F 36 8002 481C40 D3AA78 0101C8003EC81B -60.5
2016.10.19 21:48:05.784 3: CUL_HM set Flur_Lichtbild_Sw statusRequest
2016.10.19 21:48:05.786 4: CUL_send: nanoCULHomeMaticAs 0B 39 A001 D3AA78 481C40 010E
2016.10.19 21:48:08.351 4: CUL_Parse: nanoCULHomeMatic A 0F 40 A410 481C40 D3AA78 06013C00803C1A -61
2016.10.19 21:48:08.452 4: CUL_send: nanoCULHomeMaticAs 0A 40 8002 D3AA78 481C40 00
2016.10.19 21:48:09.629 4: CUL_send: nanoCULHomeMaticAs 0B 39 A001 D3AA78 481C40 010E
2016.10.19 21:48:10.079 4: CUL_Parse: nanoCULHomeMatic A 0F 39 A410 481C40 D3AA78 06013C00463C18 -62
2016.10.19 21:48:10.180 4: CUL_send: nanoCULHomeMaticAs 0A 39 8002 D3AA78 481C40 00
2016.10.19 21:48:10.302 3: CUL_HM set Flur_Lichtbild_Sw on
2016.10.19 21:48:10.305 4: CUL_send: nanoCULHomeMaticAs 0E 3A A011 D3AA78 481C40 0201C80000
2016.10.19 21:48:10.996 4: CUL_Parse: nanoCULHomeMatic A 0F 3A 8002 481C40 D3AA78 0101C800393C27 -54.5
2016.10.19 21:48:11.856 4: CUL_Parse: nanoCULHomeMatic A 0F 3C A410 481C40 D3AA78 0601C80080C824 -56
2016.10.19 21:48:11.957 4: CUL_send: nanoCULHomeMaticAs 0A 3C 8002 D3AA78 481C40 00
2016.10.19 21:48:15.858 4: CUL_Parse: nanoCULHomeMatic A 0F 45 A410 481C40 D3AA78 06017800807818 -62
2016.10.19 21:48:15.960 4: CUL_send: nanoCULHomeMaticAs 0A 45 8002 D3AA78 481C40 00
2016.10.19 21:48:30.812 4: CUL_Parse: nanoCULHomeMatic A 0D 06 A410 4AD281 D3AA78 0601B620F9 -77.5
2016.10.19 21:48:30.914 4: CUL_send: nanoCULHomeMaticAs 0A 06 8002 D3AA78 4AD281 00
2016.10.19 21:48:41.532 4: CUL_Parse: nanoCULHomeMatic A 0D 06 A410 4AD27A D3AA78 0601B32026 -55
2016.10.19 21:48:41.633 4: CUL_send: nanoCULHomeMaticAs 0A 06 8002 D3AA78 4AD27A 00
2016.10.19 21:48:42.818 4: CUL_Parse: nanoCULHomeMatic A 0D 06 A410 4AD279 D3AA78 0601B5201C -60
2016.10.19 21:48:42.920 4: CUL_send: nanoCULHomeMaticAs 0A 06 8002 D3AA78 4AD279 00
2016.10.19 21:48:47.497 4: CUL_Parse: nanoCULHomeMatic A 0D 06 A410 4AD279 D3AA78 0601912020 -58
2016.10.19 21:48:47.599 4: CUL_send: nanoCULHomeMaticAs 0A 06 8002 D3AA78 4AD279 00
2016.10.19 21:49:00.544 4: CUL_Parse: nanoCULHomeMatic A 0D 07 A410 4AD281 D3AA78 06010000F7 -78.5
2016.10.19 21:49:00.646 4: CUL_send: nanoCULHomeMaticAs 0A 07 8002 D3AA78 4AD281 00
2016.10.19 21:49:10.303 4: CUL_Parse: nanoCULHomeMatic A 0D 07 A410 4AD27A D3AA78 0601000024 -56
2016.10.19 21:49:10.405 4: CUL_send: nanoCULHomeMaticAs 0A 07 8002 D3AA78 4AD27A 00
2016.10.19 21:49:15.589 4: CUL_Parse: nanoCULHomeMatic A 0D 07 A410 4AD279 D3AA78 0601000023 -56.5
2016.10.19 21:49:15.692 4: CUL_send: nanoCULHomeMaticAs 0A 07 8002 D3AA78 4AD279 00
2016.10.19 21:49:21.545 4: CUL_Parse: nanoCULHomeMatic A 0D 07 A410 4AD279 D3AA78 0601000022 -57
2016.10.19 21:49:21.647 4: CUL_send: nanoCULHomeMaticAs 0A 07 8002 D3AA78 4AD279 00
2016.10.19 21:49:21.743 4: CUL_Parse: nanoCULHomeMatic A 0D 07 A410 4AD279 D3AA78 0601000021 -57.5
Zwischenrein hat noch ein HM Dimmer gestreut. Ich hab keine Ahnung warum, betätigt habe ich ihn nicht.
Meine Lösung für das Problem heißt HM-MOD-RPI-PCB, damit habe ich aktuell keine Probleme mehr. Ich helfe aber gerne noch beim Debuggen mit dem CUL.
Viele Grüße,
Christian
Hallo Christian,
danke für den Versuch.
Die gute Nachricht ist also, das die Device Warteschlange nicht mehr hängen geblieben ist!?!
Ich habe meine Änderungen an 10_CUL_HM.pm noch etwas korrigiert, so dass die Perl-Warnung auch weg ist, nebst einer Warnung mit undefiniertem $parse.
Wieder hier https://forum.fhem.de/index.php/topic,24436.msg489979.html#msg489979 (https://forum.fhem.de/index.php/topic,24436.msg489979.html#msg489979) zu finden.
Deine System Timing Probleme adressiert diese Änderung jedoch nicht, nur den AES bedingten Hänger der device Warteschlange.
Gruß, Ansgar.
Hallo,
auch ich habe meine Jalousien auf AES umgestellt.
Seitdem kommt es häufiger vor, dass der Status unknown ist und Einträge für protEvt_AESerrReject erscheinen.
Wie kann man mit dem Problem umgehen? Konntet ihr es lösen?
Hallo zusammen,
selbes Problem hier.
Mein Workaround ist auch ein zeitversetztes Ansteuern der Aktoren, dann auf set_xx prüfen und wenn nötig einen statusRequest schicken.
* Innerhalb eines Scripts 2 sec. Abstand zwischen den Aktoren und die drei Scripte die gleichzeitig laufen zufällig innerhalb von zwei Sekunden versetzen.
* Sollte ein set_xx hängenbleiben, nach 2 Minuten einen statusRequest absetzen. (siehe: https://forum.fhem.de/index.php/topic,61844.0.html)
Das funktioniert fast immer, nur manchmal schlägt auch der statusRequest mit einem AESreject fehl und dann hilft auch kein zweiter(manueller) statusRequest, denn dann wird einfach die Position gemeldet und das Rollo fährt nicht.
Ich dachte bis vor kurzem, dass ich der einzige mit diesem Problem bin, aber anscheinend haben das mehrer Leute.
Was diese Geschichte doppelt unangenehm macht ist, dass ich nicht verstehe warum ein statusRequest zum (beliebig späteren)Ausführen des Befehls sorgt, obwohl beim set ein AESrejected gemeldet wurde?
Grüße
Hugo
Habt ihr eigentlich nur sign-on beim Geraet aktiviert oder auch ein AesCommReq gesetzt?
Ist diese Problematik mit den AES Fehlern eher bei Homematic im BidCOS Protokoll angesiedelt oder ist es eher FHEM seitig zu untersuchen?
Ich frage deshalb, weil ich denke, dass BidCOS ja Befehle 3x senden sollte, wenn diese nicht richtig angekommen sind, oder?
Es ist beides gesetzt.
Ich bin mir nahezu sicher, dass dieses Verhalten dann zustande kommt, wenn eine AES Signatur(von einem Device) mit einem anderen Paket(von HM-LAN) bei der Übertragung kollidiert und somit die Signatur "zerstört" wird und dann vermutlich als "falsch" interpretiert wird.
Umso mehr auf dem Funkkanal los ist und umso mehr AES Kommunikationen man hat, desto höher ist dann natürlich die Wahrscheinlichkeit. Darum helfen auch die Zeitverzögerungen halbwegs.
Ist aber nur eine Vermutung.
Grüße
Hugo
Der Vermutung kann ich nur zustimmen.
Auch mir fiel auf, dass sich die Fehler haeufen, wenn ich alle meine Jalos anspreche.
Darf ich fragen, warum die AESCommReq anhast?
IMHO wird das doch nur benoetigt, wenn der Taster der Jalosie FHEM meldet, dass er gedrueckt wurde, oder die Jalo ihren Status preis gibt.
Das habe ich erstmal ausgelassen, um den Traffic zu reduzieren.
Damit die Kommunikation zw. FHEM und Aktor auch signiert stattfindet. Sollte nicht soviel Last erzeugen.
Hugo
Ich habe in diesem Zusammenhang ein merkwürdiges Verhalten bemerkt.
Bis zum vorletzten Update von FHEM welches ich gemacht habe, hat ein statusRequest immer dazu geführt, dass die Rollos die gewünschte Position angefahren haben.
Nach diesem Update kam es zu einer starken Häufung (1-2 pro Tag bei ca. 30 Fahrten) dieser set_-Hänger. Ein weiteres Update führte dazu, dass die Hänger wieder nahezu gegen Null gingen (ca. 1-2 pro Monat bei 30 Fahrten pro Tag). Wenn es jedoch zu einem Hänger kam, dann half der statusRequest nicht mehr. Ein Hinzufügen eines weiteren set nach dem statusRequest half auch nicht. Der Aktor ignoriert diesen anscheinend.
Bei einem Aktor der sich standhaft gegen meine Kommandos gewehrt hat, habe ich eine manuelle Fahrt ausgelöst und im Eventlog mitgelesen.
Er meldete sich kurz nach dem Anfahren mit dem aktuellen Status (5.5) und motor:down. Nachdem das Rollo geschlossen war, hat der Aktor sich nochmals gemeldet, was jedoch mit einem AES:failed fehlgeschlagen ist. Ein Klick auf stausRequest führte zu einem unreachable als Satus und ein weiterer Druck auf statusRequest lieferte dann den korrekten Status des Aktors. Irgendwie erweckt das den Anschein, dass irgendetwas den Aktor aus dem Tritt bringt, von dem er sich aber mit der Zeit bzw. nach ein paar Kommandos wieder "erholt".
Mein Problem ist, dass dieses Verhalten nur sehr selten auftritt und ich somit bei allen Rollos über Wochen alles loggen müsste um irgendwann dieses Verhalten zu erwischen. Wenn es dann dazu gekommen ist, muss ich die Einträge noch finden. (Die einzige Möglichkeit etwas aus der SQLite zu bekommen ist für mich DBReport, oder gibt es etwas anderes?)
Ich kann dieses Verhalten leider auch nicht erzwingen/provozieren.
Ich möchte gerne Daten liefern, damit Experten für das HM-Protokoll auch etwas damit anfangen können, denn meine Beschreibungen sind da wenig hilfreich befürchte ich.
Hat jemand Tipps für mich, wie ich das loggen kann, ohne meinen Raspi damit umzubringen? (und mich beim Suchen der Einträge ;-)
Heute morgen war es seit langem wieder einmal soweit, dass sich ein Rollo gegen all meine Versuche gewehrt hat zu fahren.
@martin: die letzte Änderung am AES (lt. SVN log) hat dazu geführt, dass dieses Verhalten wieder extrem selten auftritt. Danke!
Folgendes ist passiert:
1. Script löst ein set 100 aus.
2. state Missing ACK
3. Nach zwei Minuten prüft das Script, ob im state ein set_ zu finden ist und führt in diesem Fall ein statusRequest aus. (sieht man aber im dbLog nicht, obwohl es im Logfile steht!)
4. Zwei Sekunden später wird das set 100 nocheinmal ausgeführt
5. AES schlägt fehl (obwohl zu diesem Zeitpunkt schon wieder wenig/nichts los ist auf der Funkseite)
6. state Missing ACK
7. Drei Stunden später funktioniert ein statusRequest per Webfrontend einwandfrei (siehe dbLog)
Ich bin ratlos.
List in diesem Zustand:
Internals:
DEF 3FFDF5
HMLAN1_MSGCNT 267
HMLAN1_RAWMSG RD57EC5B8,0050,96865AB5,01,FFB4,5DA4103FFDF5ABCDEF0601000048
HMLAN1_RSSI -76
HMLAN1_TIME 2017-03-16 06:02:20
IODev HMLAN1
LASTInputDev HMLAN1
MSGCNT 267
NAME OG_Wohnzimmer_Terrasse_Rollo
NOTIFYDEV global
NR 223
NTFY_ORDER 50-OG_Wohnzimmer_Terrasse_Rollo
STATE MISSING ACK
TYPE CUL_HM
lastMsg No:5B - t:10 s:3FFDF5 d:ABCDEF 06010000
protCmdDel 11
protEvt_AESCom-ok 81 last_at:2017-03-15 18:27:17
protEvt_AESerrReject 4 last_at:2017-03-11 19:29:53
protLastRcv 2017-03-15 18:27:17
protResnd 21 last_at:2017-03-16 06:02:32
protResndFail 5 last_at:2017-03-16 06:02:36
protSnd 94 last_at:2017-03-16 06:02:19
protState CMDs_done_Errors:1
rssi_HMLAN1 avg:-60.74 min:-81 max:-51 lst:-65 cnt:31
rssi_at_HMLAN1 avg:-61.34 min:-79 max:-46 lst:-60 cnt:85
Helper:
Dblog:
Aescommtodev:
Logdb:
TIME 1489640540.10387
VALUE fail
Devicemsg:
Logdb:
TIME 1489598837.61539
VALUE off (to vccu)
Level:
Logdb:
TIME 1489640416.39267
VALUE set_100
Motor:
Logdb:
TIME 1489598837.61539
VALUE stop:off
Pct:
Logdb:
TIME 1489598837.61539
VALUE 0
State:
Logdb:
TIME 1489640556.85317
VALUE MISSING ACK
Readings:
2017-03-15 18:26:34 CommandAccepted yes
2016-12-09 20:24:45 D-firmware 2.8
2016-12-09 20:24:45 D-serialNr MEQ1098453
2017-03-05 15:30:26 PairedTo 0xABCDEF
2016-12-04 00:50:50 R-driveDown 35.5 s
2016-12-04 00:50:50 R-driveTurn 0.5 s
2016-12-04 00:50:50 R-driveUp 36 s
2016-12-04 00:50:48 R-pairCentral 0xABCDEF
2017-02-27 23:33:36 R-powerUpAction off
2016-12-04 00:50:50 R-sign on
2017-03-05 15:30:26 RegL_00. 02:01 0A:AB 0B:CD 0C:EF 15:FF 18:00 00:00
2017-03-05 15:30:28 RegL_01. 08:01 09:00 0A:00 0B:01 0C:63 0D:01 0E:68 0F:05 10:00 30:06 57:24 56:00 00:00
2017-03-16 06:02:20 aesCommToDev fail
2017-03-15 18:26:33 aesKeyNbr 02
2017-03-15 18:27:17 deviceMsg off (to vccu)
2017-03-13 10:45:07 laststate none
2017-03-16 06:02:18 level set_100
2017-03-16 06:00:00 mode none
2017-03-15 18:27:17 motor stop:off
2017-03-15 18:27:17 pct 0
2017-03-15 18:27:17 recentStateType info
2017-03-16 06:02:36 state MISSING ACK
2017-03-15 18:27:17 timedOn off
2017-02-04 23:07:23 trigLast RemoteRC8_Btn_05:short
2017-02-04 23:07:23 trig_RemoteRC8_Btn_05 Short_12
2017-02-04 23:07:00 trig_RemoteRC8_Btn_06 Short_16
Helper:
HM_CMDNR 93
cSnd 11ABCDEF3FFDF50201C8,01ABCDEF3FFDF5010E
dlvlCmd ++A011ABCDEF3FFDF50201C8
mId 006A
peerIDsRaw ,00000000
rxType 1
supp_Pair_Rep 0
Dir:
cur stop
rct down
Expert:
def 1
det 0
raw 1
tpl 0
Io:
newChn +3FFDF5,01,01,02
nextSend 1489640540.19257
rxt 0
vccu vccu
p:
3FFDF5
01
01
02
prefIO:
HMLAN1
Mrssi:
mNo 5B
Io:
HMLAN1 -58
Prt:
bErr 0
sProc 0
Q:
qReqConf
qReqStat
Role:
chn 1
dev 1
prs 1
Rpt:
IO HMLAN1
flg A
ts 1489598837.60456
ack:
HASH(0x1b261a0)
5B8002ABCDEF3FFDF500
Rssi:
Hmlan1:
avg -60.741935483871
cnt 31
lst -65
max -51
min -81
At_hmlan1:
avg -61.3411764705882
cnt 85
lst -60
max -46
min -79
Shadowreg:
Tmpl:
Role:
Attributes:
IODev HMLAN1
IOgrp vccu:HMLAN1
aesCommReq 1
alias OG Wohnzimmer Rollo (Terrasse)
autoReadReg 5_readMissing
automatic on
closedlevel 0
darkening on
devStateIcon on:fts_shutter_10@green off:fts_shutter_100@black 9\d+.*:fts_shutter_10 8\d+.*:fts_shutter_20 7\d+.*:fts_shutter_30 6\d+.*:fts_shutter_40 5\d+.*:fts_shutter_50 4\d+.*:fts_shutter_60 3\d+.*:fts_shutter_70 2\d+.*:fts_shutter_80 1\d+.*:fts_shutter_90 \d.*:fts_shutter_100
event-on-change-reading aesCommToDev,aesKeyNbr,deviceMsg,level,motor,pct,powerOn,recentStateType,state,timedOn
expert 2_defReg+raw
firmware 2.8
forced_unshadow no
geoposition east
group Rollos OG
model HM-LC-Bl1PBU-FM
openedlevel 100
peerIDs 00000000,
room OG Wohnzimmer
security OG_Wohnzimmer_Terrasse_SEC
serialNr MEQ1098453
shadow on
shadowposition 0
sleeping no
subType blindActuator
sunlevel 2
timezone OG1
userattr automatic darkening geoposition security shadow shadowposition sunlevel openedlevel closedlevel sleeping timezone forced_unshadow
webCmd statusRequest:toggleDir:on:off:up:down:stop
Im dblog findet man dazu:
2017-03-16_06-00-16__OG_Wohnzimmer_Terrasse_Rollo__level set_100 2017-03-16 09:35:32
2017-03-16_06-00-16__OG_Wohnzimmer_Terrasse_Rollo__state set_100 2017-03-16 09:35:32
2017-03-16_06-00-34__OG_Wohnzimmer_Terrasse_Rollo__state MISSING ACK 2017-03-16 09:35:32
2017-03-16_06-02-18__OG_Wohnzimmer_Terrasse_Rollo__state set_100 2017-03-16 09:35:32
2017-03-16_06-02-19__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev pending 2017-03-16 09:35:32
2017-03-16_06-02-20__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev fail 2017-03-16 09:35:32
2017-03-16_06-02-36__OG_Wohnzimmer_Terrasse_Rollo__state MISSING ACK 2017-03-16 09:35:32
2017-03-16_09-05-56__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev ok 2017-03-16 09:35:32
2017-03-16_09-05-56__OG_Wohnzimmer_Terrasse_Rollo__level 0 2017-03-16 09:35:32
2017-03-16_09-05-56__OG_Wohnzimmer_Terrasse_Rollo__state off 2017-03-16 09:35:32
2017-03-16_09-06-03__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev ok 2017-03-16 09:35:32
2017-03-16_09-06-03__OG_Wohnzimmer_Terrasse_Rollo__motor up:off 2017-03-16 09:35:32 2017-03-16_09-06-03__OG_Wohnzimmer_Terrasse_Rollo__state off 2017-03-16 09:35:32
2017-03-16_09-06-26__OG_Wohnzimmer_Terrasse_Rollo__state set_on 2017-03-16 09:35:32
2017-03-16_09-06-28__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev pending 2017-03-16 09:35:32
2017-03-16_09-06-29__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev ok 2017-03-16 09:35:32
2017-03-16_09-06-29__OG_Wohnzimmer_Terrasse_Rollo__deviceMsg 70 (to vccu) 2017-03-16 09:35:32
2017-03-16_09-06-29__OG_Wohnzimmer_Terrasse_Rollo__level 70 2017-03-16 09:35:32
2017-03-16_09-06-29__OG_Wohnzimmer_Terrasse_Rollo__motor up:70 2017-03-16 09:35:32
2017-03-16_09-06-29__OG_Wohnzimmer_Terrasse_Rollo__pct 70 2017-03-16 09:35:32
2017-03-16_09-06-29__OG_Wohnzimmer_Terrasse_Rollo__state 70 2017-03-16 09:35:32
2017-03-16_09-06-45__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev pending 2017-03-16 09:35:32
2017-03-16_09-06-46__OG_Wohnzimmer_Terrasse_Rollo__aesCommToDev ok 2017-03-16 09:35:32
2017-03-16_09-06-46__OG_Wohnzimmer_Terrasse_Rollo__deviceMsg on (to vccu) 2017-03-16 09:35:32
2017-03-16_09-06-46__OG_Wohnzimmer_Terrasse_Rollo__level 100 2017-03-16 09:35:32
2017-03-16_09-06-46__OG_Wohnzimmer_Terrasse_Rollo__motor stop:on 2017-03-16 09:35:32
2017-03-16_09-06-46__OG_Wohnzimmer_Terrasse_Rollo__pct 100 2017-03-16 09:35:32
2017-03-16_09-06-46__OG_Wohnzimmer_Terrasse_Rollo__state on 2017-03-16 09:35:32
und im Logfile:
2017.03.16 06:00:16 3: CUL_HM set OG_Wohnzimmer_Terrasse_Rollo 100
2017.03.16 06:02:02 3: CUL_HM set OG_Kinderzimmer_Ost_Rollo statusRequest
2017.03.16 06:02:16 3: CUL_HM set OG_Wohnzimmer_Terrasse_Rollo statusRequest
2017.03.16 06:02:16 1: set_ Fehler bei: OG_Wohnzimmer_Terrasse_Rollo
2017.03.16 06:02:18 3: CUL_HM set OG_Wohnzimmer_Terrasse_Rollo 100