[Gelöst] Keymatic nimmt sporadisch keine Befehle an - AES Problem?

[Capricornus]

Hallo zusammen,

tut mir leid, wenn ich nochmal nachfragen muss, aber ich befürchte, ich habe mit meinem AES größere Probleme. Nicht nur, dass sich der Bewegungsmelder nicht anlernen lässt, auch macht jetzt meine Keymatic sporadisch Ärger.

Ich habe sowohl die Keymatic als auch die Fernbedienung an fhem angelernt und nutze zum Verriegeln/Entriegeln ein notify, welches auf die jeweiligen Tasten reagiert. Das funktioniert soweit problemlos - abgesehen von einer kleinen Zeitverzögerung kann ich sowohl über Fernbedienung als auch über fhem den Türantrieb steuern. Jetzt ist es mir aber einige Male passiert, dass die Keymatic einfach nicht reagiert. Der Event Monitor sagt, dass der Befehl der Fernbedienung empfangen und auch richtig an die Keymatic gesendet wurde, diese daraufhin aber nicht antwortet. Fhem steht derweil auf set_<gewählterStatus> und alle weiteren Commands stauen sich auf.

Anbei ein Rohdaten-Mitschnitt von einem erfolgreichen Entriegelungsvorgang und direkt danach einem fehlgeschlagenen Öffnungsvorgang:

Code Auswählen Erweitern

2016.01.08 18:43:41.206 4: CUL_Parse: COC A 0B 47 A440 386B94 123456 011332 -49
2016.01.08 18:43:41.308 4: CUL_send:  COCAs 0D 47 8002 123456 386B94 01040000
2016.01.08 18:43:41.408 4: CUL_send:  COCAs 0D 06 B011 221287 38FBC5 800101FF
2016.01.08 18:43:41.939 4: CUL_Parse: COC A 11 06 A002 38FBC5 221287 04E790EEC4EBA80210 -66
2016.01.08 18:43:42.041 4: CUL_send:  COCAs 19 06 A003 221287 38FBC5 c774baba59357de287903ef65574696a
2016.01.08 18:43:42.228 4: CUL_Parse: COC A 12 06 8002 38FBC5 221287 0101001042FAFFA78211 -65.5
2016.01.08 18:43:46.463 4: CUL_Parse: COC A 0D 07 A410 38FBC5 221287 0601010011 -65.5
2016.01.08 18:43:46.568 4: CUL_send:  COCAs 11 07 A002 221287 38FBC5 04423279FE778802
2016.01.08 18:43:46.761 4: CUL_Parse: COC A 19 07 A403 38FBC5 221287 98272057EE9CAE52137BE9B11A50141112 -65
2016.01.08 18:43:46.865 4: CUL_send:  COCAs 0E 07 8002 221287 38FBC5 00f3b1a164
2016.01.08 18:43:56.954 4: CUL_Parse: COC A 0B 48 8440 386B94 123456 411434 -48
2016.01.08 18:43:57.040 4: CUL_send:  COCAs 0D 08 B011 221287 38FBC5 8001C8FF
2016.01.08 18:43:57.455 4: CUL_Parse: COC A 0B 4A A040 386B94 123456 41142E -51
2016.01.08 18:43:57.557 4: CUL_send:  COCAs 0D 4A 8002 123456 386B94 0104C800
2016.01.08 18:43:57.676 4: CUL_Parse: COC A 11 08 A002 38FBC5 221287 04D49C035D2F180212 -65
2016.01.08 18:43:57.778 4: CUL_send:  COCAs 19 08 A003 221287 38FBC5 9ff5aa2ac7f36735e90ff4d6ae338881
2016.01.08 18:44:26.749 4: CUL_Parse: COC A 14 32 845E 38B78F 000000 8029C0000000000008EF01F8 -78

Danach steht die Keymatic auf folgendem Status:

Code Auswählen Erweitern

Internals:
   COC_MSGCNT 15
   COC_RAWMSG A1108A00238FBC522128704D49C035D2F1802::-65:COC
   COC_RSSI   -65
   COC_TIME   2016-01-08 18:43:57
   DEF        38FBC5
   IODev      COC
   LASTInputDev COC
   MSGCNT     15
   NAME       KeyMatic
   NR         71
   NTFY_ORDER 50-KeyMatic
   STATE      set_open
   TYPE       CUL_HM
   lastMsg    No:08 - t:02 s:38FBC5 d:221287 04D49C035D2F1802
   protCmdPend 1 CMDs pending
   protEvt_AESCom-ok 2 last_at:2016-01-08 18:43:46
   protLastRcv 2016-01-08 18:43:57
   protSnd    12 last_at:2016-01-08 18:43:57
   protState  CMDs_processing...
   rssi_COC   avg:-64.75 min:-66 max:-64 lst:-66 cnt:4
   rssi_at_COC avg:-65.27 min:-66 max:-65 lst:-65 cnt:11
   Readings:
     2016-01-08 18:43:42   CommandAccepted yes
     2016-01-07 18:12:53   D-firmware      2.5
     2016-01-07 18:12:53   D-serialNr      MEQxxxxxxx
     2016-01-08 16:50:26   PairedTo        0x221287
     2016-01-07 17:58:49   R-angelLocked   720.07200720072 deg
     2016-01-07 17:58:49   R-angelMax      1275.12751275128 deg
     2016-01-07 17:58:49   R-angelOpen     270.02700270027 deg
     2016-01-07 18:12:58   R-pairCentral   0x221287
     2016-01-07 17:58:49   R-setupPosition 360.03600360036 deg
     2016-01-08 16:50:26   RegL_00.        02:01 03:19 0A:22 0B:12 0C:87 00:00
     2016-01-08 16:50:27   RegL_01.        14:00 15:64 16:01 17:18 18:12 19:55 1A:30 1F:00  00:00
     2016-01-08 16:50:28   RegL_03.Fernbedienung_lock 03:00 04:32 05:64 07:FF 0B:66 83:00 84:32 85:64  87:FF 8B:66 00:00
     2016-01-08 16:50:28   RegL_03.Fernbedienung_unlock 03:00 04:32 05:64 07:FF 0B:33 83:00 84:32 85:64  87:FF 8B:88 00:00
     2016-01-08 18:43:46   aesCommToDev    ok
     2016-01-08 18:43:57   aesKeyNbr       02
     2016-01-08 18:43:46   battery         ok
     2016-01-08 18:43:46   direction       none
     2016-01-08 18:43:46   error           none
     2016-01-08 18:43:46   lock            unlocked
     2016-01-08 18:43:46   recentStateType info
     2016-01-08 18:43:57   state           set_open
     2016-01-08 18:43:46   uncertain       no
   cmdStack:
     ++A01122128738FBC58001C8FF
   Helper:
     AESreqAck  A84A2F1A
     HM_CMDNR   8
     cSnd       1122128738FBC5800101FF,1122128738FBC58001C8FF
     mId        0026
     rxType     2
     Expert:
       def        1
       det        0
       raw        1
       tpl        0
     Io:
       newChn     +38FBC5,01,01,02
       nextSend   1452275037.77757
       rxt        0
       vccu       VCCU
       p:
         38FBC5
         01
         01
         02
       prefIO:
         COC
     Mrssi:
       mNo        08
       Io:
         COC        -63
     Prt:
       bErr       0
       sProc      1
     Q:
       qReqConf
       qReqStat
     Role:
       chn        1
       dev        1
     Rssi:
       Coc:
         avg        -64.75
         cnt        4
         lst        -66
         max        -64
         min        -66
       At_coc:
         avg        -65.2727272727273
         cnt        11
         lst        -65
         max        -65
         min        -66
   Role:
Attributes:
   IODev      COC
   IOgrp      VCCU:COC
   aesCommReq 1
   autoReadReg 0_off
   expert     2_raw
   firmware   2.5
   model      HM-SEC-KEY-S
   msgRepeat  1
   peerIDs    00000000,
   room       5.1_Sicherheit
   serialNr   MEQxxxxxx
   subType    keyMatic
   webCmd     lock:inhibit on:inhibit off:unlock

Und hier zum direkten Vergleich ein Event Monitor Auszug von einem erfolgreichen Verriegeln.

Code Auswählen Erweitern

2016-01-08 18:32:16 CUL_HM Fernbedienung battery: ok
2016-01-08 18:32:16 CUL_HM Fernbedienung CMDs_done
2016-01-08 18:32:16 CUL_HM Fernbedienung Fernbedienung_lock Short
2016-01-08 18:32:16 CUL_HM KeyMatic set_lock
2016-01-08 18:32:16 CUL_HM KeyMatic set_lock
2016-01-08 18:32:16 CUL_HM KeyMatic set_lock
2016-01-08 18:32:16 CUL_HM Fernbedienung_lock Short (to virtueller_Aktor)
2016-01-08 18:32:16 CUL_HM Fernbedienung_lock trigger: Short_11
2016-01-08 18:32:16 CUL_HM Fernbedienung_lock trigger_cnt: 11
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock ON
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock trigLast: Fernbedienung_lock:short
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock trig_Fernbedienung_lock: short
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock virtActState: ON
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock virtActTrigNo: 11
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock virtActTrigRpt: 1
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock virtActTrigType: short_Release
2016-01-08 18:32:16 CUL_HM virtuell_Fernbedienung_lock virtActTrigger: Fernbedienung_lock
2016-01-08 18:32:17 CUL_HM KeyMatic aesKeyNbr: 02
2016-01-08 18:32:17 CUL_HM KeyMatic aesCommToDev: ok
2016-01-08 18:32:17 CUL_HM KeyMatic battery: ok
2016-01-08 18:32:17 CUL_HM KeyMatic direction: down
2016-01-08 18:32:17 CUL_HM KeyMatic error: none
2016-01-08 18:32:17 CUL_HM KeyMatic lock: unlocked
2016-01-08 18:32:17 CUL_HM KeyMatic unlocked (uncertain)
2016-01-08 18:32:17 CUL_HM KeyMatic uncertain: yes
2016-01-08 18:32:17 CUL_HM KeyMatic aesKeyNbr: 02
2016-01-08 18:32:18 CUL_HM KeyMatic aesCommToDev: ok
2016-01-08 18:32:18 CUL_HM KeyMatic battery: ok
2016-01-08 18:32:18 CUL_HM KeyMatic direction: down
2016-01-08 18:32:18 CUL_HM KeyMatic error: none
2016-01-08 18:32:18 CUL_HM KeyMatic lock: unlocked
2016-01-08 18:32:18 CUL_HM KeyMatic unlocked (uncertain)
2016-01-08 18:32:18 CUL_HM KeyMatic uncertain: yes
2016-01-08 18:32:18 CUL_HM KeyMatic aesKeyNbr: 02
2016-01-08 18:32:18 CUL_HM KeyMatic aesCommToDev: ok
2016-01-08 18:32:18 CUL_HM KeyMatic battery: ok
2016-01-08 18:32:18 CUL_HM KeyMatic direction: down
2016-01-08 18:32:18 CUL_HM KeyMatic error: none
2016-01-08 18:32:18 CUL_HM KeyMatic lock: unlocked
2016-01-08 18:32:18 CUL_HM KeyMatic unlocked (uncertain)
2016-01-08 18:32:18 CUL_HM KeyMatic uncertain: yes
2016-01-08 18:32:22 CUL_HM KeyMatic aesCommToDev: pending
2016-01-08 18:32:22 CUL_HM KeyMatic aesCommToDev: ok
2016-01-08 18:32:22 CUL_HM KeyMatic battery: ok
2016-01-08 18:32:22 CUL_HM KeyMatic direction: none
2016-01-08 18:32:22 CUL_HM KeyMatic error: none
2016-01-08 18:32:22 CUL_HM KeyMatic lock: locked
2016-01-08 18:32:22 CUL_HM KeyMatic locked
2016-01-08 18:32:22 CUL_HM KeyMatic uncertain: no


Zurücksetzen lässt sich der Antrieb nur über ein Löschen der msgEvents, danach funktioniert sie wieder, wie wenn nie etwas gewesen wäre. Ich vermute das Problem irgendwo beim AES, denn ab und an wird der Befehl mit einem zwischenzeitlichen aesCommToDev: fail unterbrochen, ehe dann nach einiger Zeit ein OK kommt und der Befehl durchgeht, siehe hier:

...
2016-01-08 18:55:27 CUL_HM virtuell_Fernbedienung_unlock virtActTrigRpt: 2
2016-01-08 18:55:27 CUL_HM virtuell_Fernbedienung_unlock virtActTrigType: long_Release
2016-01-08 18:55:27 CUL_HM virtuell_Fernbedienung_unlock virtActTrigger: Fernbedienung_unlock
2016-01-08 18:55:31 CUL_HM KeyMatic aesCommToDev: fail
2016-01-08 18:55:31 CUL_HM KeyMatic aesKeyNbr: 02
2016-01-08 18:55:31 CUL_HM KeyMatic aesCommToDev: ok
2016-01-08 18:55:31 CUL_HM KeyMatic battery: ok
...


Hat jemand eine Idee, was hier nicht stimmen könnte? Fehlt ggf. noch ein Attribut?


Viele Grüße,
Martin

[Capricornus]

So,

ich habe das Problem "umgangen", indem ich die Fernbedienung an die Keymatic geppert und die notifys deaktiviert habe. Geholfen hat dieser Beitrag (danke! - auch wenn ich ein wenig anders vorgehen musste). Jetzt kann ich den Türschlossantrieb sowohl über fhem als auch über die Fernbedienung steuern.

Gelöst ist das Thema für mich aber noch nicht ganz, denn offenbar scheint es ja immer noch einen Fehler bei der Kommunikation mit AES zu geben. Hat jemand eine Idee?


Viele Grüße,
Martin