AES verschluesselte Kommunikation

retro1982 · October 2, 2016, 5:18pm

Hi,

Ich habe das Problem dass wenn ich die AES verschluesselung der Devices Aktiviere (im Homematic Konfigurator die Kanaele auf gesichert stelle) habe ich ein seltsames Verhalten.

Einerseits kommt dann bei jedem Befehl der an zum Beispiel an einen Dimmer geschickt wird die Fehlermeldung:

Module HomeMatic BidCoS: LAN-Konfigurationsadapter “My-HM-CFG-LAN”: Warning: AES handshake was not successful:

Andererseits schaltet der Schalter dann! Aber es wird versucht den Befehl 3x zu senden.

Was ich gemacht habe ist folgendes (zum testen).

Neues Geraet (hier ein Wandthermostat) in Betrieb genommen (resetten sollte auch reichen).
Geraet angelert.
… Geraet kann ohne prob gesteuert werden.
Kanaele auf gesichert gestellt
… Kriege die fehlermeldung jedes mal ( auch das 3 malige resenden) aber der dimmer reagiert.
Kanaele wieder auf standart
… keine probleme

Hab ich hier etwas uebersehen oder falsch gemacht???

Hoffe auf euere Hilfe.
LG,
Martin

wbuh · October 2, 2016, 5:18pm

Gleiche Beobachtung bei mir auch.
Eigentlich ist es fast der einzige Eintragstyp im Errorlog.
Was auch interessant ist: ich setze zwei HM LAN Config Adapter ein.
Beide mit gleichem Firmware-Stand (0.964, DHCP off). Das Problem hat jedoch nur einer von beiden (Gerät ist gerade vor zwei Monaten eingebaut worden).
Der Adapter ist als default Adapter eingestellt.
Log sieht bei mir aus wie folgt:

08/04/15 03:37:58.608 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D2DA6101C5766FD1D5F060xxxxx 08/04/15 06:21:23.551 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D76A6101C56DDFD1D5F060xxxxx 08/04/15 06:21:24.106 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D76A6101C56DDFD1D5F060xxxxx 08/04/15 06:21:24.404 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D76A6101C56DDFD1D5F060xxxxx 08/04/15 06:30:04.668 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C54A011FD1D5F1B43890xxxxx 08/04/15 11:59:54.638 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D93A6101C5766FD1D5F060xxxxx 08/04/15 12:40:47.641 Warning: Connection to client number 442450 closed (3). 08/04/15 15:28:23.630 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DDFA6101C5A05FD1D5F060xxxxx 08/04/15 17:55:23.538 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C5A05FD1D5F060xxxxx 08/04/15 17:55:23.990 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C5A05FD1D5F060xxxxx 08/04/15 17:55:24.726 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C5A05FD1D5F060xxxxx 08/04/15 17:55:36.931 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C56DDFD1D5F060xxxxx 08/04/15 17:55:37.642 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C56DDFD1D5F060xxxxx 08/04/15 17:55:38.066 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C56DDFD1D5F060xxxxx 08/04/15 20:37:23.148 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C5766FD1D5F060xxxxx 08/04/15 20:37:23.452 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C5766FD1D5F060xxxxx 08/04/15 20:37:24.085 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0DFFA6101C5766FD1D5F060xxxxx 08/04/15 21:29:06.019 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C9EA011FD1D5F1B434B0xxxxx 08/04/15 21:29:06.125 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C6EA011FD1D5F1B437E0xxxxx 08/04/15 21:29:12.342 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C59A011FD1D5F1B435F0xxxxx 08/04/15 21:53:58.393 Warning: Connection to client number 451252 closed (3). 08/04/15 21:56:50.295 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 1903A004FD1D5F2697E88A94FF12A0E638342EF15B1A0E9xxxxx 08/05/15 02:24:53.569 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D48A6101C5766FD1D5F060xxxxx 08/05/15 05:40:33.306 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Warning: AES handshake was not successful: 0D8BA6101C56DDFD1D5F060xxxxx 08/05/15 06:30:00.707 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C9FA011FD1D5F1B434B0xxxxx 08/05/15 06:30:04.210 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C5AA011FD1D5F1B435Fxxxxx 08/05/15 06:30:04.314 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "HM-CFG-LAN-EG": Info: No response to packet after 3 tries: 0C56A011FD1D5F1B43890xxxxx 08/05/15 06:30:10.011 Warning: Connection to client number 459465 closed (3)

Die Connection to client closed Fälle sind (vermute ich) aus dem openHAB Binding.
Auch bei mir hab ich bislang nicht festgestellt, dass es eine spürbare Auswirkung auf Abläufe gehabt hätte.

retro1982 · October 2, 2016, 5:18pm

Hi,

Vielen dank fuer die info!

ich habe inzwischen auch den master von github gebildet, aber gleiches ergebnis.

Kann irgendjemand verifizieren ob es ueberhaupt jemals funktioniert hat, wenn ja kann ich ja den commit suchen ab den es nicht mehr funktioniert.
Nur waere es nett wenn mir irgendjemand bestaetigien koennte dass es jemals funktioniert hat.

Vielleicht hat noch jemand eine Idee ?

Lg,
Martin

wbuh · October 2, 2016, 5:18pm

Was genau meinst Du mit “ob es jemals funktioniert hat”?
Wenn Du wissen möchtest, ob Wandthermostate in homegear funktionieren: ja, trotz der handshake Problem Meldung.
Verschlüsselte Kommunikation bzw. AES Kommunikation mit Devices geht ebenfalls problemlos.

Das von mir geschilderte Log ist lediglich eine Beobachtung. Eine Einschränkung in der Funktion oder Auswirkung habe ich bislang noch nicht feststellen können.

Verschlüsselt ist die Kommunikation trotz AES Handshake Meldung in jedem Fall, da homegear nur mit aktivierter Verschlüsselung arbeitet (was nichts mit der AES Aktivierung bei einigen Komponenten zu tun hat). Wenn man die Kommunikation zwischen Komponenten und einen HM Config Lan Adapter mit einem LAN COnfig Adapter mitliest, sieht man sehr schnell den unterschied zwischen den offenen und verschlüsselten Datenpaketen.

sathya · October 2, 2016, 5:18pm

Hallo Martin,

generell sollte es funktionieren. Gelegentlich dürfen die Handshake-Fehlermeldungen auch im Log auftauchen. Gerade die Unterputzaktoren haben einen katastrophalen Empfang. Da beim Handshake mehrere Pakete gesendet werden, schlägt dieser dann oft fehl. Ohne Handshake klappt es einwandfrei, weil nur ein Paket gesendet wird (dieses gegebenenfalls bis zu 10 mal bevor etwas im Error-Log auftaucht). Teste mal, ob es mit einem Aktor problemlos klappt, der etwa ein bis zwei Meter (nicht näher) vom HM-CFG-LAN entfernt ist. Falls da jeder Handshake fehlschlägt, ist tatsächlich etwas faul und wir müssen uns das genauer ansehen. Da der Aktor schaltet, kann es sich aber nur um ein kleines und schnell lösbares Problem in der Vearbeitung der Rückmeldung handeln. Bis auf den Logeintrag ist alles in Ordnung und auch verschlüsselt.

Viele Grüße

Sathya

retro1982 · October 2, 2016, 5:19pm

Hi,

war beruflich im Ausland, darum antworte ich erst jetzt.

Das Problem das ich habe ist folgendes. Wenn der AES handshake fehlschlaegt gibt:

eine Fehlermeldung , das ist ja ok.
wird das Antwort packet nicht mehr weiter von HomeGear verarbeitet
wird das Packet versucht zu resenden (3 oder 5 mal)
wird das Device als inaktive makiert was dazu fuhrt dass
staendig versucht wird das device zu erreichen (inc resend)

das fuehrt bei mir zu der 1 prozent warnung und nix geht mehr (nach ca 5 - 10 Minuten)

Also nochmal zusammengefasst,

die Fehlermeldung stoert nicht, aber der Fehler schon

LG,
Martin

retro1982 · October 2, 2016, 5:19pm

Hier noch ein log damit man den fehler besser sieht der bei mir auftritt!


09/25/15 00:48:03.729 Loading RPC server settings from /etc/homegear/rpcservers.conf
09/25/15 00:48:03.730 Loading RPC client settings from /etc/homegear/rpcclients.conf
09/25/15 00:48:03.740 Info: Loading family module mod_miscellaneous.so
09/25/15 00:48:03.765 Info: Loading family module mod_sonos.so
09/25/15 00:48:03.794 Info: Loading family module mod_insteon.so
09/25/15 00:48:03.826 Info: Loading family module mod_homematicwired.so
09/25/15 00:48:03.856 Info: Loading family module mod_homematicbidcos.so
09/25/15 00:48:03.897 Info: Loading family module mod_philipshue.so
09/25/15 00:48:03.921 Info: Loading family module mod_max.so
09/25/15 00:48:03.964 Info: Backing up database...
09/25/15 00:48:04.057 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Enabling AES encryption for communication
 with HM-CFG-LAN.
09/25/15 00:48:04.058 Initializing database...
09/25/15 00:48:04.062 Initializing family controller...
09/25/15 00:48:04.063 Module HomeMatic BidCoS: Loading XML RPC devices...
09/25/15 00:48:04.851 Info: Not initializing device family HomeMatic Wired, because no physical interface was found.
09/25/15 00:48:04.851 Info: Not initializing device family INSTEON, because no physical interface was found.
09/25/15 00:48:04.851 Info: Not initializing device family MAX!, because no physical interface was found.
09/25/15 00:48:04.851 Info: Not initializing device family Philips hue, because no physical interface was found.
09/25/15 00:48:04.851 Info: Not initializing device family Sonos, because no physical interface was found.
09/25/15 00:48:04.851 Module Miscellaneous: Loading XML RPC devices...
09/25/15 00:48:04.857 Loading devices...
09/25/15 00:48:04.857 Module HomeMatic BidCoS: Loading HomeMatic BidCoS device 2
09/25/15 00:48:04.860 Module HomeMatic BidCoS: Loading HomeMatic BidCoS device 4
09/25/15 00:48:04.861 Module HomeMatic BidCoS: Loading peer 1
09/25/15 00:48:04.898 Module Miscellaneous: Loading Miscellaneous device 3
09/25/15 00:48:04.899 Start listening for packets...
09/25/15 00:48:04.901 Initializing RPC client...
09/25/15 00:48:04.901 Starting XML RPC server RPCServer1 listening on 0.0.0.0:2001...
09/25/15 00:48:04.901 Info: Connecting to host 192.168.0.11 on port 1000...
09/25/15 00:48:04.908 RPC Server (Port 2001): Info: RPC Server started listening on address 0.0.0.0 and port 2001
09/25/15 00:48:04.909 Starting XML RPC server RPCServer2 listening on 0.0.0.0:2002, SSL enabled...
09/25/15 00:48:04.910 Info: Connected to host 192.168.0.11 on port 1000. Client number is: 0
09/25/15 00:48:04.917 Starting XML RPC server RPCServer3 listening on 0.0.0.0:2003, SSL enabled, authentification enabled...
09/25/15 00:48:04.918 RPC Server (Port 2002): Info: RPC Server started listening on address 0.0.0.0 and port 2002
09/25/15 00:48:04.924 Starting CLI server...
09/25/15 00:48:04.924 RPC Server (Port 2003): Info: RPC Server started listening on address 0.0.0.0 and port 2003
09/25/15 00:48:04.924 Initializing event handler...
09/25/15 00:48:04.925 Loading events...
09/25/15 00:48:04.925 Startup complete. Waiting for physical interfaces to connect.
09/25/15 00:48:05.926 All physical interfaces are connected now.
09/25/15 00:48:05.926 Starting UPnP server...
09/25/15 00:48:05.927 Info: UPnP server: Binding to address: 10.0.2.15
09/25/15 00:48:05.929 UPnP Server: Info: Started listening.
09/25/15 00:48:10.872 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:10.872 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:10.870 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9CB4412BD82C31D3EA01C600
09/25/15 00:48:11.095 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x48): 119CA00231D3EA2BD82C043A890A0B941504
09/25/15 00:48:11.320 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x49): 0E9C800231D3EA2BD82C00C9F3D6C2
09/25/15 00:48:11.540 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:11.541 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:11.539 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9DA2412BD82CXXXXXX01C600
09/25/15 00:48:11.659 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 1003A001XXXXXX2BD82
C00040000000000
09/25/15 00:48:12.012 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9DA2412BD82CXXXXXX01C600
09/25/15 00:48:12.268 Module HomeMatic BidCoS: Info: Parameter CYCLIC_INFO_MSG of peer 1 and channel 0 was set to 0x01.
09/25/15 00:48:12.268 Module HomeMatic BidCoS: Info: Parameter SABOTAGE_MSG of peer 1 and channel 0 was set to 0x01.
09/25/15 00:48:12.266 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 1A03A0102BD82CXXXXXX02020109010ABA0BBE0CEF10011406000
0
09/25/15 00:48:12.385 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 1004A001XXXXXX2BD82
C01040000000001
09/25/15 00:48:12.889 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 1604A0102BD82CXXXXXX020801206C2100226430060000
09/25/15 00:48:13.008 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 0B05A001XXXXXX2BD82
C0103
09/25/15 00:48:13.601 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 1205A0102BD82CXXXXXX0131D3EA0300000000
09/25/15 00:48:13.720 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 0B0DA004XXXXXX2BD82
C0100
09/25/15 00:48:14.572 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: No response to packet after 3 tries: 190D
A004XXXXXX2BD82C52D1686B25BF288F9D566956CA105D05
09/25/15 00:48:17.175 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:17.175 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:17.174 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9EB4412BD82C31D3EA01C700
09/25/15 00:48:17.400 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x49): 119EA00231D3EA2BD82C04013C0B69477304
09/25/15 00:48:17.625 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x49): 0E9E800231D3EA2BD82C00786AAEA7
09/25/15 00:48:17.851 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:17.852 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:17.850 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x56): 0C9FA2412BD82CXXXXXX01C700
09/25/15 00:48:17.970 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 0B0DA004XXXXXX2BD82
C0100
09/25/15 00:48:18.660 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: No response to packet after 3 tries: 190D
A004XXXXXX2BD82CE9ED1DBEEAA779AEB9205BF8F2F2DAB6
09/25/15 00:48:23.284 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:23.285 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:23.283 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x58): 0CA0B4412BD82C31D3EA01C800
09/25/15 00:48:23.518 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x48): 11A0A00231D3EA2BD82C0426710AB77CC104
09/25/15 00:48:23.743 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x48): 0EA0800231D3EA2BD82C009828EF8B
09/25/15 00:48:10.870 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9CB4412BD82C31D3EA01C600
09/25/15 00:48:11.095 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x48): 119CA00231D3EA2BD82C043A890A0B941504
09/25/15 00:48:11.320 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x49): 0E9C800231D3EA2BD82C00C9F3D6C2
09/25/15 00:48:11.540 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:11.541 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:11.539 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9DA2412BD82CXXXXXX01C600
09/25/15 00:48:11.659 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 1003A001XXXXXX2BD82
C00040000000000
09/25/15 00:48:12.012 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9DA2412BD82CXXXXXX01C600
09/25/15 00:48:12.268 Module HomeMatic BidCoS: Info: Parameter CYCLIC_INFO_MSG of peer 1 and channel 0 was set to 0x01.
09/25/15 00:48:12.268 Module HomeMatic BidCoS: Info: Parameter SABOTAGE_MSG of peer 1 and channel 0 was set to 0x01.
09/25/15 00:48:12.266 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 1A03A0102BD82CXXXXXX02020109010ABA0BBE0CEF10011406000
0
09/25/15 00:48:12.385 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 1004A001XXXXXX2BD82
C01040000000001
09/25/15 00:48:12.889 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 1604A0102BD82CXXXXXX020801206C2100226430060000
09/25/15 00:48:13.008 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 0B05A001XXXXXX2BD82
C0103
09/25/15 00:48:13.601 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 1205A0102BD82CXXXXXX0131D3EA0300000000
09/25/15 00:48:13.720 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 0B0DA004XXXXXX2BD82
C0100
09/25/15 00:48:14.572 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: No response to packet after 3 tries: 190D
A004XXXXXX2BD82C52D1686B25BF288F9D566956CA105D05
09/25/15 00:48:17.175 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:17.175 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:17.174 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x57): 0C9EB4412BD82C31D3EA01C700
09/25/15 00:48:17.400 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x49): 119EA00231D3EA2BD82C04013C0B69477304
09/25/15 00:48:17.625 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x49): 0E9E800231D3EA2BD82C00786AAEA7
09/25/15 00:48:17.851 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:17.852 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:17.850 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x56): 0C9FA2412BD82CXXXXXX01C700
09/25/15 00:48:17.970 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: Sending (LEQ0384530): 0B0DA004XXXXXX2BD82
C0100
09/25/15 00:48:18.660 Module HomeMatic BidCoS: LAN-Konfigurationsadapter "LEQ0384530": Info: No response to packet after 3 tries: 190D
A004XXXXXX2BD82CE9ED1DBEEAA779AEB9205BF8F2F2DAB6
09/25/15 00:48:23.284 Module HomeMatic BidCoS: Info: LOWBAT on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was 
set to 0x00.
09/25/15 00:48:23.285 Module HomeMatic BidCoS: Info: STATE on channel 1 of HomeMatic BidCoS peer 1 with serial number LEQ0566861 was s
et to 0x00.
09/25/15 00:48:23.283 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x58): 0CA0B4412BD82C31D3EA01C800
09/25/15 00:48:23.518 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x48): 11A0A00231D3EA2BD82C0426710AB77CC104
09/25/15 00:48:23.743 HomeMatic BidCoS packet received (LEQ0384530, RSSI: 0x48): 0EA0800231D3EA2BD82C009828EF8B

Ich hoffe das hilft ein wenig!
LG Martin

sathya · October 2, 2016, 5:19pm

Hallo Martin,

Klar!

Jupp. Das Log zeigt A004-Pakete. Das heißt, der Anlernvorgang wurde noch nicht vollständig abgeschlossen. Auch CONFIG_PENDING müsste gesetzt sein. Mach mal folgendes:

sudo homegear -r
fs 0
ds c
ls

Merk dir die ID des Peers mit der Adresse “2BD82C”. Jetzt resette das Gerät:

ps PEERID
queues clear
unselect
/* ANLERNTASTE AM GERÄT DRÜCKEN */
prs PEERID

Jetzt sollte das Gerät neu starten - die LED leuchtet in allen drei Farben auf.

Wieder anlernen:

pon

Und wieder die Anlerntaste am Gerät drücken. Gib “ls” ein, um zu sehen, ob “Config Pending” noch auf “Yes” steht. Falls das nach 20 Sekunden noch der Fall ist (bzw. wenn die LED aufhört zu blinken), drück noch einmal die Anlerntaste. Je nach Empfang sind manchmal mehrere Versuche erforderlich. Sobald der Anlernvorgang erfolgreich abgeschlossen ist, aktiviere AES auf einem Kanal. Drücke die Anlerntaste am Gerät und warte erneut, bis “Config Pending” auf “No” steht. Das Gerät sollte jetzt eigentlich funktionieren. Falls nicht, schick mir das Log der ganzen Aktion.

Viele Grüße

Sathya

retro1982 · October 2, 2016, 5:19pm

Hi Sathya,

ja super das Problem mit dem Fensterkontakt ist wie du beschrieben hast zu beheben gewesen. War aber leider nicht das Hauptproblem.

Ich hab jetzt mal eine Teststellung bei mir aufgebaut und zwar hab ich folgendes gemacht:

Debian 8 installiert
homegear 0.5.25 installiert
Oracle Java installiert
openhab installiert

Homegear hab ich mal irgendeine Zentrale (keine spezielle ID) erstellen lassen.

Geteste habe ich mit einem HM-TC-IT-WM-W-EU.
Also den HM-TC-IT-WM-W-EU reset, danach angelert.

Als naechsten Schritt hab ich ueber die “HomeMatic-Komponenten konfigurieren” mich mit homegear verbunden und dann alle Kanaele vom Thermostat
auf “gesichert” gestellt.

Danach hab ich noch testweise die Konfig geaendert und uebertragen.

Soweit hat das super hingehauen.

Wenn ich jetzt versuch ueber OpenHab auf channel 2 SET_TEMPERATURE zu setzen krieg ich den AES Fehler.

HMLAN meldet als status 0x0030 und ich hab leider keinen plan mehr was ich vielleicht falsch mache.

Ich hab hier mal die config files und das gesamte log (level 6) angehaengt (rar). Wenn es dir etwas hilft koennte ich auch die komplette VM dir zum download bereitstellen.

Mach ich hier etwas falsch, oder ist vielleicht mein HMLAN adapter hin/verwirrt ?

Ich hab mir mal dann den source angesehen und hab gesehn dass der HMLAN adapter das ganze AES handling macht.

Aja dieses Verhalten ist bei mir 100% reproduzierbar.

Ich habe auch die github HEAD bevor du mit dem projekt splitting angefange hast gebildet (39b73d1cf0b5ed5ab386b769e75831d2477f0141), aber das gleiche ergebnis.

Vielen Dank schon mal im voraus,
Martin

PS.: Ganz tolles Projekt das du ganz allein und sehr professionell aufgezogen hast und vor allem super supportest!
debug.rar (96.1 KB)

retro1982 · October 2, 2016, 5:19pm

Hi nochmal,

ich werd mir jetzt von einem Freund einen anderen HM CFG LAN adapter in den naechsten Tagen ausborgen und damit nochmal testen.

Generelle Frage ist der HM LAN adapter HM-LGW-O-TW-W-EU ein guter ersatz fuer den HM CFG LAN adapter ? Da mir die verwendung von aes wichtig waere kann ich ja keinen CUL oder ahnliches einsetzen … Also wenn mein HM CFG LAN wirklich was haben sollte, was sollte ich mir dann zulegen ?

LG,
Martin

Nachtrag: Habe eben versucht den LAN adapter zu “reseten” indem ich nochmal ein firmware update gemacht habe … hat auch hingehaut aba nix geaendert … bin schoen langsam am verzweifeln, warum es bei mir, reproduzierbar, nicht funktioniert

sathya · October 2, 2016, 5:19pm

Hallo Martin,

ich habe mir das Ganze jetzt einmal näher angeschaut. Und siehe da: Auch bei mir geht es nicht. Ich bekomme ebenfalls 0x30 als Status zurück. Der Handshake ist aber nachgerechnet korrekt! Das scheint also ein Bug im HM-CFG-LAN zu sein. Mit HM-LGW geht es (gerade probiert).

Eigentlich ist aus meiner Sicht generell der HM-CFG-LAN die bessere Wahl, weil das HM-LGW zum Teil aus unerfindlichen Gründen abstürzt (bei mir zu Hause läuft es zur Zeit stabil - warum auch immer). Alternativ kannst du noch den CUL nehmen, der kann bereits undokumentiert AES (allerdings Stand heute muss das Anlernen noch über HM-CFG-LAN oder HM-LGW erfolgen. Und Resends gehen noch nicht). Entsprechend bleibt nur das HM-LGW…

Viele Grüße

Sathya

retro1982 · October 2, 2016, 5:19pm

Hi Sathya,

hab mir jetzt einen HM-LGW gekauft und bin damit soweit ganz zufrieden … wenn er abstuerzt dann is er inerhalb weniger sekunden wieder da.

So das funktioniert jetzt ganz gut.

Habe noch einen Bug gefunden / festgestellt.

Wenn ich den Homematic Konfigurator mit homegear verwende, dann kann ich auf allen Kanaelen aller devices die Verschluesselung aufdrehen.
Damit hats dann natuerlich in einigen Situationen nit hinghaut. Hab mir jetzt zum testen auch die CCU2 gekauft.

Und siehe da wenn ich da mit dem Homematic Konfigurator raufgeh kann ich nicht mehr bei allen geraeten die verschluesselung aktivieren.

Da durfte noch ein kleiner Bug drinne sein…

LG,
Martin

sathya · October 2, 2016, 5:19pm

Hallo Martin,

danke dir, das schaue ich mir an.

Viele Grüße

Sathya