HM-LC-Bl1PBU-FM Missing Ack

[crazystone]

Bin gerade am Verzweifeln! Ich betreibe insgesamt 6 der o.g. Rolladenaktoren. Alle funktionieren einwandfrei. Nur einen (den in der Küche, Luftlinie 6m vom rpi mit CUL) reagiert nicht auf Kommandos. Ich bekomme immer ein MISSING ACK. Der Status wird nach manueller Bedienung richtig angezeigt. RSSI ist mit -62 auch nicht anders als bei anderen.

Doch jetzt kommts, ich habe den Aktor komplett aus FHEM gelöscht, die HW gegen einen komplett Aktor neuen ausgetauscht, neu gepairt und wieder angelegt... dasselbe Verhalten! Wieder nur  MISSING ACK bei jedem Kommando, korrekter Rollo-Status wird angezeigt.

Hat jemand eine Idee?

CFGFN
CUL_0_MSGCNT                            6
CUL_0_RAWMSG                           A0D6884102FF2250000000601C000::-62:CUL_0
CUL_0_RSSI                                  -62
CUL_0_TIME                                  2015-08-18 09:05:23
DEF                                                 2FF225
IODev                                            CUL_0
LASTInputDev                               CUL_0
MSGCNT                                        6
NAME                                             RolloKueche
NR                                                 337
STATE                                           MISSING ACK
TYPE                                             CUL_HM
lastMsg                                         No:68 - t:10 s:2FF225 d:000000 0601C000
protCmdDel                                  11
protLastRcv                                  2015-08-18 09:05:23
protResnd                                    21 last_at:2015-08-18 09:27:20
protResndFail                               7 last_at:2015-08-18 09:27:24
protSnd                                        7 last_at:2015-08-18 09:27:10
protState                                      CMDs_done_Errors:1
rssi_at_CUL_0                              avg:-69.83 min:-76.5 max:-58.5 lst:-62 cnt:6

[frank]

deine spärlichen infos lassen vermuten, dass das pairing nicht funktioniert hat.

Code Auswählen Erweitern

CUL_0_RAWMSG                           A0D6884102FF2250000000601C000::-62:CUL_0
ein list wäre, wie immer informativer.

[crazystone]

Hallo Frank,

danke für deine Antwort.

1.) Was meinst Du bitte genau mit "list", wie erzeuge ich das?

2.) Was kann die Ursache sein, dass das Pairing nicht funktioniert? Wie kontrolliere ich es? Der Aktor wurde komplett korrekt angelegt nachdem ich

        set CUL_0 hmPairSerial LEQxxxxx

     gemacht habe (so habe ich das bei den anderen auch erfolgreich gemacht). Der Rolladenstatus wird wie gesagt auch korrekt angezeigt.

[frank]

Code Auswählen Erweitern

list RolloKueche
in die eingabezeile. den text dann hier mit code-tags posten. 

[crazystone]

Das war einfach 

Code Auswählen Erweitern



Internals:
   CFGFN
   CUL_0_MSGCNT 8
   CUL_0_RAWMSG A0D6B84102FF2250000000601C800::-62.5:CUL_0
   CUL_0_RSSI -62.5
   CUL_0_TIME 2015-08-18 09:47:39
   DEF        2FF225
   IODev      CUL_0
   LASTInputDev CUL_0
   MSGCNT     8
   NAME       RolloKueche
   NR         337
   STATE      MISSING ACK
   TYPE       CUL_HM
   lastMsg    No:6B - t:10 s:2FF225 d:000000 0601C800
   protCmdDel 12
   protLastRcv 2015-08-18 09:47:39
   protResnd  24 last_at:2015-08-18 09:47:46
   protResndFail 8 last_at:2015-08-18 09:47:51
   protSnd    8 last_at:2015-08-18 09:47:32
   protState  CMDs_done_Errors:1
   rssi_at_CUL_0 avg:-68.18 min:-76.5 max:-58.5 lst:-62.5 cnt:8
   Readings:
     2015-08-18 08:58:47   D-firmware      2.3
     2015-08-18 08:58:47   D-serialNr      LEQ1022xxx
     2015-08-18 09:47:39   deviceMsg       on (to broadcast)
     2015-08-18 09:47:39   level           100
     2015-08-18 09:47:39   levelMissed     desired:0
     2015-08-18 09:47:39   motor           stop:on
     2015-08-18 09:47:39   pct             100
     2015-08-18 09:47:39   recentStateType info
     2015-08-18 09:47:51   state           MISSING ACK
     2015-08-18 09:47:39   timedOn         off
   Helper:
     cSnd       11F110342FF225020100
     mId        006A
     rxType     1
     Io:
       newChn     +2FF225,00,01,00
       nextSend   1439891259.47766
       prefIO
       rxt        0
       vccu
       p:
         2FF225
         00
         01
         00
     Mrssi:
       mNo        6B
       Io:
         CUL_0      -60.5
     Prt:
       bErr       0
       sProc      0
     Q:
       qReqConf   00
       qReqStat
     Role:
       chn        1
       dev        1
       prs        1
     Rssi:
       At_cul_0:
         avg        -68.1875
         cnt        8
         lst        -62.5
         max        -58.5
         min        -76.5
Attributes:
   IODev      CUL_0
   autoReadReg 4_reqStatus
   expert     2_full
   firmware   2.3
   model      HM-LC-Bl1PBU-FM
   room       3_ErdGeschoss
   serialNr   LEQ1022xxx
   subType    blindActuator
   webCmd     statusRequest:toggle:on:off:up:down:stop



Serials verfälscht, damit mir nicht jemand zu Hause hilft die hochzufahren... 

[frank]

Serials verfälscht, damit mir nicht jemand zu Hause hilft die hochzufahren...

das hat keinen sinn. damit kanst du nur verhindern, dass dieser aktor nicht durch jemand anders gepairt wird, solange er ungepairt ist. sicher ist nur aes mit eigenem schlüssel.

also einfach drüberpairen, bis es funktioniert (reading pairCentral und zwar ohne "set_").
wenn nicht, raw-messages vom pairing posten.

[crazystone]

Also ich bekomme keinen anderen Zustand hin. Kannst Du bitte nochmals kurz erklären, wie ich ein raw-message listing vom pairing bekomme?

Danke!

[frank]

 siehe wiki homematic sniffen

[crazystone]

So, hier nochmal der gescheiterte Pairing Vorgang als rawmessages vom Log. Dazu wurde logging gemäß wiki aktiviert:

Code Auswählen Erweitern


attr global verbose 1
attr global mseclog 1
attr CUL_0 verbose 4


Ausgangszustand war ein komplett gelöschter Aktor. Nach

Code Auswählen Erweitern


set CUL_0 hmPairSerial LEQ1022034


erscheint das folgende im Log:

Code Auswählen Erweitern



2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:52:32 1: Error:  has no TYPE
2015.08.19 04:52:32 1: Error: CUL_HM_HM_LC_Bl1PBU_FM_2FF225 has no TYPE
2015.08.19 04:53:06 4: CUL_send:  CUL_0As 15 10 8401 F11034 000000 010A4c455131303232303334
2015.08.19 04:53:06 4: CUL_Parse: CUL_0 A 1A 10 8000 2FF225 F11034 23006A4C4551313032323033343001010010 -66
2015.08.19 04:53:06 1: PERL WARNING: Use of uninitialized value $name in hash element at ./FHEM/10_CUL_HM.pm line 7102.
2015.08.19 04:53:06 1: PERL WARNING: Use of uninitialized value $name in hash element at ./FHEM/10_CUL_HM.pm line 7106.
2015.08.19 04:53:06 1: PERL WARNING: Use of uninitialized value $devN in hash element at ./FHEM/10_CUL_HM.pm line 7107.
2015.08.19 04:53:06 1: RCV L:1A N:10 F:80 CMD:00 SRC:2FF225 DST:F11034 23006A4C45513130323230333430010100 (DEVICE_INFO FIRMWARE:0x23 TYPE:0x006A SERIALNO:LEQ1022034 CLASS:0x30 PEER_CHANNEL_A:0x01 PEER_CHANNEL_B:0x01 UNKNOWN:0x00) (,RPTEN)



In dem Fall wurde der Aktor CUL_HM_HM_LC_Bl1PBU_FM_2FF225 dann auch gar nicht erst angelegt. Am Aktor selbst kann man anhand der blinkenden grünen LED beobachten, dass der auch in den Pairing Mode versetzt wird.

[frank]

Code Auswählen Erweitern

attr global mseclog 1
millisekunden kann ich aber nicht erkennen.

das device hat aber geantwortet und könnte somit bereits gepaired sein

Code Auswählen Erweitern

2015.08.19 04:53:06 4: CUL_send:  CUL_0As 15 10 8401 F11034 000000 010A4c455131303232303334
2015.08.19 04:53:06 4: CUL_Parse: CUL_0 A 1A 10 8000 2FF225 F11034 23006A4C4551313032323033343001010010 -66

du hast am cul das attr hmProtocolEvents gesetzt. das solltest du grundsätzlich abschalten. ist sehr belastend und kann timingprobleme verursachen.
die ganzen fehlermeldungen sind auch nicht gut. eventuell macht auch das aktuelle cul_hm probleme.

ich würde das device nochmal mit delete löschen, dann save und fhem restart. dann neu pairen. wenn immer noch probleme sind, eine ältere version probieren oder auf martin warten. 
oder ist dein fhem nicht aktuell genug? version?

[crazystone]

So, hier nun nochmals mit msec... 

...und von einem Versuch (nach Löschung und Neustart), bei dem zumindest dann der Aktor neu angelegt wurde...

Code Auswählen Erweitern



2015.08.19 14:16:11.737 4: CUL_Parse: CUL_0 A 1A 18 8400 2FF225 000000 23006A4C4551313032323033343001010009 -69.5
2015.08.19 14:16:11.903 1: RCV L:1A N:18 F:84 CMD:00 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:broadcast 23006A4C45513130323230333430010100 (DEVICE_INFO FIRMWARE:0x23 TYPE:0x006A SERIALNO:LEQ1022034 CLASS:0x30 PEER_CHANNEL_A:0x01 PEER_CHANNEL_B:0x01 UNKNOWN:0x00) (,BCAST,RPTEN)
2015.08.19 14:16:11.986 4: CUL_send:  CUL_0As 10 06 A001 F11034 2FF225 00050000000000
2015.08.19 14:16:12.002 1: SND L:10 N:06 F:A0 CMD:01 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00050000000000 (CONFIG_START CHANNEL:0x00 PEER_ADDRESS:0x000000 PEER_CHANNEL:0x00 PARAM_LIST:0x00) (,BIDI,RPTEN)
2015.08.19 14:16:12.133 4: CUL_Parse: CUL_0 A 0A 06 8002 2FF225 F11034 000E -67
2015.08.19 14:16:12.143 1: RCV L:0A N:06 F:80 CMD:02 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 00 (ACK) (,RPTEN)
2015.08.19 14:16:12.236 4: CUL_send:  CUL_0As 13 07 A001 F11034 2FF225 000802010AF10B100C34
2015.08.19 14:16:12.253 1: SND L:13 N:07 F:A0 CMD:01 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 000802010AF10B100C34 (CONFIG_WRITE_INDEX CHANNEL:0x00 DATA: 02:01 0A:F1 0B:10 0C:34) (,BIDI,RPTEN)
2015.08.19 14:16:12.384 4: CUL_Parse: CUL_0 A 0A 07 8002 2FF225 F11034 000D -67.5
2015.08.19 14:16:12.393 1: RCV L:0A N:07 F:80 CMD:02 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 00 (ACK) (,RPTEN)
2015.08.19 14:16:12.486 4: CUL_send:  CUL_0As 0B 08 A001 F11034 2FF225 0006
2015.08.19 14:16:12.501 1: SND L:0B N:08 F:A0 CMD:01 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 0006 (CONFIG_END CHANNEL:0x00) (,BIDI,RPTEN)
2015.08.19 14:16:12.634 4: CUL_Parse: CUL_0 A 0A 08 8002 2FF225 F11034 000F -66.5
2015.08.19 14:16:12.644 1: RCV L:0A N:08 F:80 CMD:02 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 00 (ACK) (,RPTEN)
2015.08.19 14:16:12.736 4: CUL_send:  CUL_0As 10 09 A001 F11034 2FF225 00040000000000
2015.08.19 14:16:12.753 1: SND L:10 N:09 F:A0 CMD:01 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00040000000000 (CONFIG_PARAM_REQ CHANNEL:0x00 PEER_ADDRESS:0x000000 PEER_CHANNEL:0x00 PARAM_LIST:0x00) (,BIDI,RPTEN)
2015.08.19 14:16:12.895 4: CUL_Parse: CUL_0 A 16 09 A010 2FF225 F11034 0202010AF10B100C3415FF180010 -66
2015.08.19 14:16:12.906 1: RCV L:16 N:09 F:A0 CMD:10 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 0202010AF10B100C3415FF1800 (INFO_PARAM_RESPONSE_PAIRS DATA:0x02010AF10B100C3415FF1800) (,BIDI,RPTEN)
2015.08.19 14:16:12.997 4: CUL_send:  CUL_0As 0A 09 8002 F11034 2FF225 00
2015.08.19 14:16:13.012 1: SND L:0A N:09 F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)
2015.08.19 14:16:13.146 4: CUL_Parse: CUL_0 A 0C 0A A010 2FF225 F11034 0300000F -66.5
2015.08.19 14:16:13.157 1: RCV L:0C N:0A F:A0 CMD:10 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 030000 (INFO_PARAM_RESPONSE_SEQ OFFSET:0x00 DATA:0x00) (,BIDI,RPTEN)
2015.08.19 14:16:13.249 4: CUL_send:  CUL_0As 0A 0A 8002 F11034 2FF225 00
2015.08.19 14:16:13.264 1: SND L:0A N:0A F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)
2015.08.19 14:16:13.278 4: CUL_send:  CUL_0As 10 0A A001 F11034 2FF225 01040000000001
2015.08.19 14:16:13.295 1: SND L:10 N:0A F:A0 CMD:01 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 01040000000001 (CONFIG_PARAM_REQ CHANNEL:0x01 PEER_ADDRESS:0x000000 PEER_CHANNEL:0x00 PARAM_LIST:0x01) (,BIDI,RPTEN)
2015.08.19 14:16:13.435 4: CUL_Parse: CUL_0 A 14 0A A010 2FF225 F11034 030800000001F401F405000F -66.5
2015.08.19 14:16:13.446 1: RCV L:14 N:0A F:A0 CMD:10 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 030800000001F401F40500 (INFO_PARAM_RESPONSE_SEQ OFFSET:0x08 DATA:0x00000001F401F40500) (,BIDI,RPTEN)
2015.08.19 14:16:13.538 4: CUL_send:  CUL_0As 0A 0A 8002 F11034 2FF225 00
2015.08.19 14:16:13.552 1: SND L:0A N:0A F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)
2015.08.19 14:16:13.732 4: CUL_Parse: CUL_0 A 14 0A A010 2FF225 F11034 030800000001F401F4050011 -65.5
2015.08.19 14:16:13.835 4: CUL_send:  CUL_0As 0A 0A 8002 F11034 2FF225 00
2015.08.19 14:16:13.849 1: SND L:0A N:0A F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)
2015.08.19 14:16:13.986 4: CUL_Parse: CUL_0 A 0E 0B A010 2FF225 F11034 023006572413 -64.5
2015.08.19 14:16:13.996 1: RCV L:0E N:0B F:A0 CMD:10 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 0230065724 (INFO_PARAM_RESPONSE_PAIRS DATA:0x30065724) (,BIDI,RPTEN)
2015.08.19 14:16:14.088 4: CUL_send:  CUL_0As 0A 0B 8002 F11034 2FF225 00
2015.08.19 14:16:14.103 1: SND L:0A N:0B F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)
2015.08.19 14:16:14.237 4: CUL_Parse: CUL_0 A 0C 0C A010 2FF225 F11034 0300000F -66.5
2015.08.19 14:16:14.248 1: RCV L:0C N:0C F:A0 CMD:10 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 030000 (INFO_PARAM_RESPONSE_SEQ OFFSET:0x00 DATA:0x00) (,BIDI,RPTEN)
2015.08.19 14:16:14.340 4: CUL_send:  CUL_0As 0A 0C 8002 F11034 2FF225 00
2015.08.19 14:16:14.354 1: SND L:0A N:0C F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)
2015.08.19 14:16:14.369 4: CUL_send:  CUL_0As 0B 0B A001 F11034 2FF225 0103
2015.08.19 14:16:14.385 1: SND L:0B N:0B F:A0 CMD:01 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 0103 (CONFIG_PEER_LIST_REQ CHANNEL:0x01) (,BIDI,RPTEN)
2015.08.19 14:16:14.521 4: CUL_Parse: CUL_0 A 0E 0B A010 2FF225 F11034 010000000011 -65.5
2015.08.19 14:16:14.533 1: RCV L:0E N:0B F:A0 CMD:10 SRC:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 DST:F11034 0100000000 (INFO_PEER_LIST PEER1:broadcast) (,BIDI,RPTEN)
2015.08.19 14:16:14.623 4: CUL_send:  CUL_0As 0A 0B 8002 F11034 2FF225 00
2015.08.19 14:16:14.638 1: SND L:0A N:0B F:80 CMD:02 SRC:F11034 DST:CUL_HM_HM_LC_Bl1PBU_FM_2FF225 00 (ACK) (,RPTEN)



[frank]

und was fehlt? sieht doch vielversprechend aus. pairing hat funktioniert. poste ein list vom device. mach ein configcheck mit hminfo, dann weisst du, ob alles da ist.

du hast am cul das attr hmProtocolEvents gesetzt. das solltest du grundsätzlich abschalten. ist sehr belastend und kann timingprobleme verursachen.

[crazystone]

Hahaha....ich hasse Probleme, die sich von selbst in Luft auflösen...

Nach diesem Mal hat das manuelle Pairing (CUL hmPairForSec 600; Config-Taste) so funktioniert, dass der Aktor sich auch ansteuern lässt. Warum auch immer.

Ich werde es wohl jetzt wieder für Monate nicht anfassen...never touch a running System!

Jetzt kommt auch das pairingCentral reading.

Danke noch für Deine Hilfe!

[crazystone]

du hast am cul das attr hmProtocolEvents gesetzt. das solltest du grundsätzlich abschalten. ist sehr belastend und kann timingprobleme verursachen.

...hab ich jetzt auch abgeschaltet.  Danke!