HM-LC-Bl1PBU-FM nicht mehr setzbar

[gandy]

hi Martin,

meine wireshark mitschnitte sind Text Files, die aus "follow TCP stream" als txt gespeichert werden - sieht ähnlich aus wie der Austausch zwischen fhem und hmlan. hab auch jeweils als raw gespeichert - oder wie sollte es richtig sein?

grüße,
Andy.

[gandy]

Hi Martin,

konnte jetzt endlich Deine Dateien einspielen und damit testen. Hab damit reichlich Daten erhoben dann den AES code neu gesetzt und zur Sicherheit alle Aktoren auf Werkszustand gesetzt/neu angelernt, im letzten Schritt dann RS04 und RS08 auf "gesichert" gesetzt. Wirklich interessant wirds vermutlich am Ende, weil beide AES code reject melden, aber nur einer von beiden nicht fährt.

Logfile fhem-2013-08.log-1, beginnt mit fhem shutdown restart - zu Beginn wie gehabt Rollos 02,03,04 mit AES-Problem, entsprechend neuer Log-Eintrag AES code rejected beim Versuch, RS04 zu fahren. abwechselnd AES code reject oder auch nicht, Rollos fahren meistens (bis auf einen Fall, s.U.)

Im Einzelnen (fhem-2013-08.log-1)

Code Auswählen Erweitern


2013.08.26 07:26:47.480 : shutdown restart, um neue Treiber einzubinden
2013.08.26 07:26:59.288 : FHEM beginnt, getSerial,getConfig,statusRequest auf allen RolloSchaltern abzusetzen
                          Zwischendrin funkt die Beschattungsautomatik dazwischen, Rollos fahren aber nicht, AES code rejected
2013.08.26 07:31:00.325 : FHEM shutdown für LAN-Konfigurator Einsatz
2013.08.26 07:35:18.229 : FHEM neustart
2013.08.26 07:35:29.249 : FHEM beginnt, getSerial,getConfig,statusRequest auf allen RolloSchaltern abzusetzen
2013.08.26 07:36:18.728 : Beschattungsautomatik steuer Rollos 01,02,03,04 an - AES code rejected für 02,03,04, [b]aber Rollos fahren![/b]
2013.08.26 07:37:43.464 : Kommando von Webschnittstelle an Rollo 04 - fährt, kein AES code reject


An der Stelle habe ich den Logeintrag leicht modifiziert, um bei AES code reject "src" statt "dst" zu loggen

Code Auswählen Erweitern


2013.08.26 07:59:50.054 : shutdown restart - reload 00_HMLAN hätte wohl gereicht...
2013.08.26 08:01:59.293 : set RolloSchalter04 60 - kein AES code reject, Rollo fährt
2013.08.26 08:02:48.449 : set RolloSchalter04 70 - AES code rejected, aber Rollo fährt
2013.08.26 08:05:08.132 : set RolloSchalter04 80 - kein AES code reject, Rollo fährt
2013.08.26 08:05:44.473 : set RolloSchalter04 90 - AES code rejected, aber Rollo fährt
2013.08.26 08:06:24.000 : set RolloSchalter04 90 -  kein AES code reject (noch 3x mit der selben Position wiederholt)
2013.08.26 08:06:50.522 : set RolloSchalter04 100 - kein AES code reject, Rollo fährt
2013.08.26 08:09:26.804 : set RolloSchalter04 pct 90 - AES code rejected, aber Rollo fährt
...
(Hier zwischendrin manuell eingegriffen, am besten ignorieren)
...
2013.08.26 08:12:04.172 : set RolloSchalter04 100 - kein AES code reject, Rollo fährt
(ab hier per Endlosschleife im 10sek Abstand in 10% Schritten zwischen 0 und 100 gefahren)
(mal mit / mal ohne AES code reject, Rollos fuhren jedes mal, ausser bei dem hier:)
2013.08.26 08:21:17.733 : set RolloSchalter04 pct 70 - AES code reject, Rollo fährt nicht (auch keine LED Anzeige oder Relais-Geräusch)!!!!
2013.08.26 08:29:38.007 : Endlosschleife gestoppt

2013.08.26 09:16:06.342 : set RolloSchalter04 15 - kein AES code reject, Rollo fährt


----

Hier habe ich nun FHEM wieder beendet, per LAN-Konfigurator einen neuen AES-Key vergeben, ALLE Rolloschalter auf Werkszustand zurückgesetzt, wieder alle angelernt und FHEM neu gestartet. Dort dann driveUp und driveDown gesetzt. Ausserdem ein paar Rollos gefahren, siehe fhem-2013-08.log-2

----

Dann FHEM wieder gestoppt, LAN-Konfigurator geöffnet und RS04,RS08 jeweils auf "Gesichert" umgestellt. Dabei gesehen, dass driveUp/driveDown noch auf Werkseinstellung stehen (50s).

fhem-2013-08.log-3: FHEM neu gestartet und versucht, alle Rollos zu fahren. AES code reject auf RS04 und RS08. Unterschied: Rollo RS04 bewegt sich, RS08 nicht. Vielleicht erste Hinweise?

Jede Menge Daten, ich hoffe es ist was brauchbares dabei....

Beste Grüße,
Andy.

[gandy]

Nachtrag: nach ein paar weiteren versuchen habe ich RS04 und RS08 nochmals auf werkseinstellungen zurůckgesetzt und aes-sign nicht wieder aktiviert. Während des fhem Starts kam es dann zu einer Situation mit schnellen Richtungswechseln, seitdem fahren beide Motoren nur noch abwärts. sieht ganz nach dem verklebte-relais-kontakte-syndrom aus.... vorerst scheinen die beiden Jungs aus dem Spiel zu sein...

Beste grüße,
Andy.

Edit: Hab die beiden Motoren auf Handschalter zurückgebaut - und sie fahren immer noch nur in eine Richtung...

[martinp876]

Hi,

habe version 3802 eingestellt. Hier kann man für AES 2 neue ProtokollEvents einsehen:
prot_AESerrReject #  Anzahl nicht erfolgreicher AES anforderungen
prot_AESok #  Anzahl erfolgreicher AES Anforderungen

Eine Übersicht bekommt man mit HMInfo (immer gut für Übersichten)

set hm param -d prot_AESok prot_AESerrReject

Zu beachten ist, dass bei einem deiner Versuche HMLAN "Zusammengebrochen" ist. Hat also den Status ERROR-OVERLOAD. FHEM blockiert die Übertragung bis HMLAN wieder aktiv ist.

Gruss Martin

[gandy]

Hi Martin,

kurzes Update: Beide Rollos sind wieder OK, anscheinend hatte ich es geschafft, die Endpositionen in den Motoren neu zu setzen - nach einer Neukalibrierung funktioniert jetzt alles wieder, auch die Aktoren sind fehlerfrei.

Bin jetzt wieder mit der aktuellsten SVN Version dabei, habe aktuell einen der Aktoren auf AES konfiguriert, konnte bislang aber noch keine prot_AESok oder prot_AESerrReject sehen.

Beste Grüße,
Andy.

[martinp876]

Hallo Andy,

hier eine erweiterte Version.

kannst du es einmal einbauen und noch einmal loggen?
Problem war, dass das erste AES vom device rejected wird. Das habe ich noch nicht abgefangen.

Warum es zu dem AES Problem kommt kann ich nicht sagen - evtl ist dies normal nach restart... wird das log zeigen. Am AES selbst habe ich nichts geaendert - kann ich garnicht

Gruss Martin

[gandy]

Hallo Martin,

hab eben ein update durchgeführt, 00_HMLAN.pm ersetzt und fhem neu gestartet. Dann ohne über die Windows Software zu gehen direkt

Code Auswählen Erweitern


set RolloSchalter02 regSet sign on

abgesetzt, etwas gewartet und dann das Rollo munter in der Gegend herumgefahren:

Code Auswählen Erweitern


2013.09.18 21:10:58.824 2: CUL_HM set RolloSchalter02 pct 20
2013.09.18 21:10:58.825 3: HMLAN_Send:  HMLAN1 S:S327B8A4A stat:  00 t:00000000 d:01 r:327B8A4A m:93 A011 139AF8 1AB518 020128
2013.09.18 21:10:59.000 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262B94F0 d:FF r:FFBF     m:93 A002 1AB518 139AF8 0423E97D17298908
2013.09.18 21:10:59.237 3: HMLAN_Parse: HMLAN1 R:R327B8A4A stat:0021 t:262B94F5 d:04 r:FFC1     m:93 8002 1AB518 139AF8 010100103F0C4300B6
2013.09.18 21:11:06.110 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262BB0C8 d:FF r:FFBE     m:94 A410 1AB518 139AF8 06012800
2013.09.18 21:11:12.617 3: HMLAN_Send:  HMLAN1 I:K
2013.09.18 21:11:12.621 3: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061517 d:139AF8 O:139AF8 t:262BCA3E IDcnt:000F
2013.09.18 21:11:37.632 3: HMLAN_Send:  HMLAN1 I:K
2013.09.18 21:11:37.636 3: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061517 d:139AF8 O:139AF8 t:262C2BF9 IDcnt:000F
2013.09.18 21:11:46.033 3: HMLAN_Parse: HMLAN1 R:E1B09BD   stat:0000 t:262C4CBF d:FF r:FFB8     m:44 8670 1B09BD 000000 008A55

2013.09.18 21:11:58.549 2: CUL_HM set RolloSchalter02 pct 30
2013.09.18 21:11:58.549 3: HMLAN_Send:  HMLAN1 S:S327C7396 stat:  00 t:00000000 d:01 r:327C7396 m:94 A011 139AF8 1AB518 02013C
2013.09.18 21:11:58.725 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262C7E45 d:FF r:FFBE     m:94 A002 1AB518 139AF8 042C10745E36B808
2013.09.18 21:11:59.657 3: HMLAN_Parse: HMLAN1 R:R327C7396 stat:0030 t:262C7E4B d:04 r:FFBE     m:94 A002 1AB518 139AF8 042C10745E36B808
2013.09.18 21:11:59.657 3: HMLAN_Parse: HMLAN1 AES code rejected for 139AF8 48
2013.09.18 21:12:02.635 3: HMLAN_Send:  HMLAN1 I:K
2013.09.18 21:12:02.639 3: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061517 d:139AF8 O:139AF8 t:262C8DA7 IDcnt:000F
2013.09.18 21:12:03.298 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262C9035 d:FF r:FFBF     m:95 A410 1AB518 139AF8 06013C00

2013.09.18 21:12:15.134 2: CUL_HM set RolloSchalter02 pct 35
2013.09.18 21:12:15.134 3: HMLAN_Send:  HMLAN1 S:S327CB45F stat:  00 t:00000000 d:01 r:327CB45F m:95 A011 139AF8 1AB518 020146
2013.09.18 21:12:15.311 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262CBF11 d:FF r:FFBF     m:95 A002 1AB518 139AF8 0445BA05482C3A08
2013.09.18 21:12:16.243 3: HMLAN_Parse: HMLAN1 R:R327CB45F stat:0030 t:262CBF17 d:04 r:FFBF     m:95 A002 1AB518 139AF8 0445BA05482C3A08
2013.09.18 21:12:16.244 3: HMLAN_Parse: HMLAN1 AES code rejected for 139AF8 48
2013.09.18 21:12:18.615 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262CCC0C d:FF r:FFBF     m:96 A410 1AB518 139AF8 06014600
2013.09.18 21:12:27.640 3: HMLAN_Send:  HMLAN1 I:K
2013.09.18 21:12:27.644 3: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061517 d:139AF8 O:139AF8 t:262CEF58 IDcnt:000F

2013.09.18 21:12:29.476 2: CUL_HM set RolloSchalter02 pct 40
2013.09.18 21:12:29.477 3: HMLAN_Send:  HMLAN1 S:S327CEC66 stat:  00 t:00000000 d:01 r:327CEC66 m:96 A011 139AF8 1AB518 020150
2013.09.18 21:12:29.654 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262CF71B d:FF r:FFBF     m:96 A002 1AB518 139AF8 04ED6E3EC6BCEA08
2013.09.18 21:12:29.890 3: HMLAN_Parse: HMLAN1 R:R327CEC66 stat:0021 t:262CF720 d:04 r:FFC1     m:96 8002 1AB518 139AF8 010146103FA435060F
2013.09.18 21:12:30.331 3: HMLAN_Parse: HMLAN1 R:E197D4E   stat:0000 t:262CF9D0 d:FF r:FFBA     m:6B 8670 197D4E 000000 00DC3E
2013.09.18 21:12:33.036 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262D0463 d:FF r:FFBD     m:97 A410 1AB518 139AF8 06015000

2013.09.18 21:12:43.377 2: CUL_HM set RolloSchalter02 pct 45
2013.09.18 21:12:43.378 3: HMLAN_Send:  HMLAN1 S:S327D22B3 stat:  00 t:00000000 d:01 r:327D22B3 m:97 A011 139AF8 1AB518 02015A
2013.09.18 21:12:43.553 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262D2D68 d:FF r:FFBE     m:97 A002 1AB518 139AF8 044C83572EF45D08
2013.09.18 21:12:43.790 3: HMLAN_Parse: HMLAN1 R:R327D22B3 stat:0021 t:262D2D6E d:04 r:FFC1     m:97 8002 1AB518 139AF8 010150103FCC8AE13F
2013.09.18 21:12:46.861 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262D3A66 d:FF r:FFBF     m:98 A410 1AB518 139AF8 06015A00

2013.09.18 21:12:51.459 2: CUL_HM set RolloSchalter02 pct 50
2013.09.18 21:12:51.460 3: HMLAN_Send:  HMLAN1 S:S327D4245 stat:  00 t:00000000 d:01 r:327D4245 m:98 A011 139AF8 1AB518 020164
2013.09.18 21:12:51.636 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262D4CFB d:FF r:FFBE     m:98 A002 1AB518 139AF8 04E98EF734D00708
2013.09.18 21:12:51.872 3: HMLAN_Parse: HMLAN1 R:R327D4245 stat:0021 t:262D4D01 d:04 r:FFC1     m:98 8002 1AB518 139AF8 01015A103FCAE09ED0
2013.09.18 21:12:52.642 3: HMLAN_Send:  HMLAN1 I:K
2013.09.18 21:12:52.646 3: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061517 d:139AF8 O:139AF8 t:262D5106 IDcnt:000F
2013.09.18 21:12:55.017 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262D5A44 d:FF r:FFBE     m:99 A410 1AB518 139AF8 06016400

2013.09.18 21:13:00.474 2: CUL_HM set RolloSchalter02 pct 60
2013.09.18 21:13:00.475 3: HMLAN_Send:  HMLAN1 S:S327D657C stat:  00 t:00000000 d:01 r:327D657C m:99 A011 139AF8 1AB518 020178
2013.09.18 21:13:00.651 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262D7035 d:FF r:FFBD     m:99 A002 1AB518 139AF8 04C9EE1C81FE3508
2013.09.18 21:13:00.888 3: HMLAN_Parse: HMLAN1 R:R327D657C stat:0021 t:262D703B d:04 r:FFC0     m:99 8002 1AB518 139AF8 01016410406CEC7E2C
2013.09.18 21:13:05.260 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0000 t:262D8249 d:FF r:FFC0     m:9A A410 1AB518 139AF8 06017800

2013.09.18 21:13:08.981 2: CUL_HM set RolloSchalter02 pct 0
2013.09.18 21:13:08.982 3: HMLAN_Send:  HMLAN1 S:S327D86B7 stat:  00 t:00000000 d:01 r:327D86B7 m:9A A011 139AF8 1AB518 020100
2013.09.18 21:13:09.158 3: HMLAN_Parse: HMLAN1 R:E1AB518   stat:0100 t:262D9172 d:FF r:FFC0     m:9A A002 1AB518 139AF8 049984CF8D6A8608
2013.09.18 21:13:10.090 3: HMLAN_Parse: HMLAN1 R:R327D86B7 stat:0030 t:262D9177 d:04 r:FFC0     m:9A A002 1AB518 139AF8 049984CF8D6A8608
2013.09.18 21:13:10.091 3: HMLAN_Parse: HMLAN1 AES code rejected for 139AF8 48


Augenscheinlich gibt es Situationen mit reject und welche ohne. Aber zumindest im Moment scheint alles zu funktionieren.

Zum weiter Testen habe ich nun 6 von den 8 Aktoren auf sign eingestellt, logfile läuft sowieso immer mit. Sollte noch was vorkommen, schick ich weitere Einträge. Auf jeden Fall an der Stelle ein rießiges Dankeschön für Deinen unermüdlichen Einsatz und Hilfe, das ist echt Klasse!

Beste Grüße,
Andy.

[martinp876]

hi Andy,

ist befremdlich.
1) danke für die logs, sehr gut für die Auswertung
2) ALLE deine Kommandos wurden ausgeführt, auch die mit AES reject
3)die AES reject zeigen kein ACK, werden dennoch bearbeitet.
4)bei AES reject wird (bisher) nicht wiederholt. Die nächste Version wird wiederholen

Version 3924 hat ein verbessertes handling
- protEvt_AESerrReject zählung
- Wiederholung von messages wenn AES Probleme gemeldet werden

gerne noch einmal einen log nach einem test... .und bitte prüfen, dass die Zähler korrekt arbeiten

Danke Martin

[gandy]

Hi Martin,

ok, werd ich machen - den letzten Punkt hab ich aber noch nicht verstanden, wie achte ich auf korrekte Funktion der Zähler?

Grüße,
Andy.

[martinp876]

prüfen, ob die proto-events auch inkrementieren...

[gandy]

Mit dem Thread Link kommt die Auflösung des Ganzen, damit ist dieses Thema aus meiner Sicht abgeschlossen, auf zum Nächsten :-)

Grüße,
Andy.