Eins von zwei HM-Dis-WM55 paired nicht, RESPONSE TIMEOUT:RegisterRead

[dog_martin]

Liebe Fachleute,

habe mir vor etwa 14 Tagen zwei HM-Dis-WM55 bestellt und bin erst heute dazu gekommen Nr. 2 zusammen zu bauen.
Während Nr. 1 seit 14 Tagen problemlos funktioniert, weigert sich Nr. 2 komplett zu pairen und bleibt auf set_0xD8814F stehen.
Ein getConfig wird mit einem RESPONSE TIMEOUT:RegisterRead quittiert.

Ich habe bereits mehrfach(!):
- das Display auf Werkseinstellungen zurück gesetzt
- das Device aus FHEM entfernt und erneut versucht zu pairen
- FHEM aktualisiert
- den RasPi neu gestartet
Sämtliche anderen Aktoren, Schalter, Sensoren sind voll funktionstüchtig.

Wie kann ich ermitteln, ob das HM-Dis-WM55 selbst das Problem ist?
Freue mich über jeden Tipp!

Code Auswählen Erweitern

Internals:
   CFGFN
   CUL_0_MSGCNT 8
   CUL_0_RAWMSG A1A0284004656F0D8814F1000D34E45513031303338333212110000::-57:CUL_0
   CUL_0_RSSI -57
   CUL_0_TIME 2016-08-20 13:42:49
   DEF        4656F0
   IODev      CUL_0
   LASTInputDev CUL_0
   MSGCNT     8
   NAME       HM_4656F0
   NOTIFYDEV  global
   NR         249
   STATE      RESPONSE TIMEOUT:RegisterRead
   TYPE       CUL_HM
   channel_01 HM_4656F0_Dis_01
   channel_02 HM_4656F0_Dis_02
   channel_03 HM_4656F0_Dis_03
   channel_04 HM_4656F0_Dis_04
   channel_05 HM_4656F0_Dis_05
   channel_06 HM_4656F0_Dis_06
   channel_07 HM_4656F0_Dis_07
   channel_08 HM_4656F0_Dis_08
   channel_09 HM_4656F0_Dis_09
   channel_0A HM_4656F0_Dis_10
   lastMsg    No:02 - t:00 s:4656F0 d:D8814F 1000D34E45513031303338333212110000
   protCmdDel 21
   protLastRcv 2016-08-20 13:42:49
   protResndFail 1 last_at:2016-08-20 13:42:53
   protSnd    7 last_at:2016-08-20 13:42:49
   protState  CMDs_done_Errors:1
   rssi_at_CUL_0 avg:-58.37 cnt:8 min:-64 lst:-57 max:-54.5
   Readings:
     2016-08-20 13:41:34   CommandAccepted yes
     2016-08-20 13:42:49   D-firmware      1.0
     2016-08-20 13:42:49   D-serialNr      NEQ0103832
     2016-08-20 13:41:33   R-pairCentral   set_0xD8814F
     2016-08-20 13:41:35   aesCommToDev    ok
     2016-08-20 13:41:34   aesKeyNbr       00
     2016-08-20 13:42:53   state           RESPONSE TIMEOUT:RegisterRead
     Regl_00.:
       VAL
   Helper:
     HM_CMDNR   23
     cSnd       01D8814F4656F00006,01D8814F4656F000040000000000
     mId        00D3
     rxType     4
     Dispi:
       L:
         L1:
           d          1
         L2:
           d          1
         L3:
           d          1
         L4:
           d          1
         L5:
           d          1
         L6:
           d          1
       S:
         L1:
           d          1
         L2:
           d          1
         L3:
           d          1
         L4:
           d          1
         L5:
           d          1
         L6:
           d          1
     Expert:
       def        1
       det        0
       raw        1
       tpl        0
     Io:
       newChn     +4656F0,00,00,00
       nextSend   1471693369.69051
       prefIO
       rxt        0
       vccu
       p:
         4656F0
         00
         00
         00
     Mrssi:
       mNo        02
       Io:
         CUL_0      -55
     Prt:
       bErr       0
       sProc      0
     Q:
       qReqConf
       qReqStat
     Role:
       dev        1
     Rssi:
       At_cul_0:
         avg        -58.375
         cnt        8
         lst        -57
         max        -54.5
         min        -64
     Shadowreg:
       RegL_00.    02:01 0A:D8 0B:81 0C:4F
Attributes:
   IODev      CUL_0
   IOgrp      VCCU:CUL_0
   autoReadReg 4_reqStatus
   expert     2_raw
   firmware   1.0
   model      HM-Dis-WM55
   room       CUL_HM
   serialNr   NEQ0103832
   subType    pushButton
   webCmd     getConfig:clear msgEvents

[MadMax-FHEM]

Hi,

welches IODev hast du?

CUL??

Ich weiß zwar nicht, warum einer ging und der andere nicht geht (gleicher Typ) aber es gibt hin und wieder wohl Timing-Probleme mit CUL und Homematic.

https://forum.fhem.de/index.php/topic,56690.msg482089.html#msg482089

Vielleicht hilft das...
...da ja (wie bei mir) mehrfaches Zurücksetzen und neu Pairen nicht klappt...

Gruß, Joachim

[dog_martin]

Ja, ist ein CUL.
Das Thema mit der neuen Firmware habe ich schon gesehen.
Bin jedoch skeptisch, ob die experimentelle(?) FW hier Besserung bringt.
Zumal ein Display problemlos funktioniert.

Ich bin erst einmal auf der Suche nach der Ursache und möchte verstehen was da eigentlich schief läuft.
Ein erster Versuch die Kommunikation mit zu schneiden hat bei mir mehr Fragezeichen hinterlassen als mögliche Hinweise...

[MadMax-FHEM]

Dann am besten mal den Mitschnitt hier (in code Tags '#') posten...

Dann besteht die Chance, dass jemand drüber kuckt der sieht wo der Fehler liegt und (vielleicht) auch ne Lösung hat...

Gruß, Joachim

[dog_martin]

Ich bin mir bei der Menge an der für mich nur schwer identifizierbarer Informationen noch nicht einmal sicher was ich jetzt hier zeigen sollte.
Ich versuch's mit folgendem Ausschnitt einer getConfig Kommunikation:
(Vielleicht kann jemand fachkundige Interpretationshilfe leisten...)

Code Auswählen Erweitern

2016.08.20 13:18:02 4: CUL_Parse: CUL_0 A 0C 4E 8670 3F7B5E 000000 00D3432A -53
2016.08.20 13:18:37.052 4: CUL_Parse: CUL_0 A 0F 3B 8610 21DC66 000000 0A890C080018BF -106.5
2016.08.20 13:18:55.458 4: CUL_Parse: CUL_0 A 0F 78 8610 3F65A2 000000 0A910A0E0000C8 -102
2016.08.20 13:19:55.501 4: CUL_Parse: CUL_0 A 0C 7C 8670 207080 000000 00DC3ECC -100
2016.08.20 13:20:07.752 4: CUL_Parse: CUL_0 A 0B 06 A240 4656F0 D8814F 01021E -59
2016.08.20 13:20:07.852 4: CUL_send:  CUL_0As 0A 06 8002 D8814F 4656F0 00
2016.08.20 13:20:07.965 4: CUL_send:  CUL_0As 13 07 A011 D8814F 4656F0 8001020A0A0A0A0A0A03
2016.08.20 13:20:08.119 4: CUL_Parse: CUL_0 A 0A 07 8002 4656F0 D8814F 001E -59
2016.08.20 13:20:30.414 4: CUL_Parse: CUL_0 A 1A 07 8400 4656F0 D8814F 1000D34E4551303130333833321211000025 -55.5
2016.08.20 13:20:30.417 1: General  --------- start me
2016.08.20 13:20:30.514 4: CUL_send:  CUL_0As 10 1C A001 D8814F 4656F0 00040000000000
2016.08.20 13:20:40.595 4: CUL_Parse: CUL_0 A 0C 4F 8670 3F7B5E 000000 00D3432B -52.5
2016.08.20 13:20:56.166 4: CUL_Parse: CUL_0 A 1A 08 8400 4656F0 D8814F 1000D34E455130313033383332121100000F -66.5
2016.08.20 13:20:56.169 1: General  --------- start me
2016.08.20 13:20:56.169 1: General  --------- stop me###########
2016.08.20 13:21:02.833 4: CUL_Parse: CUL_0 A 1A 09 8400 4656F0 D8814F 1000D34E4551303130333833321211000011 -65.5
2016.08.20 13:21:02.836 1: General  --------- start me
2016.08.20 13:21:02.837 1: General  --------- stop me###########
2016.08.20 13:21:32.953 4: CUL_Parse: CUL_0 A 0F 79 8610 3F65A2 000000 0A91090E0000C8 -102
2016.08.20 13:21:46.410 4: CUL_Parse: CUL_0 A 1A 0A 8400 4656F0 D8814F 1000D34E455130313033383332121100001F -58.5
2016.08.20 13:21:46.414 1: General  --------- start me
2016.08.20 13:21:46.414 1: General  --------- stop me###########
2016.08.20 13:22:23.307 4: CUL_Parse: CUL_0 A 0C 34 A641 404D00 D8814F 0126C8EC -84
2016.08.20 13:22:23.409 4: CUL_send:  CUL_0As 0D 34 8002 D8814F 404D00 0101C800
2016.08.20 13:22:52.810 4: CUL_Parse: CUL_0 A 0C 35 A641 404D00 D8814F 012700EF -82.5
2016.08.20 13:22:52.911 4: CUL_send:  CUL_0As 0D 35 8002 D8814F 404D00 0101C800
2016.08.20 13:22:56.508 4: CUL_Parse: CUL_0 A 0C 7D 8670 207080 000000 00DC3FCC -100
2016.08.20 13:23:03.849 4: CUL_Parse: CUL_0 A 0C 50 8670 3F7B5E 000000 00D3432A -53
2016.08.20 13:23:31.530 4: CUL_Parse: CUL_0 A 0B 0B A240 4656F0 D8814F 01031E -59
2016.08.20 13:23:31.632 4: CUL_send:  CUL_0As 0A 0B 8002 D8814F 4656F0 00
2016.08.20 13:23:31.745 4: CUL_send:  CUL_0As 13 0C A011 D8814F 4656F0 8001020A0A0A0A0A0A03
2016.08.20 13:23:31.899 4: CUL_Parse: CUL_0 A 0A 0C 8002 4656F0 D8814F 001D -59.5
2016.08.20 13:23:36.691 4: CUL_Parse: CUL_0 A 1A 0C 8400 4656F0 D8814F 1000D34E455130313033383332121100001B -60.5

[martinp876]

scheint schon gepairt. logge einmal ein getConfig.

[dog_martin]

Zumindest behauptet das Display das, in FHEM bleibt das set_0xD8814F stehen...
Hier ein getConfig mitschnitt mit erneutem RESPONSE TIMEOUT:RegisterRead:

Code Auswählen Erweitern

2016.08.20 17:37:21.099 4: CUL_Parse: CUL_0 A 0B 09 A240 4656F0 D8814F 020214 -64
2016.08.20 17:37:21.199 4: CUL_send:  CUL_0As 0A 09 8002 D8814F 4656F0 00
2016.08.20 17:37:21.311 4: CUL_send:  CUL_0As 13 0A A011 D8814F 4656F0 8001020A0A0A0A0A0A03
2016.08.20 17:37:21.464 4: CUL_Parse: CUL_0 A 0A 0A 8002 4656F0 D8814F 0016 -63
2016.08.20 17:38:18.050 4: CUL_Parse: CUL_0 A 1A 0A 8400 4656F0 D8814F 1000D34E4551303130333833321211000021 -57.5
2016.08.20 17:38:18.054 1: General  --------- start me
2016.08.20 17:38:18.151 4: CUL_send:  CUL_0As 10 1F A001 D8814F 4656F0 00040000000000
2016.08.20 17:38:28.678 4: CUL_Parse: CUL_0 A 0D 1D A610 3F6BC9 1F8727 0601C800C8 -102
2016.08.20 17:38:28.940 4: CUL_Parse: CUL_0 A 19 1D A603 3F6BC9 1F8727 78EA587E0F8E35CC15754360454E36BFC7 -102.5

[frank]

Hier ein getConfig mitschnitt mit erneutem RESPONSE TIMEOUT:RegisterRead:

drückst du auch aufs knöpfchen?

[dog_martin]

Ja, bin doch geübt durch Nr. 1.
Nr. 2 verhält sich auch optisch anders.
Während Nr.1 mit "Übernehmen" schnell orange/grün blinkt, startet Nr. 2 kurz und blinkt dan nur noch weiss/grün.
Gibt der letze Mitschnitt irgendeine Erkenntnis preis?

[frank]

er gibt kein mucks von sich. deshalb ja meine frage.

fehler beim zusammenbauen?

[dog_martin]

Hmmm, ausschliessen kann man das ja nie.
Dennoch möchte ich mich als geübten Löter bezeichnen.
Und kontrolliert habe ich das (optisch) natürlich schon.

Wirklich schwierig und/oder umfangreich war der Zusammenbau ja eigentlich nicht.
Ich werd's jedenfalls morgen nochmals kontrollieren...

Es ist ja nicht so, dass gar nichts am CUL ankommt.
Das Pairing startet und die Signalstärke ist der Position angemessen.

[frank]

die anlernmessage vom knöpfchen drücken war ja doch zu sehen, aber der schalter reagiert halt nicht mehr auf die getconfig message. könnte also ein timingproblem sein.

wiederhole bis es klappt (cmds_done), sonst die optimierte fw.

Das Thema mit der neuen Firmware habe ich schon gesehen.
Bin jedoch skeptisch, ob die experimentelle(?) FW hier Besserung bringt.

[dog_martin]

Moin Moin!

Habe soeben nochmals meinen Zusammenbau unter der grossen Lupe überprüft: sieht wirklich absolut einwandfrei aus.
Alles wieder zusammengesteckt, auf Werkseinstellungen zurückgesetzt und in FHEM das alte Device entfernt.
Dann ein "set VCCU hmPairForSec 60", den Taster auf der Rückseite gedrückt, Anlernen per unteren Fronttaster gestartet, "Starten" blinkt einige Sekunden weiss/orange.
In FHEM wurde das Device unter CUL_HM hinzugefügt, allerdings noch mit "R-pairCentral: set_0xD8814F".
Ein getConfig und einmal dann den unteren Taster "Übernehmen": kurzes orange/grünes Blinken "Übernehmen", dann weiss/grünes Blinken "Übernehmen" für mehrere Sekunden.
In FHEM wieder ein "RESPONSE TIMEOUT:RegisterRead"...

Hier nochmals ein aktueller Mitschnitt:

Code Auswählen Erweitern

2016.08.21 11:30:30.462 1: PERL WARNING: Use of uninitialized value in pattern match (m//) at ./FHEM/10_CUL_HM.pm line 3018.
2016.08.21 11:30:30.644 4: CUL_send:  CUL_0As 10 16 A001 D8814F 4656F0 00050000000000
2016.08.21 11:30:30.803 4: CUL_Parse: CUL_0 A 11 16 A002 4656F0 D8814F 04194E1A2C194E0029 -53.5
2016.08.21 11:30:30.904 4: CUL_send:  CUL_0As 19 16 A003 D8814F 4656F0 8fa47595f8890fb40419b4e7601ff8b3
2016.08.21 11:30:31.061 4: CUL_Parse: CUL_0 A 0E 16 8002 4656F0 D8814F 0034042C6329 -53.5
2016.08.21 11:30:31.161 4: CUL_send:  CUL_0As 13 17 A001 D8814F 4656F0 000802010AD80B810C4F
2016.08.21 11:30:31.322 4: CUL_Parse: CUL_0 A 11 17 A002 4656F0 D8814F 042DB02E8E2DB00029 -53.5
2016.08.21 11:30:31.423 4: CUL_send:  CUL_0As 19 17 A003 D8814F 4656F0 a42e19eccfc193b3073ef7fd73c056d0
2016.08.21 11:30:31.586 4: CUL_Parse: CUL_0 A 0E 17 8002 4656F0 D8814F 007D23FCC929 -53.5
2016.08.21 11:30:31.687 4: CUL_send:  CUL_0As 0B 18 A001 D8814F 4656F0 0006
2016.08.21 11:30:31.845 4: CUL_Parse: CUL_0 A 11 18 A002 4656F0 D8814F 04811767F581170028 -54
2016.08.21 11:30:31.945 4: CUL_send:  CUL_0As 19 18 A003 D8814F 4656F0 fcdc6a3bda3a230587f5b5982270d6d3
2016.08.21 11:30:32.144 4: CUL_Parse: CUL_0 A 0E 18 8002 4656F0 D8814F 007D1306E029 -53.5
2016.08.21 11:30:41.875 4: CUL_Parse: CUL_0 A 0C 88 8670 207080 000000 009255D3 -96.5
2016.08.21 11:31:07.829 4: CUL_Parse: CUL_0 A 1A 02 8400 4656F0 D8814F 1000D34E4551303130333833321211000028 -54
2016.08.21 11:31:07.832 1: General  --------- start me
2016.08.21 11:31:07.930 4: CUL_send:  CUL_0As 10 17 A001 D8814F 4656F0 00040000000000
2016.08.21 11:31:19.957 4: CUL_Parse: CUL_0 A 1A 03 8400 4656F0 D8814F 1000D34E4551303130333833321211000028 -54
2016.08.21 11:31:19.960 1: General  --------- start me
2016.08.21 11:31:19.960 1: General  --------- stop me###########
2016.08.21 11:31:21.776 4: CUL_Parse: CUL_0 A 1A 04 8400 4656F0 D8814F 1000D34E4551303130333833321211000028 -54
2016.08.21 11:31:21.779 1: General  --------- start me
2016.08.21 11:31:21.779 1: General  --------- stop me###########
2016.08.21 11:31:28.439 4: CUL_Parse: CUL_0 A 1A 05 8400 4656F0 D8814F 1000D34E4551303130333833321211000028 -54
2016.08.21 11:31:28.442 1: General  --------- start me
2016.08.21 11:31:28.443 1: General  --------- stop me###########
2016.08.21 11:31:58.799 4: CUL_Parse: CUL_0 A 0C 5C 8670 3F7B5E 000000 00CA402B -52.5
2016.08.21 11:32:20.073 4: CUL_Parse: CUL_0 A 1A 06 8400 4656F0 D8814F 1000D34E4551303130333833321211000029 -53.5
2016.08.21 11:32:20.077 1: General  --------- start me
2016.08.21 11:32:20.077 1: General  --------- stop me###########
2016.08.21 11:32:57.881 4: CUL_Parse: CUL_0 A 0C 89 8670 207080 000000 009155D3 -96.5
2016.08.21 11:34:26.553 4: CUL_Parse: CUL_0 A 0C 5D 8670 3F7B5E 000000 00CA402A -53
2016.08.21 11:34:46.158 4: CUL_Parse: CUL_0 A 0D C3 A610 434249 1F8727 06010000D0 -98
2016.08.21 11:36:39.807 4: CUL_Parse: CUL_0 A 0C 5E 8670 3F7B5E 000000 00CA402A -53
2016.08.21 11:37:53.045 4: CUL_Parse: CUL_0 A 0D BE A610 4349F5 D8814F 06010000FA -77
2016.08.21 11:37:53.146 4: CUL_send:  CUL_0As 0A BE 8002 D8814F 4349F5 00
2016.08.21 11:39:42.813 4: CUL_Parse: CUL_0 A 0C 5F 8670 3F7B5E 000000 00CA402B -52.5
2016.08.21 11:40:05.576 4: CUL_Parse: CUL_0 A 0D 00 A610 43431D 1F8727 06010000D2 -97
2016.08.21 11:40:05.839 4: CUL_Parse: CUL_0 A 19 00 A603 43431D 1F8727 463A46F5197FFBCE8769436EDB7D019DD0 -98
2016.08.21 11:40:27.147 4: CUL_Parse: CUL_0 A 0C 8C 8670 207080 000000 008F56D0 -98
2016.08.21 11:42:19.736 4: CUL_Parse: CUL_0 A 0C 01 A641 43431D 1F8727 01F1C8D3 -96.5
2016.08.21 11:42:20.000 4: CUL_Parse: CUL_0 A 19 01 A603 43431D 1F8727 449AEA01F3D1FE3B78F4E67521646EBED5 -95.5
2016.08.21 11:42:20.532 4: CUL_Parse: CUL_0 A 0C 02 A241 43431D 1F8727 01F1C8CF -98.5
2016.08.21 11:42:20.795 4: CUL_Parse: CUL_0 A 19 02 A203 43431D 1F8727 5DE821FCA3E81F0B0E9180B24010968BCE -99
2016.08.21 11:42:31.317 4: CUL_Parse: CUL_0 A 0C 60 8670 3F7B5E 000000 00CA402B -52.5
2016.08.21 11:42:39.178 4: CUL_Parse: CUL_0 A 0B 07 A240 4656F0 D8814F 010129 -53.5
2016.08.21 11:42:39.279 4: CUL_send:  CUL_0As 0A 07 8002 D8814F 4656F0 00
2016.08.21 11:42:39.392 4: CUL_send:  CUL_0As 13 08 A011 D8814F 4656F0 8001020A0A0A0A0A0A03
2016.08.21 11:42:39.546 4: CUL_Parse: CUL_0 A 0A 08 8002 4656F0 D8814F 0029 -53.5
2016.08.21 11:42:55.259 4: CUL_Parse: CUL_0 A 0C 03 A641 43431D 1F8727 01F200D1 -97.5
2016.08.21 11:42:55.523 4: CUL_Parse: CUL_0 A 19 03 A603 43431D 1F8727 788F0308C860BEFD13AFB4E65C8052B6CF -98.5
2016.08.21 11:42:55.799 4: CUL_Parse: CUL_0 A 12 28 8002 394A0E 1F8727 010502002E0002AAAAC3 -104.5
2016.08.21 11:42:56.084 4: CUL_Parse: CUL_0 A 0C 04 A241 43431D 1F8727 01F200D1 -97.5
2016.08.21 11:42:56.349 4: CUL_Parse: CUL_0 A 19 04 A203 43431D 1F8727 618B7E979133BB38CD27240208A316C7D0 -98
2016.08.21 11:44:57.157 4: CUL_Parse: CUL_0 A 0C 8E 8670 207080 000000 008E56CC -100
2016.08.21 11:45:05.321 4: CUL_Parse: CUL_0 A 0C 61 8670 3F7B5E 000000 00CA402B -52.5
2016.08.21 11:45:24.344 4: CUL_Parse: CUL_0 A 1A 08 8400 4656F0 D8814F 1000D34E4551303130333833321211000028 -54
2016.08.21 11:45:24.348 1: General  --------- start me
2016.08.21 11:45:35.397 4: CUL_Parse: CUL_0 A 1A 09 8400 4656F0 D8814F 1000D34E4551303130333833321211000028 -54
2016.08.21 11:45:35.401 1: General  --------- start me
2016.08.21 11:45:35.401 1: General  --------- stop me###########
2016.08.21 11:45:51.527 4: CUL_Parse: CUL_0 A 1A 0A 8400 4656F0 D8814F 1000D34E4551303130333833321211000029 -53.5
2016.08.21 11:45:51.530 1: General  --------- start me
2016.08.21 11:45:51.531 1: General  --------- stop me###########
2016.08.21 11:45:58.793 4: CUL_Parse: CUL_0 A 0F 8C 8610 3F65A2 000000 0A90F80E0000BE -107

Ich habe mich gegen die optimierte FW entschieden und werde das Display erst einmal reklamieren - das erste hat schliesslich auch ohne funktioniert.

Ohne eine offizielle Übernahme dieser Firmware und der angepassten Module wäre das für mich eine Sackgasse bzgl. zukünftiger Updates.

[frank]

das pairen funktioniert prima, obwohl du sogar aes angeschaltet hast.
er antwortet nur nicht auf das getconfig.

aes macht das schlechte timing der cul geräte nicht einfacher. ist das beim anderen schalter ebenfalls eingeschaltet? vielleicht liegt es daran.

Ohne eine offizielle Übernahme dieser Firmware und der angepassten Module wäre das für mich eine Sackgasse bzgl. zukünftiger Updates.

entweder timingoptimierte homematic io's nutzen, oder beim "chef" den wunsch der übernahme der timing verbesserungen äussern.

[dog_martin]

AES ist bei einigen Kontakten und auch beim anderen Display eingeschaltet.
(Weniger weil ICH das eingeschaltet habe, sondern weil's default war - wenn's funktioniert: Finger weg lassen.)

Den Wunsch beim Chef äussere ich gern. Wer isses denn?