AES mit CUL nicht mehr möglich?

Bytechanger · 31 August 2016, 16:40:53

Hallo,

nachdem mir es mir bei den Fenstersensoren auffiel, habe ich nun auch Steckdosen getestet.
Sobald in FHEM ein "Signatur-Handshake" über den CUL laufen soll läuft es schief ..

Bei den Fenstersendoren steht aesCommToDev: pending .
Gleiches gilt für Aktoren. Dort wird ebenfalls aesCommToDev: pending
eingeblendet.
Sämtliche Aktionen / Meldungen bleiben dann natürlich ignoriert!

Da vor geraumer Zeit alles funktionierte, bin ich nun auf der Suche nach dem Fehler.
Da ich Homematic über eine VCCU in Kombi mit einem HMLAN und dem CUL nutze, kann
ich in den Devices über

IODev CUL0
IOgrp vccu:CUL0

und

IODev HMLAN1
IOgrp vccu:HMLAN1

umschalten. Bei HMLAN1 funktioniert alles... ??

Code Auswählen

Internals: 
   CMDS       BbCFiAZNkGMKUYRTVWXefmLltux 
   CUL0_MSGCNT 8 
   CUL0_TIME  2016-08-31 16:36:03 
   Clients    :CUL_HM:HMS:CUL_IR:STACKABLE_CC: 
   DEF        /dev/serial/by-id/usb-busware.de_CUL868-if00 0000 
   DeviceName /dev/serial/by-id/usb-busware.de_CUL868-if00 
   FHTID      0000 
   NAME       CUL0 
   NR         124 
   NR_CMD_LAST_H 4 
   PARTIAL 
   RAWMSG     A0EA380021DA462343DCC0068A52CE821 
   RSSI       -57.5 
   STATE      Initialized 
   TYPE       CUL 
   VERSION    V 1.66 CUL868 
   initString X21 
Ar 
   owner_CCU  vccu 
   Matchlist: 
     1:CUL_HM   ^A.................... 
     8:HMS      ^810e04....(1|5|9).a001 
     D:CUL_IR   ^I............ 
     H:STACKABLE_CC ^\* 
   Readings: 
     2016-08-31 16:36:03   cmds             B b C F i A Z N k G M K U Y R T V W X e f m L l t u x 
     2016-08-28 18:22:53   hmSioDly        0 
     2016-08-29 08:01:41   raw             V 1.66 CUL868 
     2016-08-31 16:36:03   state           Initialized 
     2016-08-30 06:15:37   version         V 1.66 CUL868 
   XMIT_TIME: 
     1472654192.50399 
     1472654193.8701 
     1472654198.30359 
     1472654204.09606 
   Helper: 
     456779: 
       QUEUE: 
     467509: 
       QUEUE: 
Attributes: 
   event-on-change-reading .* 
   group      Sender 
   hmId       1DA111
   icon       cul_cul 
   rfmode     HomeMatic 
   room       Basis

Code Auswählen

Internals: 
   CUL0_MSGCNT 6 
   CUL0_RAWMSG A0EA380021DA462343DCC0068A52CE8::-57.5:CUL0 
   CUL0_RSSI  -57.5 
   CUL0_TIME  2016-08-31 16:36:03 
   DEF        1DA462 
   HMLAN1_MSGCNT 731 
   HMLAN1_RAWMSG E1DA462,0000,110C683C,FF,FFC7,04A0111DA4624567790201C80000 
   HMLAN1_RSSI -57 
   HMLAN1_TIME 2016-08-31 16:32:36 
   IODev      HMLAN1 
   LASTInputDev CUL0 
   MSGCNT     737 
   NAME       vccu 
   NOTIFYDEV  global 
   NR         140 
   NTFY_ORDER 50-vccu 
   STATE      HMLAN1:ok,CUL0:ok, 
   TYPE       CUL_HM 
   assignedIOs CUL0,HMLAN1 
   channel_01 V_HS_AL_armInt 
   channel_02 V_HS_AL_armExt 
   channel_03 V_HS_AL_light 
   channel_04 V_HS_AL_disarm 
   lastMsg    No:A3 - t:02 s:1DA462 d:343DCC 0068A52CE8 
   protLastRcv 2016-08-31 16:36:03 
   rssi_at_CUL0 max:-57.5 avg:-58.41 min:-59 lst:-57.5 cnt:6 
   rssi_at_HMLAN1 lst:-57 cnt:46 avg:-62.89 min:-77 max:-57 
   Readings: 
     2016-08-31 16:36:03   CommandAccepted yes 
     2016-08-31 16:36:01   aesKeyNbr       02 
     2016-08-28 10:00:00   aesReqTo        AussenSwitch 
     2016-08-30 12:45:08   state           HMLAN1:ok,CUL0:ok, 
     2016-08-26 21:42:50   unknown_10F5B8  received 
     2016-08-24 20:08:43   unknown_144111  received 
     2016-08-31 11:22:09   unknown_152097  received 
     2016-08-29 22:24:36   unknown_1941B4  received 
     2016-08-31 15:59:12   unknown_206B03  received 
     2016-08-30 06:23:46   unknown_23FBE0  received 
     2016-08-24 20:08:44   unknown_411111  received 
     2016-08-31 16:26:03   unknown_FD1D5F  received 
   Helper: 
     HM_CMDNR   163 
     mId        FFF0 
     rxType     1 
     Ack: 
     Expert: 
       def        1 
       det        0 
       raw        0 
       tpl        0 
     Io: 
       nextSend   1472654233.25728 
       prefIO 
       vccu 
       ioList: 
         HMLAN1 
         CUL0 
     Mrssi: 
       mNo        A3 
       Io: 
         CUL0       -57.5 
     Prt: 
       bErr       0 
       sProc      0 
       Rspwait: 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       dev        1 
       vrt        1 
     Rssi: 
       At_cul0: 
         avg        -58.4166666666667 
         cnt        6 
         lst        -57.5 
         max        -57.5 
         min        -59 
       At_hmlan1: 
         avg        -62.8913043478261 
         cnt        46 
         lst        -57 
         max        -57 
         min        -77 
     Tmpl: 
Attributes: 
   IODev      HMLAN1 
   IOList     HMLAN1,CUL0 
   event-on-change-reading .* 
   group      Sender 
   hmKey      01:jajajuajaja
   model      CCU-FHEM 
   room       Basis 
   subType    virtual 
   webCmd     virtual:update

Greets

Byte

Bytechanger · 31 August 2016, 17:27:47

Mit einer 10_CUL_HM Version von Mai 2016 bekomme ich zumindest ein statusRequest bei den Aktoren hin....
Schalten geht weiterhin nicht, es erscheint damit aber kein Pending mehr.

Vermute also damit, dass es an Änderungen an der 10_CUL_HM liegt ??!!

Greets

Byte

mgernoth · 31 August 2016, 19:08:47

Hallo,

Zitat von: Bytechanger am 31 August 2016, 16:40:53
Code Auswählen Erweitern
IOList HMLAN1,CUL0 hmKey 01:jajajuajaja

Das funktioniert so nicht. Der Key muss in der VCCU definiert sein und die VCCU muss in der IOList des Geräts vorkommen, sonst findet der CUL-Code den Schlüssel nicht. Da die VCCU den Schlüssel in den HMLAN schreibt, funktioniert es hier, auch wenn die VCCU nicht selbst in der IOList steht.

EDIT: Und gibt es irgendwelche Einträge im Log?

Viele Grüße
Michael

Bytechanger · 31 August 2016, 19:38:40

Keine Sorge, ich habe den Key nur rausgenommen!
Ich wollte ihn nicht ins Internet posten
Der ist natürlich richtig drin, sonst würde HMLAN auch nicht funktionieren!

Gruß

Byte

mgernoth · 31 August 2016, 19:40:35

Hi,

Zitat von: Bytechanger am 31 August 2016, 19:38:40
Keine Sorge, ich habe den Key nur rausgenommen!
Ich wollte ihn nicht ins Internet posten
Der ist natürlich richtig drin, sonst würde HMLAN auch nicht funktionieren!

Ähja, sorry. Das war ja die VCCU. Ich hatte das irgendwie für ein List der Steckdose gehalten...

Aber nochmal: Taucht bei Schaltversuchen irgendwas im Fhem-Log auf? Ist das Perl-Modul installiert?

Viele Grüße
Michael

Bytechanger · 31 August 2016, 19:47:47

Wie gesagt, vccu->HM-Lan funktioniert ! Nur der CUL streikt !

So sieht der Fensterkontakt aus, wenn er über HMLAN ordnungsgemäß funktioniert:

Code Auswählen

Internals: 
   DEF        278291 
   HMLAN1_MSGCNT 18 
   HMLAN1_RAWMSG E278291,0040,11BB7F69,01,FFC0,27A6412782911DA462015D00 
   HMLAN1_RSSI -64 
   HMLAN1_TIME 2016-08-31 19:43:50 
   IODev      HMLAN1 
   LASTInputDev HMLAN1 
   MSGCNT     18 
   NAME       EG.bu.Buero.TK 
   NOTIFYDEV  global 
   NR         382 
   NTFY_ORDER 50-EG.bu.Buero.TK 
   STATE      closed 
   TYPE       CUL_HM 
   lastMsg    No:27 - t:41 s:278291 d:1DA462 015D00 
   protEvt_AESCom-ok 2 last_at:2016-08-31 19:43:50 
   protLastRcv 2016-08-31 19:43:50 
   protSnd    2 last_at:2016-08-31 19:43:50 
   protState  CMDs_done 
   rssi_at_HMLAN1 cnt:2 lst:-64 avg:-62 min:-64 max:-60 
   Readings: 
     2016-08-30 06:48:32   Activity        alive 
     2016-08-29 20:23:19   D-firmware      2.4 
     2016-08-29 20:23:19   D-serialNr      LEQ0214244 
     2016-08-29 20:24:15   PairedTo        0x1DA462 
     2016-08-29 20:24:15   R-cyclicInfoMsg on 
     2016-08-29 20:24:16   R-eventDlyTime  0 s 
     2016-08-29 20:24:15   R-pairCentral   0x1DA462 
     2016-08-29 20:24:15   R-sabotageMsg   on 
     2016-08-29 20:24:16   R-sign          on 
     2016-08-29 20:24:15   RegL_00.        02:01 09:01 0A:1D 0B:A4 0C:62 10:01 14:06 00:00 
     2016-08-29 20:24:16   RegL_01.        08:01 20:60 21:00 22:64 30:06 00:00 
     2016-08-31 19:43:50   aesCommToDev    ok 
     2016-08-31 06:22:52   alive           yes 
     2016-08-31 19:43:50   battery         ok 
     2016-08-31 19:43:50   contact         closed (to vccu) 
     2016-08-31 06:22:52   recentStateType info 
     2016-08-31 06:22:52   sabotageError   off 
     2016-08-31 19:43:50   state           closed 
     2016-08-31 19:43:50   trigDst_vccu    noConfig 
     2016-08-31 19:43:50   trig_aes_vccu   ok:93 
     2016-08-31 19:43:50   trigger_cnt     93 
   Helper: 
     HM_CMDNR   39 
     mId        00B1 
     rxType     28 
     Aescommrq: 
       challenge  85AB7439BA43 
       kNo        1 
       msg        A0C26A6412782911DA462015CC8 
       msgIn      A0C26A6412782911DA462015CC8:AESCom-ok:-69:HMLAN1 
     Expert: 
       def        1 
       det        0 
       raw        1 
       tpl        0 
     Io: 
       newChn     +278291,01,01,02 
       nextSend   1472665429.81064 
       rxt        2 
       vccu       vccu 
       p: 
         278291 
         01 
         01 
         02 
       prefIO: 
         HMLAN1 
     Mrssi: 
       mNo        27 
       Io: 
         HMLAN1     -62 
     Prt: 
       bErr       0 
       sProc      0 
       sleeping   0 
       Rspwait: 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       chn        1 
       dev        1 
     Rpt: 
       IO         HMLAN1 
       flg        A 
       ts         1472665430.10168 
       ack: 
         HASH(0x2f393f8) 
         2780021DA46227829100 
     Rssi: 
       At_hmlan1: 
         avg        -62 
         cnt        2 
         lst        -64 
         max        -60 
         min        -64 
     Tmpl: 
   Role: 
Attributes: 
   IODev      HMLAN1 
   IOgrp      vccu:HMLAN1 
   actCycle   028:00 
   actStatus  alive 
   aesCommReq 1 
   alarmDevice Sensor 
   alarmSettings alarm6,|EG.bu.Buero.TK:[Oo]pen.*|Fenster Büro|on 
   autoReadReg 4_reqStatus 
   devStateIcon open:fts_window_1w_open@red closed:fts_window_1w@green 
   expert     2_raw 
   firmware   2.4 
   group      Fenster 
   icon       fts_window_2w_open 
   model      HM-SEC-SC-2 
   peerIDs    00000000, 
   room       CUL_HM,Zustand,Öffnungssensoren 
   serialNr   LEQ0214244 
   subType    threeStateSensor

Und so das Eventlog:

Code Auswählen

2016-08-31 19:43:49 CUL_HM EG.bu.Buero.TK aesCommToDev: pending
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK aesCommToDev: ok
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK battery: ok
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK contact: closed (to vccu)
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK closed
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK trigDst_vccu: noConfig
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK trig_aes_vccu: ok:93
2016-08-31 19:43:50 CUL_HM EG.bu.Buero.TK trigger_cnt: 93

------------ und jetzt, wenn ich auf CUL0 schalte ------->

Code Auswählen

Internals: 
   DEF        278291 
   HMLAN1_MSGCNT 25 
   HMLAN1_RAWMSG E278291,0040,11BDD729,01,FFC0,29A6412782911DA462015F00 
   HMLAN1_RSSI -64 
   HMLAN1_TIME 2016-08-31 19:46:23 
   IODev      CUL0 
   LASTInputDev HMLAN1 
   MSGCNT     25 
   NAME       EG.bu.Buero.TK 
   NOTIFYDEV  global 
   NR         382 
   NTFY_ORDER 50-EG.bu.Buero.TK 
   STATE      open 
   TYPE       CUL_HM 
   lastMsg    No:28 - t:41 s:278291 d:1DA462 015EC8 
   protEvt_AESCom-ok 3 last_at:2016-08-31 19:45:35 
   protLastRcv 2016-08-31 19:45:35 
   protSnd    3 last_at:2016-08-31 19:45:35 
   protState  CMDs_done 
   rssi_at_HMLAN1 avg:-62.66 min:-64 max:-60 cnt:3 lst:-64 
   Readings: 
     2016-08-30 06:48:32   Activity        alive 
     2016-08-29 20:23:19   D-firmware      2.4 
     2016-08-29 20:23:19   D-serialNr      LEQ0214244 
     2016-08-29 20:24:15   PairedTo        0x1DA462 
     2016-08-29 20:24:15   R-cyclicInfoMsg on 
     2016-08-29 20:24:16   R-eventDlyTime  0 s 
     2016-08-29 20:24:15   R-pairCentral   0x1DA462 
     2016-08-29 20:24:15   R-sabotageMsg   on 
     2016-08-29 20:24:16   R-sign          on 
     2016-08-29 20:24:15   RegL_00.        02:01 09:01 0A:1D 0B:A4 0C:62 10:01 14:06 00:00 
     2016-08-29 20:24:16   RegL_01.        08:01 20:60 21:00 22:64 30:06 00:00 
     2016-08-31 19:46:23   aesCommToDev    pending 
     2016-08-31 06:22:52   alive           yes 
     2016-08-31 19:45:35   battery         ok 
     2016-08-31 19:45:35   contact         open (to vccu) 
     2016-08-31 06:22:52   recentStateType info 
     2016-08-31 06:22:52   sabotageError   off 
     2016-08-31 19:45:35   state           open 
     2016-08-31 19:45:35   trigDst_vccu    noConfig 
     2016-08-31 19:45:35   trig_aes_vccu   ok:94 
     2016-08-31 19:45:35   trigger_cnt     94 
   Helper: 
     HM_CMDNR   40 
     mId        00B1 
     rxType     28 
     Aescommrq: 
       challenge  85AB7439BA43 
       kNo        1 
       msg        A0C29A6412782911DA462015F00 
       msgIn      A0C29A6412782911DA462015F00:AESCom-ok:-64:HMLAN1 
     Expert: 
       def        1 
       det        0 
       raw        1 
       tpl        0 
     Io: 
       newChn     +278291,01,01,02 
       nextSend   1472665583.32335 
       rxt        2 
       vccu       vccu 
       p: 
         278291 
         01 
         01 
         02 
       prefIO: 
         CUL0 
     Mrssi: 
       mNo        28 
       Io: 
         HMLAN1     -62 
     Prt: 
       bErr       0 
       sProc      0 
       sleeping   0 
       Rspwait: 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       chn        1 
       dev        1 
     Rpt: 
       IO         HMLAN1 
       flg        A 
       ts         1472665535.86242 
       ack: 
         HASH(0x2f393f8) 
         2880021DA46227829100 
     Rssi: 
       At_hmlan1: 
         avg        -62.6666666666667 
         cnt        3 
         lst        -64 
         max        -60 
         min        -64 
     Tmpl: 
   Role: 
Attributes: 
   IODev      CUL0 
   IOgrp      vccu:CUL0 
   actCycle   028:00 
   actStatus  alive 
   aesCommReq 1 
   alarmDevice Sensor 
   alarmSettings alarm6,|EG.bu.Buero.TK:[Oo]pen.*|Fenster Büro|on 
   autoReadReg 4_reqStatus 
   devStateIcon open:fts_window_1w_open@red closed:fts_window_1w@green 
   expert     2_raw 
   firmware   2.4 
   group      Fenster 
   icon       fts_window_2w_open 
   model      HM-SEC-SC-2 
   peerIDs    00000000, 
   room       CUL_HM,Zustand,Öffnungssensoren 
   serialNr   LEQ0214244 
   subType    threeStateSensor

Hier das Log--->

Code Auswählen

2016-08-31 19:46:23 CUL_HM EG.bu.Buero.TK aesCommToDev: pending
2016-08-31 19:46:23 CUL_HM EG.bu.Buero.TK aesCommToDev: pending

Scheint so, als ob der AES-Handshake ins leere läuft!

Meine Hoffnung liegt auf @Martin786
Greets

Byte

mgernoth · 31 August 2016, 19:57:16

Hallo,

Zitat von: Bytechanger am 31 August 2016, 19:47:47
Hier das Log--->
Code Auswählen Erweitern
2016-08-31 19:46:23 CUL_HM EG.bu.Buero.TK aesCommToDev: pending 2016-08-31 19:46:23 CUL_HM EG.bu.Buero.TK aesCommToDev: pending
Scheint so, als ob der AES-Handshake ins leere läuft!

Ja, vielleicht findet sich ein Hinweis in der fhem-Logdatei (und nicht im Eventlog). Schau bitte einmal in die fhem-Logdatei.
Und evtl. auch gleich Sniffing aktivieren.

Viele Grüße
Michael

Bytechanger · 31 August 2016, 20:12:20

Das war das Log...

wenn ich dieses Device noch auf verbose 5 Stelle sieht es im Fall von

HMLAN

Code Auswählen

2016.08.31 20:13:40 5: CUL_HM EG.bu.Buero.TK prep ACK for 01
2016.08.31 20:13:40 5: CUL_HM EG.bu.Buero.TK protEvent:CMDs_done
2016.08.31 20:13:40 5: CUL_HM EG.bu.Buero.TK sent ACK:2

CUL0

Code Auswählen

2016.08.31 20:14:27 5: CUL_HM EG.bu.Buero.TK requesting signature with challenge 85AB7439BA43 for key 1
2016.08.31 20:14:27 5: CUL_HM EG.bu.Buero.TK requesting signature with challenge 85AB7439BA43 for key 1

aus

Bytechanger · 31 August 2016, 20:25:00

Code Auswählen

2016.08.31 20:20:26 5: HMLAN/RAW: /E278291,0100,11DD0774,FF,FFBF,30A6412782911DA4620166C8

2016.08.31 20:20:26 5: HMLAN_Parse: HMLAN1 R:E278291   stat:0100 t:11DD0774 d:FF r:FFBF     m:30 A641 278291 1DA462 0166C8
2016.08.31 20:20:26 5: HMLAN1 dispatch A0C30A6412782911DA4620166C8:AESpending:-65:HMLAN1
[b]2016.08.31 20:20:26 5: CUL_HM EG.bu.Buero.TK requesting signature with challenge 85AB7439BA43 for key 1
2016.08.31 20:20:26 5: CUL0 sending As1130A0021DA4622782910485AB7439BA4302
2016.08.31 20:20:26 5: CUL 278291 dly:83ms
2016.08.31 20:20:26 4: CUL_send:  CUL0As 11 30 A002 1DA462 278291 0485AB7439BA4302
2016.08.31 20:20:26 5: Triggering EG.bu.Buero.TK (1 changes)[/b]
2016.08.31 20:20:26 5: Starting notify loop for EG.bu.Buero.TK, first event aesCommToDev: pending
2016.08.31 20:20:27 5: ZE.Batterie: not on any display, ignoring notify
2016.08.31 20:20:27 5: HMLAN/RAW: /E1DA462,0000,11DD0813,FF,FFC6,30A0021DA4622782910485AB7439BA4302

2016.08.31 20:20:27 5: HMLAN_Parse: HMLAN1 R:E1DA462   stat:0000 t:11DD0813 d:FF r:FFC6     m:30 A002 1DA462 278291 0485AB7439BA4302
2016.08.31 20:20:27 5: HMLAN1 dispatch A1130A0021DA4622782910485AB7439BA4302::-58:HMLAN1
2016.08.31 20:20:27 5: HMLAN/RAW: /E278291,0040,11DD0774,01,FFBF,30A6412782911DA4620166C8

2016.08.31 20:20:27 5: HMLAN_Parse: HMLAN1 R:E278291   stat:0040 t:11DD0774 d:01 r:FFBF     m:30 A641 278291 1DA462 0166C8
2016.08.31 20:20:27 5: HMLAN1 dispatch A0C30A6412782911DA4620166C8:AESCom-ok:-65:HMLAN1
[b]2016.08.31 20:20:27 5: CUL_HM EG.bu.Buero.TK requesting signature with challenge 85AB7439BA43 for key 1
2016.08.31 20:20:27 5: CUL0 sending As1130A0021DA4622782910485AB7439BA4302
2016.08.31 20:20:27 4: CUL_send:  CUL0As 11 30 A002 1DA462 278291 0485AB7439BA4302[/b]
[b]2016.08.31 20:20:27 5: Triggering EG.bu.Buero.TK (1 changes)[/b]
[b]2016.08.31 20:20:27 5: Starting notify loop for EG.bu.Buero.TK, first event aesCommToDev: pending[/b]
2016.08.31 20:20:27 5: ZE.Batterie: not on any display, ignoring notify
2016.08.31 20:20:27 5: HMLAN/RAW: /E1DA462,0000,11DD0927,FF,FFC6,30A0021DA4622782910485AB7439BA4302

2016.08.31 20:20:27 5: HMLAN_Parse: HMLAN1 R:E1DA462   stat:0000 t:11DD0927 d:FF r:FFC6     m:30 A002 1DA462 278291 0485AB7439BA4302
2016.08.31 20:20:27 5: HMLAN1 dispatch A1130A0021DA4622782910485AB7439BA4302::-58:HMLAN1
2016.08.31 20:20:27 4: CUL_HM vccu dupe: dont process
2016.08.31 20:20:29 5: HMLAN_Send:  HMLAN1 I:K
2016.08.31 20:20:29 5: HMLAN/RAW: /HHM-LAN-IF,03C5,LEQ0640815,2CD8CE,1DA462,11DD12E7,001E,02

und hier über HMLAN wenns ok ist

Code Auswählen

2016.08.31 20:23:51 5: Triggering EG.bu.Buero.TK (1 changes)
2016.08.31 20:23:51 5: Starting notify loop for EG.bu.Buero.TK, first event aesCommToDev: pending
2016.08.31 20:23:51 5: ZE.Batterie: not on any display, ignoring notify
2016.08.31 20:23:51 5: HMLAN/RAW: /E278291,0040,11E02678,01,FFBE,32A6412782911DA4620168C8

2016.08.31 20:23:51 5: HMLAN_Parse: HMLAN1 R:E278291   stat:0040 t:11E02678 d:01 r:FFBE     m:32 A641 278291 1DA462 0168C8
2016.08.31 20:23:51 5: HMLAN1 dispatch A0C32A6412782911DA4620168C8:AESCom-ok:-66:HMLAN1
2016.08.31 20:23:51 5: CUL_HM EG.bu.Buero.TK prep ACK for 01
2016.08.31 20:23:51 5: HMLAN: Skip ACK
2016.08.31 20:23:51 5: CUL_HM EG.bu.Buero.TK protEvent:CMDs_done
2016.08.31 20:23:51 5: CUL_HM EG.bu.Buero.TK sent ACK:2
2016.08.31 20:23:51 5: Triggering EG.bu.Buero.TK (7 changes)
2016.08.31 20:23:51 5: Starting notify loop for EG.bu.Buero.TK, first event aesCommToDev: ok

mgernoth · 31 August 2016, 20:35:52

Hallo,

Ich habe es gerade eben mit dem aktuellen Code getestet, AES funktioniert noch. Allerdings habe ich nur einen Aktor geschaltet.

Zitat von: Bytechanger am 31 August 2016, 20:12:20
Das war das Log...

Hmm, bei mir landen diese Events nicht im fhem-Log, nur in den einzelnen gerätespezifischen Logdateien (wenn überhaupt).

Zitat
CUL0
Code Auswählen Erweitern
2016.08.31 20:14:27 5: CUL_HM EG.bu.Buero.TK requesting signature with challenge 85AB7439BA43 for key 1 2016.08.31 20:14:27 5: CUL_HM EG.bu.Buero.TK requesting signature with challenge 85AB7439BA43 for key 1

Also ist das Perl-Modul installiert und der Schlüssel wurde in der VCCU gefunden. Jetzt wäre interessant, was wirklich gesendet wurde.

Viele Grüße
Michael

Bytechanger · 31 August 2016, 20:58:24

Du hast auch einen CUL und eine VCCU??

Wie kann ich noch weitergehende Logs senden??

Greets

Byte

mgernoth · 31 August 2016, 21:57:25

Hi,

Zitat von: Bytechanger am 31 August 2016, 20:58:24
Du hast auch einen CUL und eine VCCU??

Ja, hatte gerade mein Testsystem gestartet.

Zitat
Wie kann ich noch weitergehende Logs senden??

Siehe: https://forum.fhem.de/index.php/topic,16563.msg107848.html#msg107848

Viele Grüße
Michael

Bytechanger · 01 September 2016, 07:00:43

OK,

also diese Einstellung:

attr global verbose 1
attr global mseclog 1
attr <cul> verbose 4

Ergibt beim Öffnen des Fensters folgendes Log:

Code Auswählen

2016.09.01 06:59:13.430 4: CUL_send:  CUL0As 11 34 A002 1DA462 278291 04DEFB7A40FD6502
2016.09.01 06:59:13.717 4: CUL_send:  CUL0As 11 34 A002 1DA462 278291 04DEFB7A40FD6502

und beim Schalten eines Aktors:

Code Auswählen

016.09.01 07:13:24.370 4: CUL_send:  CUL0As 0E 02 A011 1DA462 456779 0201C80000
2016.09.01 07:13:29.307 4: CUL_send:  CUL0As 0E 02 A011 1DA462 456779 0201C80000

Greets

Byte

mgernoth · 01 September 2016, 09:31:29

Hi,

Zitat von: Bytechanger am 01 September 2016, 07:00:43
Ergibt beim Öffnen des Fensters folgendes Log:
Code Auswählen Erweitern
2016.09.01 06:59:13.430 4: CUL_send: CUL0As 11 34 A002 1DA462 278291 04DEFB7A40FD6502 2016.09.01 06:59:13.717 4: CUL_send: CUL0As 11 34 A002 1DA462 278291 04DEFB7A40FD6502

und beim Schalten eines Aktors:
Code Auswählen Erweitern
016.09.01 07:13:24.370 4: CUL_send: CUL0As 0E 02 A011 1DA462 456779 0201C80000 2016.09.01 07:13:29.307 4: CUL_send: CUL0As 0E 02 A011 1DA462 456779 0201C80000

Uh, wenn das alles ist, empfängt Dein CUL nichts. Nur der HMLAN empfängt, weswegen im Fenster-Fall der CUL als primäres IO versucht die Challenge zu senden.
Für mich sieht das nach einem HW-Problem des CUL aus.

In Deinem Log von 20:25 gestern Abend waren auch keine empfangenen Frames beim CUL zu sehen, die hätten aber jetzt definitiv auftauchen müssen...

Viele Grüße
Michael

frank · 01 September 2016, 09:56:10

ZitatUh, wenn das alles ist, empfängt Dein CUL nichts. Nur der HMLAN empfängt, weswegen im Fenster-Fall der CUL als primäres IO versucht die Challenge zu senden.
Für mich sieht das nach einem HW-Problem des CUL aus.

dazu habe ich mal eine frage?
wenn dem so wäre, dass der cul nichts empfängt, müsste dann nicht fhem die antworten über den hmlan bekommen und die weiteren messages wieder über den cul senden? es heisst doch immer, dass alle io's empfangen, und nach der ersten eingehenden message, egal von wem sie empfangen wurde, fhem reagiert.

gruss frank