Probleme mit AES nach Auswechseln eines IO-Devices

Begonnen von tndx, 04 März 2019, 10:09:02

Vorheriges Thema - Nächstes Thema
Drucken
Nach unten Seiten1
Benutzer-Aktionen

tndx

04 März 2019, 10:09:02 Letzte Bearbeitung: 05 März 2019, 07:02:28 von tndx
Guten Morgen,

ich habe bis gestern für Homematic eine Kombination aus NanoCUL mit TSCUL-Firmware und HMUARTLGW, angebunden über LAN, benutzt, hat etwa 2 Jahre wunderbar funktioniert. Gestern habe ich den NanoCUL gegen einen weiteren HMUARTLGW, angebunden über USB, ausgetauscht. Seitdem habe ich das Problem, dass die Kommunikation der Sensoren mit aktivierter AES-Signierung, also aesCommReq = 1, nicht klappt.

Code Auswählen

2019-03-04 09:50:37 CUL_HM FlEG_Eingang aesCommToDev: pending
2019-03-04 09:50:37 CUL_HM FlEG_Eingang aesCommToDev: fail
2019-03-04 09:50:37 CUL_HM FlEG_Eingang trig_aes_VCCU: fail:62
2019-03-04 09:50:38 CUL_HM FlEG_Eingang aesCommToDev: pending
2019-03-04 09:50:38 CUL_HM FlEG_Eingang aesCommToDev: fail
2019-03-04 09:50:38 CUL_HM FlEG_Eingang trig_aes_VCCU: fail:62
2019-03-04 09:50:39 CUL_HM FlEG_Eingang aesCommToDev: pending
2019-03-04 09:50:39 CUL_HM FlEG_Eingang aesCommToDev: fail
2019-03-04 09:50:39 CUL_HM FlEG_Eingang trig_aes_VCCU: fail:62
2019-03-04 09:50:41 CUL_HM FlEG_Eingang aesCommToDev: pending
2019-03-04 09:50:41 CUL_HM FlEG_Eingang aesCommToDev: fail
2019-03-04 09:50:41 CUL_HM FlEG_Eingang trig_aes_VCCU: fail:62
2019-03-04 09:50:43 CUL_HM FlEG_Eingang aesCommToDev: pending
2019-03-04 09:50:43 CUL_HM FlEG_Eingang aesCommToDev: fail
2019-03-04 09:50:43 CUL_HM FlEG_Eingang trig_aes_VCCU: fail:62

Ich habe mittlerweile FHEM mehrfach neugestartet, auch die HMUARTLGW abwechselnd abgeklemmt, trotzdem das gleiche Problem. Die Aktoren, die eine Signatur der Zentrale anfordern, funktionieren im Übrigen ohne Probleme:

Code Auswählen
2019-03-04 09:49:43 CUL_HM Keymatic aesCommToDev: pending
2019-03-04 09:49:43 CUL_HM Keymatic aesKeyNbr: 04
2019-03-04 09:49:43 CUL_HM Keymatic aesCommToDev: ok

Hat jemand eine Idee?

frank

#1
04 März 2019, 11:50:44
was sagt fhem.log?
wie sieht ein list vom neuen adapter und der vccu aus?
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

tndx

#2
04 März 2019, 15:43:25
Hi,

hier ist die Kommunikation mit einer Fernbedienung:

Code Auswählen
2019.03.04 15:30:46.678 0: HMUARTLGW myHmUARTUSB recv: 01 05 00 00 46 msg: DA A6 40 400FE5 F3A460 027A
2019.03.04 15:30:46.683 0: HMUARTLGW myHmUARTLGW send: 01 06400FE5000000
2019.03.04 15:30:46.689 0: HMUARTLGW myHmUARTUSB send: 01 06400FE5000000
2019.03.04 15:30:46.700 0: HMUARTLGW myHmUARTLGW recv: 01 05 00 00 35 msg: DA A6 40 400FE5 F3A460 027A
2019.03.04 15:30:46.707 0: HMUARTLGW myHmUARTLGW recv: 01 040701010038FFFFFFFFFFFFFFF9, state 90
2019.03.04 15:30:46.709 0: HMUARTLGW myHmUARTLGW GetSet Ack: 07, state 90
2019.03.04 15:30:46.710 0: HMUARTLGW myHmUARTLGW added peer: 400FE5, aesChannels: FFFFFFFFFFFFFFF9
2019.03.04 15:30:46.712 0: HMUARTLGW myHmUARTLGW send: 01 0A400FE50102
2019.03.04 15:30:46.717 0: HMUARTLGW myHmUARTUSB recv: 01 040701010006FFFFFFFFFFFFFFF9, state 90
2019.03.04 15:30:46.719 0: HMUARTLGW myHmUARTUSB GetSet Ack: 07, state 90
2019.03.04 15:30:46.720 0: HMUARTLGW myHmUARTUSB added peer: 400FE5, aesChannels: FFFFFFFFFFFFFFF9
2019.03.04 15:30:46.721 0: HMUARTLGW myHmUARTUSB send: 01 0A400FE50102
2019.03.04 15:30:46.839 0: HMUARTLGW myHmUARTLGW recv: 01 0401, state 91
2019.03.04 15:30:46.840 0: HMUARTLGW myHmUARTLGW GetSet Ack: 01, state 91
2019.03.04 15:30:46.842 0: HMUARTLGW myHmUARTLGW send: 01 09400FE50102
2019.03.04 15:30:46.851 0: HMUARTLGW myHmUARTLGW recv: 01 0401, state 92
2019.03.04 15:30:46.852 0: HMUARTLGW myHmUARTLGW GetSet Ack: 01, state 92
2019.03.04 15:30:46.853 0: HMUARTLGW myHmUARTLGW send: 01 06400FE5000000
2019.03.04 15:30:46.857 0: HMUARTLGW myHmUARTUSB recv: 01 0401, state 91
2019.03.04 15:30:46.858 0: HMUARTLGW myHmUARTUSB GetSet Ack: 01, state 91
2019.03.04 15:30:46.859 0: HMUARTLGW myHmUARTUSB send: 01 09400FE50102
2019.03.04 15:30:46.864 0: HMUARTLGW myHmUARTLGW recv: 01 040701010038FFFFFFFFFFFFFFF9, state 93
2019.03.04 15:30:46.865 0: HMUARTLGW myHmUARTLGW GetSet Ack: 07, state 93
2019.03.04 15:30:46.866 0: HMUARTLGW myHmUARTLGW added peer: 400FE5, aesChannels: FFFFFFFFFFFFFFF9
2019.03.04 15:30:46.866 0: HMUARTLGW myHmUARTLGW send: 01 07400FE5
2019.03.04 15:30:46.872 0: HMUARTLGW myHmUARTUSB recv: 01 0401, state 92
2019.03.04 15:30:46.872 0: HMUARTLGW myHmUARTUSB GetSet Ack: 01, state 92
2019.03.04 15:30:46.873 0: HMUARTLGW myHmUARTUSB send: 01 06400FE5000000
2019.03.04 15:30:46.879 0: HMUARTLGW myHmUARTLGW recv: 01 040701010037, state 90
2019.03.04 15:30:46.880 0: HMUARTLGW myHmUARTLGW GetSet Ack: 07, state 90
2019.03.04 15:30:46.880 0: HMUARTLGW myHmUARTLGW remove peer: 400FE5
2019.03.04 15:30:46.881 0: HMUARTLGW myHmUARTLGW send: 01 06400FE5000000
2019.03.04 15:30:46.886 0: HMUARTLGW myHmUARTUSB recv: 01 040701010006FFFFFFFFFFFFFFF9, state 93
2019.03.04 15:30:46.887 0: HMUARTLGW myHmUARTUSB GetSet Ack: 07, state 93
2019.03.04 15:30:46.888 0: HMUARTLGW myHmUARTUSB added peer: 400FE5, aesChannels: FFFFFFFFFFFFFFF9
2019.03.04 15:30:46.888 0: HMUARTLGW myHmUARTUSB send: 01 07400FE5
2019.03.04 15:30:46.894 0: HMUARTLGW myHmUARTLGW recv: 01 040701010038FFFFFFFFFFFFFFF9, state 90
2019.03.04 15:30:46.895 0: HMUARTLGW myHmUARTLGW GetSet Ack: 07, state 90
2019.03.04 15:30:46.895 0: HMUARTLGW myHmUARTLGW added peer: 400FE5, aesChannels: FFFFFFFFFFFFFFF9
2019.03.04 15:30:46.897 0: HMUARTLGW myHmUARTLGW send: 01 0A400FE50102
2019.03.04 15:30:46.902 0: HMUARTLGW myHmUARTUSB recv: 01 040701010005, state 90
2019.03.04 15:30:46.903 0: HMUARTLGW myHmUARTUSB GetSet Ack: 07, state 90
2019.03.04 15:30:46.903 0: HMUARTLGW myHmUARTUSB remove peer: 400FE5
2019.03.04 15:30:47.027 0: HMUARTLGW myHmUARTLGW recv: 01 0401, state 91
2019.03.04 15:30:47.028 0: HMUARTLGW myHmUARTLGW GetSet Ack: 01, state 91
2019.03.04 15:30:47.030 0: HMUARTLGW myHmUARTLGW send: 01 09400FE50102
2019.03.04 15:30:47.038 0: HMUARTLGW myHmUARTLGW recv: 01 0401, state 92
2019.03.04 15:30:47.040 0: HMUARTLGW myHmUARTLGW GetSet Ack: 01, state 92
2019.03.04 15:30:47.041 0: HMUARTLGW myHmUARTLGW send: 01 06400FE5000000
2019.03.04 15:30:47.057 0: HMUARTLGW myHmUARTLGW recv: 01 040701010038FFFFFFFFFFFFFFF9, state 93
2019.03.04 15:30:47.058 0: HMUARTLGW myHmUARTLGW GetSet Ack: 07, state 93
2019.03.04 15:30:47.059 0: HMUARTLGW myHmUARTLGW added peer: 400FE5, aesChannels: FFFFFFFFFFFFFFF9
2019.03.04 15:30:47.178 0: HMUARTLGW myHmUARTUSB recv: 01 05 00 00 47 msg: DC A2 40 400FE5 F3A460 027A
2019.03.04 15:30:47.309 0: HMUARTLGW myHmUARTUSB recv: 01 05 00 00 40 msg: DC A0 02 F3A460 400FE5 04495A00005A1600
2019.03.04 15:30:47.443 0: HMUARTLGW myHmUARTUSB recv: 01 05 00 00 47 msg: DC A0 03 400FE5 F3A460 727E0615DCF3B19E2B48390829CA7105
2019.03.04 15:30:47.453 0: HMUARTLGW myHmUARTLGW recv: 01 05 03 00 35 msg: DC A2 40 400FE5 F3A460 027A
2019.03.04 15:30:56.525 0: HMUARTLGW myHmUARTUSB send: 00 08
2019.03.04 15:30:56.534 0: HMUARTLGW myHmUARTUSB recv: 00 040200, state 98
2019.03.04 15:30:56.536 0: HMUARTLGW myHmUARTUSB GetSet Ack: 02, state 98
2019.03.04 15:30:56.536 0: HMUARTLGW myHmUARTUSB roundtrip delay: 0.0067
2019.03.04 15:31:00.589 0: HMUARTLGW myHmUARTLGW send: 00 08
2019.03.04 15:31:00.597 0: HMUARTLGW myHmUARTLGW recv: 00 04020D, state 98
2019.03.04 15:31:00.599 0: HMUARTLGW myHmUARTLGW GetSet Ack: 02, state 98
2019.03.04 15:31:00.599 0: HMUARTLGW myHmUARTLGW roundtrip delay: 0.0056
2019.03.04 15:31:11.532 0: HMUARTLGW myHmUARTUSB send: 00 08
2019.03.04 15:31:11.539 0: HMUARTLGW myHmUARTUSB recv: 00 040200, state 98
2019.03.04 15:31:11.540 0: HMUARTLGW myHmUARTUSB GetSet Ack: 02, state 98
2019.03.04 15:31:11.541 0: HMUARTLGW myHmUARTUSB roundtrip delay: 0.0038
2019.03.04 15:31:15.596 0: HMUARTLGW myHmUARTLGW send: 00 08
2019.03.04 15:31:15.611 0: HMUARTLGW myHmUARTLGW recv: 00 04020D, state 98
2019.03.04 15:31:15.613 0: HMUARTLGW myHmUARTLGW GetSet Ack: 02, state 98
2019.03.04 15:31:15.613 0: HMUARTLGW myHmUARTLGW roundtrip delay: 0.0125
2019.03.04 15:31:26.539 0: HMUARTLGW myHmUARTUSB send: 00 08
2019.03.04 15:31:26.549 0: HMUARTLGW myHmUARTUSB recv: 00 040200, state 98
2019.03.04 15:31:26.550 0: HMUARTLGW myHmUARTUSB GetSet Ack: 02, state 98
2019.03.04 15:31:26.551 0: HMUARTLGW myHmUARTUSB roundtrip delay: 0.0067

Hier ist die Konfiguration der VCCU, bis auf die AES-Keys:

Code Auswählen
Internals:
   DEF        F3A460
   FUUID      5c489f65-f33f-7a7c-523a-b93c1b2fca92609c
   IODev      myHmUARTLGW
   LASTInputDev myHmUARTLGW
   MSGCNT     153
   NAME       VCCU
   NOTIFYDEV  global
   NR         78
   NTFY_ORDER 50-VCCU
   STATE      HMLAN:UAS,myHmUARTLGW:ok,myHmUARTUSB:ok
   TYPE       CUL_HM
   assignedIOs HMLAN,myHmUARTLGW,myHmUARTUSB
   channel_01 VCCU_Btn1
   lastMsg    No:78 - t:02 s:F3A460 d:F2E1ED 00
   myHmUARTLGW_MSGCNT 26
   myHmUARTLGW_RAWMSG 05000043788002F3A460F2E1ED00
   myHmUARTLGW_RSSI -67
   myHmUARTLGW_TIME 2019-03-04 15:35:27
   myHmUARTUSB_MSGCNT 127
   myHmUARTUSB_RAWMSG 0500003F948102F3A460F07A7E00
   myHmUARTUSB_RSSI -63
   myHmUARTUSB_TIME 2019-03-04 15:34:29
   protLastRcv 2019-03-04 15:35:25
   protRcv    100 last_at:2019-03-04 15:35:25
   protRcvB   3 last_at:2019-03-04 15:33:53
   rssi_at_myHmUARTLGW cnt:26 min:-67 max:-66 avg:-66.69 lst:-67
   rssi_at_myHmUARTUSB cnt:127 min:-67 max:-62 avg:-63.13 lst:-63
   READINGS:
     2019-03-04 15:35:25   CommandAccepted yes
     2019-03-04 15:28:56   IOopen          2
     2019-03-04 15:30:47   aesKeyNbr       00
     2019-03-04 15:28:56   state           HMLAN:UAS,myHmUARTLGW:ok,myHmUARTUSB:ok
     2019-03-04 13:40:27   unknown_2BDEE8  received
   helper:
     HM_CMDNR   120
     PONtest    1
     mId        FFF0
     regLst     ,0
     rxType     1
     supp_Pair_Rep 0
     expert:
       def        1
       det        0
       raw        0
       tpl        0
     io:
       nextSend   1551710127.7376
       prefIO     
       vccu       VCCU
       ioList:
         myHmUARTLGW
         myHmUARTUSB
     mRssi:
       mNo        78
       io:
         myHmUARTLGW:
           -63
           -63
         myHmUARTUSB:
     prt:
       bErr       0
       sProc      0
       rspWait:
     q:
       qReqConf   
       qReqStat   
     role:
       dev        1
       vrt        1
     rssi:
       at_myHmUARTLGW:
         avg        -66.6923076923077
         cnt        26
         lst        -67
         max        -66
         min        -67
       at_myHmUARTUSB:
         avg        -63.1338582677165
         cnt        127
         lst        -63
         max        -62
         min        -67
     tmpl:
Attributes:
   IODev      myHmUARTLGW
   IOList     myHmUARTLGW,myHmUARTUSB
   IOgrp      VCCU
   hmKey      01:XXX
   hmKey2     02:YYY
   model      CCU-FHEM
   room       VCCU
   subType    virtual
   webCmd     virtual:update

Hier ist die Konfiguration von myHmUARTUSB:

Code Auswählen
Internals:
   AssignedPeerCnt 5
   CNT        73
   Clients    :CUL_HM:
   DEF        /dev/serial/by-id/usb-Silicon_Labs_CP2102_USB_to_UART_Bridge_Controller_0001-if00-port0@115200
   DEVCNT     210
   DevState   99
   DevType    UART
   DeviceName /dev/serial/by-id/usb-Silicon_Labs_CP2102_USB_to_UART_Bridge_Controller_0001-if00-port0@115200
   FD         118
   FUUID      5c7cdc6e-f33f-7a7c-8996-1cec8932e8bdaa69
   LastOpen   1551709717.86751
   NAME       myHmUARTUSB
   NR         491
   PARTIAL   
   RAWMSG     0501003F7AA25FF2E1EDF3A46001E81E000000000009D8000000
   RSSI       -63
   STATE      opened
   TYPE       HMUARTLGW
   XmitOpen   1
   model      HM-MOD-UART
   msgLoadCurrent 3
   msgLoadHistory 2/-/-/-/-/-/-/-/-/-/-/-
   msgLoadHistoryAbs 2/0/-/-/-/-/-/-/-/-/-/-/-
   owner      F3A460
   owner_CCU  VCCU
   Helper:
     CreditTimer 34
     FW         66561
     Initialized 1
     SendCnt    3
     AckPending:
     LastSendLen:
       3
       3
     Log:
       IDs:
         all
         sys
     PeerQueue:
     PendingCMD:
     RoundTrip:
       Delay      0.00693202018737793
     loadLvl:
       lastHistory 1551710035.40642
   MatchList:
     1:CUL_HM   ^A......................
   Peers:
     11D56B     +11D56B,00,00,00
     21DAB6     +21DAB6,00,00,00
     469A98     +469A98,00,00,00
     510308     +510308,00,00,00
     F2E1ED     +F2E1ED,00,00,00
   READINGS:
     2019-03-04 15:28:55   D-HMIdAssigned  F3A460
     2019-03-04 15:28:55   D-HMIdOriginal  4274FB
     2019-03-04 15:28:55   D-firmware      1.4.1
     2019-03-04 15:28:55   D-serialNr      MEQ1541983
     2019-03-04 15:28:37   D-type          HM-MOD-UART
     2019-03-04 15:28:55   cond            ok
     2019-03-04 15:36:26   load            3
     2019-03-04 15:28:55   loadLvl         low
     2019-03-04 15:28:37   state           opened
   helper:
Attributes:
   hmId       F3A460
   logIDs     all,sys
   room       VCCU

tndx

#3
04 März 2019, 19:35:55
Nachtrag:

Ändere ich bei einem der betroffenen Sensoren von "aesCommReq = 1" zu "aesCommReq = 0" lasse ihn auslösen und ändere dann zurück auf "aesCommReq = 1" funktioniert er von da an problemlos wieder mit AES. In der VCCU wird dann
Code Auswählen
aesKeyNbr 04
eingetragen.

Löst ein problematischer/noch nicht behandelter Sensor aus, gibt es wieder dasselbe Problem und in der VCCU wird dann
Code Auswählen
aesKeyNbr 00
eingetragen.

Es ist schön, einen Workaround zu haben, aber vielleicht kann man ja das eigentlich Problem lösen, damit ich nicht 20 Sensoren einzeln umstellen/auslösen/umstellen muss  ;D

tndx

#4
07 März 2019, 08:33:12
Guten Morgen,

hat wirklich niemand eine Idee?

Mir ist bei der Durchsicht meiner Konfigurationen was aufgefallen, was nicht unbedingt mit dem Problem zu tun hat, aber trotzdem so nicht sein sollte. Bei der VCCU steht nämlich:
Code Auswählen
assignedIOs HMLAN,myHmUARTLGW,myHmUARTUSB
In dem Attribut, in dem ich die IO-Devices angebe, steht aber:
Code Auswählen
IOList     myHmUARTLGW,myHmUARTUSB

Wie kann ich HMLAN ent-assignen?

Otto123

#5
07 März 2019, 10:50:04
Moin,

war mir auch schon aufgefallen, aber ich denke das hat nichts mit deinem Problem zu tun.
Code Auswählen
STATE      HMLAN:UAS,myHmUARTLGW:ok,myHmUARTUSB:ok
Das steht aber UAS was ev. unassigned heisst.
Bedeutet die VCCU sieht den IO mit der gleichen HMId, hat ihn aber selbst nicht in der config.

Also entweder rein oder raus.  ;D

Gruß Otto
Viele Grüße aus Leipzig  ⇉  nächster Stammtisch an der Lindennaundorfer Mühle
RaspberryPi B B+ B2 B3 B3+ ZeroW,HMLAN,HMUART,Homematic,Fritz!Box 7590,WRT3200ACS-OpenWrt,Sonos,VU+,Arduino nano,ESP8266,MQTT,Zigbee,deconz

tndx

#6
07 März 2019, 11:02:13
OK, verstehe...

Das Ding ist zwar schon lange nicht mehr angeschlossen, aber die Konfiguration ist noch aktiv, muss ich mal aufräumen.

Das AES-Problem wird dann wohl bestehen bleiben.
Drucken
Nach oben Seiten1
Benutzer-Aktionen