HM-SEC-SC MISSING ACK

juppzupp · 21 Dezember 2013, 17:11:47

Hallo zusammen,

habe mehrere SEC-SC im Einsatz, seit cirka 4 Monaten. Bei einem ist mir aufgefallen, das die LED sehr sehr lange gelb bleibt beim Kontaktwechsel. Ein Blick in FHEM hat mir dann missing ack gezeigt.
Habe zum testen 2 SEC-SC vor mir direkt nebeneinander liegen, nur einer zeigt dieses Verhalten.
Habe die Batterien untereinander getauscht, der Fehler wandert nicht.
habe sicherheitshalber fhem rebootet (den hm-usb dabei stromlos gemacht), der fehler bleibt gleich.
habe den sensor auf werkszustand zurückgesetzt, und neu angelernt, der fehler bleibt. manchmal sehr lange gelb, manchmal rot, obwohl in fhem die zustandänderung erkannt wird.

Code Auswählen

2013-12-21 17:06:25 HMLAN hmusb hmPairForSec 600
->Anlerntaste
2013-12-21 17:06:38 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-21 17:06:47 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK
->Anlerntaste
2013-12-21 17:07:02 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-21 17:07:22 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK
2013-12-21 17:07:26 CUL_HM CUL_HM_HM_SEC_SC_200D90 open
2013-12-21 17:07:26 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: open (to hmusb)
2013-12-21 17:07:29 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-21 17:07:29 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to hmusb)
2013-12-21 17:07:42 CUL_HM CUL_HM_HM_SEC_SC_200D90 open
2013-12-21 17:07:42 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: open (to hmusb)
2013-12-21 17:07:46 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-21 17:07:46 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to hmusb)
2013-12-21 17:07:48 CUL_HM CUL_HM_HM_SEC_SC_200D90 open
2013-12-21 17:07:48 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: open (to hmusb)
2013-12-21 17:07:50 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-21 17:07:50 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to hmusb)

Wie wahrscheinlich ist ein defekt ? kann ich noch etwas tun um den Fehler einzugrenzen ?

Danke !
jupp

martinp876 · 22 Dezember 2013, 09:31:49

Hallo Jupp,

kannst du die roh-messages aufzeichnen? Anleitung:
http://forum.fhem.de/index.php/topic,16563.msg107848.html#msg107848

Aktionen zum Aufzeichnen:
Kontakt öffnen/schliessen
getConfig absetzten und dann Anlernen drücken.

dann noch ein List des Device.
list CUL_HM_HM_SEC_SC_200D90

mal sehen...
Gruss Martin

juppzupp · 22 Dezember 2013, 14:41:37

Martin,

schonmal vielen Dank im voraus. Beim Versuch um 14:27 signalisiert die LED rot, um 14:33 hingegen grün.

Code Auswählen

list CUL_HM_HM_SEC_SC_200D90
fhem> list CUL_HM_HM_SEC_SC_200D90
Internals:
   DEF        200D90
   IODev      hmusb
   LASTInputDev hmusb
   MSGCNT     7
   NAME       CUL_HM_HM_SEC_SC_200D90
   NR         131
   STATE      MISSING ACK
   TYPE       CUL_HM
   hmusb_MSGCNT 7
   hmusb_RAWMSG E200D90,0000,0003D0B9,FF,FFC9,058400200D9000000020002F4B45513030393136303880810101
   hmusb_RSSI -55
   hmusb_TIME 2013-12-22 14:28:06
   lastMsg    No:05 - t:00 s:200D90 d:000000 20002F4B45513030393136303880810101
   protCmdDel 11
   protLastRcv 2013-12-22 14:28:06
   protResndFail 2 last_at:2013-12-22 14:28:11
   protSnd    3 last_at:2013-12-22 14:28:06
   protState  CMDs_done_Errors:1
   rssi_at_hmusb avg:-53.57 min:-55 max:-50 lst:-55 cnt:7 
   Readings:
     2013-12-22 14:28:06   Activity        alive
     2013-12-22 14:27:34   CommandAccepted yes
     2013-12-22 14:10:03   PairedTo        0xFECAFE
     2013-12-22 14:13:27   R-cyclicInfoMsg set_off
     2013-12-21 16:59:59   R-intKeyVisib   invisib
     2013-12-22 14:27:33   R-pairCentral   set_0xFECAFE 
     2013-12-22 14:13:27   R-sabotageMsg   set_on
     2013-12-22 14:13:27   R-transmDevTryMax set_6
     2013-12-22 14:13:18   aesKeyNbr       FF
     2013-12-22 14:27:32   alive           yes
     2013-12-22 14:27:32   battery         ok
     2013-12-22 14:27:49   contact         closed (to broadcast)
     2013-12-22 14:27:32   recentStateType info
     2013-12-22 14:27:32   sabotageError   on
     2013-12-22 14:28:11   state           MISSING ACK
   Helper:
     getCfgList all
     getCfgListNo ,4
     mId        002F
     rxType     4
     Prt:
       bErr       0
       mmcS       1
       sProc      0
       mmcA:
         ++A001FECAFE200D9000050000000000
       Rspwait:
     Q:
       qReqConf   
       qReqStat   
     Role:
       chn        1
       dev        1
     Rssi:
       At_hmusb:
         avg        -53.5714285714286
         cnt        7
         lst        -55
         max        -50
         min        -55
     Shadowreg:
       RegL_00:    02:01 0A:FE 0B:CA 0C:FE
Attributes:
   IODev      hmusb
   actCycle   028:00
   actStatus  alive
   autoReadReg 4_reqStatus
   comment    Wohnungstüre
   devStateIcon closed:fts_door_right@00FE00 open:fts_door_right_open@FF0000
   expert     2_full
   firmware   2.0
   fp_Wohnung 551,635,0,
   model      HM-SEC-SC
   peerIDs    
   serialNr   KEQ0091608
   subType    threeStateSensor

Code Auswählen

2013.12.22 14:24:47.368 1: Including ./log/fhem.save
2013.12.22 14:24:48.007 1: usb create starting
2013.12.22 14:24:57.846 1: usb create end
2013.12.22 14:24:57.861 0: Server started with 97 defined entities (version $Id: fhem.pl 4386 2013-12-15 17:09:05Z rudolfkoenig $, os linux, user fhem, pid 1922)
2013.12.22 14:24:58.865 1: 127.0.0.1:1235 reappeared (hmusb)
2013.12.22 14:24:58.885 1: HMLAN_Parse: hmusb new condition init
2013.12.22 14:25:03.786 1: HMLAN_Parse: hmusb new condition ok
2013.12.22 14:26:39.052 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:26:39.069 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:000279FB IDcnt:0002
2013.12.22 14:27:04.063 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:27:04.099 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:0002DBAE IDcnt:0002
2013.12.22 14:27:09.860 0: HMLAN_Parse: hmusb R:E1E96D0   stat:0000 t:0002F22A d:FF r:FFB5     m:98 8410 1E96D0 FECAFE 0601A900
2013.12.22 14:27:27.080 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:0003356D d:FF r:FFCB     m:00 8441 200D90 000000 010100
2013.12.22 14:27:29.068 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:27:29.096 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:00033D5A IDcnt:0002
2013.12.22 14:27:32.233 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:0003499E d:FF r:FFC9     m:01 8610 200D90 000000 0601000E
2013.12.22 14:27:33.161 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:00034D36 d:FF r:FFCA     m:02 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.22 14:27:33.297 0: HMLAN_Send:  hmusb I:+200D90,00,00,
2013.12.22 14:27:33.318 0: HMLAN_Send:  hmusb S:S1A7D4407 stat:  00 t:00000000 d:01 r:1A7D4407 m:06 A001 FECAFE 200D90 00050000000000
2013.12.22 14:27:34.409 0: HMLAN_Parse: hmusb R:R1A7D4407 stat:0001 t:00035210 d:FF r:FFC9     m:06 8002 200D90 FECAFE 00
2013.12.22 14:27:34.427 0: HMLAN_Send:  hmusb S:S1A7D485D stat:  00 t:00000000 d:01 r:1A7D485D m:07 A001 FECAFE 200D90 000802010AFE0BCA0CFE
2013.12.22 14:27:35.243 0: HMLAN_Parse: hmusb R:R1A7D485D stat:0008 t:00000000 d:FF r:7FFF     m:07 A001 FECAFE 200D90 000802010AFE0BCA0CFE
2013.12.22 14:27:35.248 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.22 14:27:47.724 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:0003861C d:FF r:FFCB     m:03 8441 200D90 000000 0103C8
2013.12.22 14:27:49.965 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:00038EE6 d:FF r:FFCE     m:04 8441 200D90 000000 010400
2013.12.22 14:27:54.073 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:27:54.671 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:0003A157 IDcnt:0003
2013.12.22 14:28:06.833 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:0003D0B9 d:FF r:FFC9     m:05 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.22 14:28:06.892 0: HMLAN_Send:  hmusb S:S1A7DC724 stat:  00 t:00000000 d:01 r:1A7DC724 m:08 A001 FECAFE 200D90 00050000000000
2013.12.22 14:28:07.505 0: HMLAN_Parse: hmusb R:R1A7DC724 stat:0008 t:00000000 d:FF r:7FFF     m:08 A001 FECAFE 200D90 00050000000000
2013.12.22 14:28:07.506 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.22 14:28:19.666 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:28:19.699 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:00040301 IDcnt:0003

Code Auswählen

2013-12-22 14:27:27.508 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-22 14:27:27.508 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to broadcast)
2013-12-22 14:27:32.583 dummy Tueren zu
2013-12-22 14:27:32.694 CUL_HM CUL_HM_HM_SEC_SC_200D90 alive: yes
2013-12-22 14:27:32.694 CUL_HM CUL_HM_HM_SEC_SC_200D90 battery: ok
2013-12-22 14:27:32.694 CUL_HM CUL_HM_HM_SEC_SC_200D90 sabotageError: on
2013-12-22 14:27:32.694 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-22 14:27:32.694 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to broadcast)
2013-12-22 14:27:33.474 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-22 14:27:33.474 CUL_HM CUL_HM_HM_SEC_SC_200D90 R-pairCentral: set_0xFECAFE 
2013-12-22 14:27:36.618 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-22 14:27:36.709 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK
2013-12-22 14:27:48.007 dummy Tueren auf
2013-12-22 14:27:48.097 CUL_HM CUL_HM_HM_SEC_SC_200D90 open
2013-12-22 14:27:48.097 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: open (to broadcast)
2013-12-22 14:27:50.207 dummy Tueren zu
2013-12-22 14:27:50.297 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-22 14:27:50.297 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to broadcast)
set CUL_HM_HM_SEC_SC_200D90 getConfig
fhem> 2013-12-22 14:28:07.059 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-22 14:28:11.707 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-22 14:28:11.810 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK

Code Auswählen

2013.12.22 14:33:05.011 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:00085D78 d:FF r:FFCE     m:06 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.22 14:33:05.068 0: HMLAN_Send:  hmusb S:S1A8253EE stat:  00 t:00000000 d:01 r:1A8253EE m:09 A001 FECAFE 200D90 00050000000000
2013.12.22 14:33:05.235 0: HMLAN_Parse: hmusb R:R1A8253EE stat:0001 t:00085E6B d:FF r:FFCB     m:09 8002 200D90 FECAFE 00
2013.12.22 14:33:05.255 0: HMLAN_Send:  hmusb S:S1A8254A9 stat:  00 t:00000000 d:01 r:1A8254A9 m:0A A001 FECAFE 200D90 000802010AFE0BCA0CFE
2013.12.22 14:33:05.651 0: HMLAN_Parse: hmusb R:R1A8254A9 stat:0001 t:00085FFC d:FF r:FFC9     m:0A 8002 200D90 FECAFE 00
2013.12.22 14:33:05.661 0: HMLAN_Send:  hmusb S:S1A82563F stat:  00 t:00000000 d:01 r:1A82563F m:0B A001 FECAFE 200D90 0006
2013.12.22 14:33:06.035 0: HMLAN_Parse: hmusb R:R1A82563F stat:0001 t:0008618D d:FF r:FFC7     m:0B 8002 200D90 FECAFE 00
2013.12.22 14:33:06.056 0: HMLAN_Send:  hmusb S:S1A8257CA stat:  00 t:00000000 d:01 r:1A8257CA m:0C A001 FECAFE 200D90 00040000000000
2013.12.22 14:33:06.899 0: HMLAN_Parse: hmusb R:R1A8257CA stat:0008 t:00000000 d:FF r:7FFF     m:0C A001 FECAFE 200D90 00040000000000
2013.12.22 14:33:06.901 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.22 14:33:20.250 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:33:20.278 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:00089923 IDcnt:0003
2013.12.22 14:33:32.985 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:0008CABC d:FF r:FFD4     m:07 A441 200D90 FECAFE 0107C8
2013.12.22 14:33:33.082 0: HMLAN_Send:  hmusb S:+200D90,01,01,FE1F
2013.12.22 14:33:33.083 0: HMLAN_Send:  hmusb S:S1A82C10E stat:  00 t:00000000 d:01 r:1A82C10E m:07 8002 FECAFE 200D90 0101C800
2013.12.22 14:33:33.600 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008CBB8 d:FF r:FFD2     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:33.619 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008CBB8 d:01 r:FFD2     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:33.721 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008CDB1 d:FF r:FFD1     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:34.041 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008CDB1 d:01 r:FFD1     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:34.297 0: HMLAN_Parse: hmusb R:R1A82C10E stat:0002 t:00000000 d:FF r:7FFF     m:07 8002 FECAFE 200D90 0101C800
2013.12.22 14:33:34.745 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008D1A1 d:FF r:FFD1     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:35.033 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008D1A1 d:01 r:FFD1     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:36.762 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008D984 d:FF r:FFD0     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:37.146 0: HMLAN_Parse: hmusb R:E200D90   stat:0040 t:0008D984 d:01 r:FFD0     m:07 A041 200D90 FECAFE 0107C8
2013.12.22 14:33:38.971 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008E22C d:FF r:FFD5     m:08 A441 200D90 FECAFE 010800
2013.12.22 14:33:39.073 0: HMLAN_Send:  hmusb S:S1A82D867 stat:  00 t:00000000 d:01 r:1A82D867 m:08 8002 FECAFE 200D90 0101C800
2013.12.22 14:33:39.488 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008E22C d:01 r:FFD5     m:08 A441 200D90 FECAFE 010800
2013.12.22 14:33:39.550 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008E328 d:01 r:FFD6     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:39.738 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008E520 d:FF r:FFCF     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:40.026 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008E520 d:01 r:FFCF     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:40.314 0: HMLAN_Parse: hmusb R:R1A82D867 stat:0002 t:00000000 d:FF r:7FFF     m:08 8002 FECAFE 200D90 0101C800
2013.12.22 14:33:40.730 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008E910 d:FF r:FFC1     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:41.051 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:0008E910 d:01 r:FFC1     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:42.747 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:0008F0EF d:FF r:FFC2     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:43.132 0: HMLAN_Parse: hmusb R:E200D90   stat:0040 t:0008F0EF d:01 r:FFC2     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:45.255 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:33:45.276 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:0008FACE IDcnt:0003
2013.12.22 14:33:47.036 0: HMLAN_Parse: hmusb R:E200D90   stat:0100 t:000901A5 d:FF r:FFC6     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:47.324 0: HMLAN_Parse: hmusb R:E200D90   stat:0050 t:000901A5 d:01 r:FFC6     m:08 A041 200D90 FECAFE 010800
2013.12.22 14:33:55.006 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:000920C8 d:FF r:FFC9     m:09 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.22 14:33:55.065 0: HMLAN_Send:  hmusb S:+200D90,01,01,FE1F
2013.12.22 14:33:55.067 0: HMLAN_Send:  hmusb S:S1A83173B stat:  00 t:00000000 d:01 r:1A83173B m:0D A001 FECAFE 200D90 00050000000000
2013.12.22 14:33:55.710 0: HMLAN_Parse: hmusb R:R1A83173B stat:0008 t:00000000 d:FF r:7FFF     m:0D A001 FECAFE 200D90 00050000000000
2013.12.22 14:33:55.712 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.22 14:34:10.263 0: HMLAN_Send:  hmusb I:K
2013.12.22 14:34:10.273 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:00095C7E IDcnt:0003

Code Auswählen

2013-12-22 14:33:05.207 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-22 14:33:10.796 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-22 14:33:10.897 CUL_HM CUL_HM_HM_SEC_SC_200D90 RESPONSE TIMEOUT:RegisterRead
2013-12-22 14:33:33.417 dummy Tueren auf
2013-12-22 14:33:33.597 CUL_HM CUL_HM_HM_SEC_SC_200D90 open
2013-12-22 14:33:33.597 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: open (to hmusb)
2013-12-22 14:33:39.355 dummy Tueren zu
2013-12-22 14:33:39.474 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-22 14:33:39.474 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to hmusb)
set CUL_HM_HM_SEC_SC_200D90 getConfig
fhem> 2013-12-22 14:33:55.216 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-22 14:33:58.694 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-22 14:33:58.808 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK

martinp876 · 22 Dezember 2013, 16:26:46

bist du auch auf der neusten SW? Das Timing ist etwas hurtig für die Devices.

juppzupp · 22 Dezember 2013, 16:40:31

Hmland/hmcfgusb haben ein paar Tage auf dem Buckel.
Fhem update check/update heute vor dem test.
Oder meinst du was anderes?
2 andere sec-sc (und anderes) klappen einwandfrei.

Gruesse

martinp876 · 22 Dezember 2013, 16:56:46

hm - wenn alles auf dem aktuellen Stand ist muss es ein Problem in der fhem-SW - timing-Berechnung vorliegen.

evtl ein update force machen und mit version kontrollieren - sonst muss ich suchen

juppzupp · 22 Dezember 2013, 17:01:47

Würde dir (na gut mir) eine Telefon/teamviewer session helfen?

Code Auswählen


fhem> version
# $Id: fhem.pl 4386 2013-12-15 17:09:05Z rudolfkoenig $
# $Id: 10_CUL_HM.pm 4435 2013-12-21 13:59:29Z martinp876 $
# $Id: 01_FHEMWEB.pm 4434 2013-12-21 09:26:06Z rudolfkoenig $
# $Id: 95_FLOORPLAN.pm 3971 2013-09-29 08:16:39Z ulimaass $
# $Id: 10_FS20.pm 3764 2013-08-22 07:09:38Z rudolfkoenig $
# $Id: 92_FileLog.pm 3759 2013-08-21 08:13:08Z rudolfkoenig $
# $Id: 00_HMLAN.pm 4370 2013-12-12 21:42:15Z martinp876 $
# $Id: 98_HMinfo.pm 4416 2013-12-19 12:04:17Z martinp876 $
# $Id: 99_RpiUtils.pm $
# $Id: 99_SUNRISE_EL.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 99_Utils.pm 3595 2013-08-05 05:38:48Z tobiasfaust $
# $Id: 90_at.pm 4246 2013-11-18 20:35:20Z rudolfkoenig $
# $Id: 98_autocreate.pm 4234 2013-11-17 10:19:41Z rudolfkoenig $
# $Id: 98_dummy.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 91_notify.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 98_telnet.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 98_weblink.pm 3770 2013-08-23 13:29:58Z rudolfkoenig $
fhem>

martinp876 · 23 Dezember 2013, 11:33:35

Hallo Jupp,

probiere einmal die angehängte Datei. Einfach die vorhandene Überschreiben (ggf vorher sichern, wenn du willst) dann restart oder auch reload 00_HMLAN machen - klappt beides.
Und dann noch einmal testen - ggf. roh-messages schicken, wenns nicht klappt (oder sowieso)...

Wenn es erfolgreich ist und du sonst nichts feststellst wird es so eingebaut

juppzupp · 23 Dezember 2013, 12:19:10

Hi,

bin mir nicht sicher ob ich alles richtig gemacht habe ? (Version bleibt gleich)
peeren wird mit rot quittiert
open/close mit orange (da wohl nicht richtig gepeert?)

Code Auswählen

jupp@quiety:~/Downloads$ scp 00_HMLAN.pm root@fhem:/opt/fhem/FHEM
root@fhem's password: 
00_HMLAN.pm                                   100%   40KB  39.8KB/s   00:00    
jupp@quiety:~/Downloads$

Code Auswählen

root@fhem:/opt/fhem/FHEM# diff 00_HMLAN.pm /root/00_HMLAN.pm 
615,617c615
< 
<     Log 1,"Generl wait $src" if (!(hex($flg)&0x22));
< #    if (hex($flg)&0x22){#not sure: 4 oder 2 ? 0x.2 works for VD!
---
>     if (hex($flg)&0x22){#not sure: 4 oder 2 ? 0x.2 works for VD!
620c618
< #    }
---
>     }

Code Auswählen

root@fhem:/opt/fhem/FHEM# /etc/init.d/fhem stop
Stopping fhem...
root@fhem:/opt/fhem/FHEM# /etc/init.d/fhem start
Starting fhem...
root@fhem:/opt/fhem/FHEM# telnet localhost 7072
Trying 127.0.0.1...
Connected to localhost.
Escape character is '^]'.
version
# $Id: fhem.pl 4386 2013-12-15 17:09:05Z rudolfkoenig $
# $Id: 10_CUL_HM.pm 4435 2013-12-21 13:59:29Z martinp876 $
# $Id: 01_FHEMWEB.pm 4434 2013-12-21 09:26:06Z rudolfkoenig $
# $Id: 95_FLOORPLAN.pm 3971 2013-09-29 08:16:39Z ulimaass $
# $Id: 10_FS20.pm 3764 2013-08-22 07:09:38Z rudolfkoenig $
# $Id: 92_FileLog.pm 3759 2013-08-21 08:13:08Z rudolfkoenig $
# $Id: 00_HMLAN.pm 4370 2013-12-12 21:42:15Z martinp876 $
# $Id: 98_HMinfo.pm 4416 2013-12-19 12:04:17Z martinp876 $
# $Id: 99_RpiUtils.pm $
# $Id: 99_SUNRISE_EL.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 99_Utils.pm 3595 2013-08-05 05:38:48Z tobiasfaust $
# $Id: 90_at.pm 4246 2013-11-18 20:35:20Z rudolfkoenig $
# $Id: 98_autocreate.pm 4234 2013-11-17 10:19:41Z rudolfkoenig $
# $Id: 98_dummy.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 95_holiday.pm 3602 2013-08-07 13:06:49Z rudolfkoenig $
# $Id: 91_notify.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 98_telnet.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 98_weblink.pm 3770 2013-08-23 13:29:58Z rudolfkoenig $

fhem> 
fhem> 
fhem> inform timer
fhem> attr global verbose 1
fhem> attr global mseclog 1
fhem> attr hmusb logIDs all,sys
logging set to sys,all
fhem> set hmusb hmPairForSec 600
fhem> 
2013-12-23 12:10:21.019 dummy Tueren zu
2013-12-23 12:10:21.086 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-23 12:10:21.086 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to broadcast)
2013-12-23 12:10:26.220 dummy Tueren zu
2013-12-23 12:10:26.312 CUL_HM CUL_HM_HM_SEC_SC_200D90 alive: yes
2013-12-23 12:10:26.312 CUL_HM CUL_HM_HM_SEC_SC_200D90 battery: ok
2013-12-23 12:10:26.312 CUL_HM CUL_HM_HM_SEC_SC_200D90 sabotageError: on
2013-12-23 12:10:26.312 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-23 12:10:26.312 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to broadcast)
2013-12-23 12:10:31.201 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-23 12:10:33.705 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-23 12:10:33.795 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK
2013-12-23 12:10:57.192 dummy Tueren auf
2013-12-23 12:10:57.255 CUL_HM CUL_HM_HM_SEC_SC_200D90 open
2013-12-23 12:10:57.255 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: open (to broadcast)
2013-12-23 12:11:00.521 dummy Tueren zu
2013-12-23 12:11:00.597 CUL_HM CUL_HM_HM_SEC_SC_200D90 closed
2013-12-23 12:11:00.597 CUL_HM CUL_HM_HM_SEC_SC_200D90 contact: closed (to broadcast)
   
fhem> set CUL_HM_HM_SEC_SC_200D90 getConfig
fhem> 2013-12-23 12:11:19.626 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-23 12:11:22.453 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-23 12:11:22.628 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK
list CUL_HM_HM_SEC_SC_200D90
Internals:
   DEF        200D90
   IODev      hmusb
   LASTInputDev hmusb
   MSGCNT     15
   NAME       CUL_HM_HM_SEC_SC_200D90
   NR         131
   STATE      MISSING ACK
   TYPE       CUL_HM
   hmusb_MSGCNT 15
   hmusb_RAWMSG R1F26E6E2,0001,04ACEEDC,FF,FFCE,058002200D90FECAFE00
   hmusb_RSSI -50
   hmusb_TIME 2013-12-23 12:11:20
   lastMsg    No:05 - t:02 s:200D90 d:FECAFE 00
   protCmdDel 17
   protLastRcv 2013-12-23 12:11:20
   protResndFail 3 last_at:2013-12-23 12:11:22
   protSnd    4 last_at:2013-12-23 12:11:20
   protState  CMDs_done_Errors:1
   rssi_at_hmusb avg:-49.06 min:-60 max:-42 lst:-50 cnt:15 
   Readings:
     2013-12-23 12:11:19   Activity        alive
     2013-12-23 12:11:20   CommandAccepted yes
     2013-12-22 14:10:03   PairedTo        0xFECAFE
     2013-12-22 14:13:27   R-cyclicInfoMsg set_off
     2013-12-21 16:59:59   R-intKeyVisib   invisib
     2013-12-23 11:57:26   R-pairCentral   set_0xFECAFE 
     2013-12-22 14:13:27   R-sabotageMsg   set_on
     2013-12-22 14:13:27   R-transmDevTryMax set_6
     2013-12-22 19:44:53   aesKeyNbr       FF
     2013-12-23 12:10:25   alive           yes
     2013-12-23 12:10:25   battery         ok
     2013-12-23 12:11:00   contact         closed (to broadcast)
     2013-12-23 12:10:25   recentStateType info
     2013-12-23 12:10:25   sabotageError   on
     2013-12-23 12:11:22   state           MISSING ACK
   Helper:
     getCfgList all
     getCfgListNo ,4
     mId        002F
     rxType     4
     Prt:
       bErr       0
       mmcS       2
       sProc      0
       mmcA:
         ++A001FECAFE200D9000050000000000
         ++A001FECAFE200D90000802010AFE0BCA0CFE
       Rspwait:
     Q:
       qReqConf   
       qReqStat   
     Role:
       chn        1
       dev        1
     Rssi:
       At_hmusb:
         avg        -49.0666666666667
         cnt        15
         lst        -50
         max        -42
         min        -60
     Shadowreg:
       RegL_00:    02:01 0A:FE 0B:CA 0C:FE
Attributes:
   IODev      hmusb
   actCycle   028:00
   actStatus  alive
   autoReadReg 4_reqStatus
   comment    Wohnungstüre
   devStateIcon closed:fts_door_right@00FE00 open:fts_door_right_open@FF0000
   expert     2_full
   firmware   2.0
   fp_Wohnung 551,635,0,
   model      HM-SEC-SC
   peerIDs    
   room       Alarmanlage
   serialNr   KEQ0091608
   subType    threeStateSensor

fhem>

Code Auswählen

2013.12.23 12:10:20.790 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:04AC0742 d:FF r:FFCE     m:00 8441 200D90 000000 010100
2013.12.23 12:10:20.793 1: Generl wait 200D90
2013.12.23 12:10:25.911 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:04AC1B45 d:FF r:FFCF     m:01 8610 200D90 000000 0601000E
2013.12.23 12:10:27.940 0: HMLAN_Send:  hmusb I:K
2013.12.23 12:10:27.960 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:04AC234F IDcnt:0003
2013.12.23 12:10:30.968 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:04AC2F11 d:FF r:FFCF     m:02 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.23 12:10:31.023 1: Generl wait 200D90
2013.12.23 12:10:31.089 0: HMLAN_Send:  hmusb S:S1F2629F3 stat:  00 t:00000000 d:01 r:1F2629F3 m:04 A001 FECAFE 200D90 00050000000000
2013.12.23 12:10:32.664 0: HMLAN_Parse: hmusb R:R1F2629F3 stat:0008 t:00000000 d:FF r:7FFF     m:04 A001 FECAFE 200D90 00050000000000
2013.12.23 12:10:32.666 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.23 12:10:52.953 0: HMLAN_Send:  hmusb I:K
2013.12.23 12:10:52.989 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:04AC8506 IDcnt:0003
2013.12.23 12:10:56.894 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:04AC9457 d:FF r:FFD1     m:03 8441 200D90 000000 0103C8
2013.12.23 12:10:56.896 1: Generl wait 200D90
2013.12.23 12:11:00.159 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:04ACA108 d:FF r:FFD6     m:04 8441 200D90 000000 010400
2013.12.23 12:11:00.160 1: Generl wait 200D90
2013.12.23 12:11:17.959 0: HMLAN_Send:  hmusb I:K
2013.12.23 12:11:17.987 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:04ACE6B4 IDcnt:0003
2013.12.23 12:11:19.395 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:04ACEC3B d:FF r:FFD2     m:05 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.23 12:11:19.396 1: Generl wait 200D90
2013.12.23 12:11:19.498 0: HMLAN_Send:  hmusb S:S1F26E6E2 stat:  00 t:00000000 d:01 r:1F26E6E2 m:05 A001 FECAFE 200D90 00050000000000
2013.12.23 12:11:20.067 0: HMLAN_Parse: hmusb R:R1F26E6E2 stat:0001 t:04ACEEDC d:FF r:FFCE     m:05 8002 200D90 FECAFE 00
2013.12.23 12:11:20.069 1: Generl wait 200D90
2013.12.23 12:11:20.170 0: HMLAN_Send:  hmusb S:S1F26E988 stat:  00 t:00000000 d:01 r:1F26E988 m:06 A001 FECAFE 200D90 000802010AFE0BCA0CFE
2013.12.23 12:11:20.931 0: HMLAN_Parse: hmusb R:R1F26E988 stat:0008 t:00000000 d:FF r:7FFF     m:06 A001 FECAFE 200D90 000802010AFE0BCA0CFE
2013.12.23 12:11:20.933 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.23 12:11:42.973 0: HMLAN_Send:  hmusb I:K
2013.12.23 12:11:42.984 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:04AD4869 IDcnt:0003

martinp876 · 23 Dezember 2013, 16:20:55

Hallo Jupp,

alles korrekt gemacht.
das Timing ist aber dennoch instabil.

12:11:19.498 Send: hmusb S:S1F26E6E2 stat: 00 t:00000000 d:01 r:1F26E6E2 m:05 A001 FECAFE 200D90 00050000000000
12:11:20.067 Parse: hmusb R:R1F26E6E2 stat:0001 t:04ACEEDC d:FF r:FFCE m:05 8002 200D90 FECAFE 00

der Aktor braucht 568ms zum Antworten.... konnte sein, dass HMLAN einmal wiederholen musste (kann man nicht sehen).
Es sieht aber nachnach aus, dass FHEM (oder das ethernet...) 120ms delay erzeugt. Das sollte eigentlich erkannt werden.
kannst du die logzeile ändern und es noch einmal probieren?
Log 1,"Generl wait $src" if (!(hex($flg)&0x22));
ersetzen mit
Log 1,"General wait $src :".int($dly);

da die Verzögerung so gross ist und wir nicht feststellen können, ob es wiederholt werden musste: wie sieht es mit dem Empfangspegel und den Batterien aus?

Gruss Martin

juppzupp · 23 Dezember 2013, 18:13:14

Huhu,

kein Ethernet, ist ein usb adapter.
Entfernung geschätzte 2m, ein anderer SC der direkt daneben liegt, "zickt" ja auch nicht. (logs weiter unten)
die Batterien vom CUL_HM_HM_SEC_SC_2253BC in den CUL_HM_HM_SEC_SC_200D90 eingelegt, gleiches verhalten.

Code Auswählen

fhem>  reload 00_HMLAN
fhem> inform timer
fhem> attr global verbose 1
fhem> attr global mseclog 1
fhem> attr hmusb logIDs all,sys
logging set to sys,all
fhem> set hmusb hmPairForSec 600
fhem> 2013-12-23 18:03:05.816 CUL_HM CUL_HM_HM_SEC_SC_200D90 Activity: alive
2013-12-23 18:03:11.773 CUL_HM CUL_HM_HM_SEC_SC_200D90 ResndFail
2013-12-23 18:03:11.846 CUL_HM CUL_HM_HM_SEC_SC_200D90 MISSING ACK


2013.12.23 18:02:43.445 0: HMLAN_Send:  hmusb I:K
2013.12.23 18:02:43.469 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:0103D753 IDcnt:0005
2013.12.23 18:03:05.607 0: HMLAN_Parse: hmusb R:E200D90   stat:0000 t:01042DC6 d:FF r:FFCC     m:0B 8400 200D90 000000 20002F4B45513030393136303880810101
2013.12.23 18:03:05.609 1: General wait 200D90 :6
2013.12.23 18:03:05.666 0: HMLAN_Send:  hmusb I:+200D90,00,00,
2013.12.23 18:03:05.704 0: HMLAN_Send:  hmusb S:S2068F509 stat:  00 t:00000000 d:01 r:2068F509 m:07 A001 FECAFE 200D90 00050000000000
2013.12.23 18:03:06.343 0: HMLAN_Parse: hmusb R:R2068F509 stat:0008 t:00000000 d:FF r:7FFF     m:07 A001 FECAFE 200D90 00050000000000
2013.12.23 18:03:06.345 0: HMLAN_Parse: hmusb no ACK from 200D90
2013.12.23 18:03:08.462 0: HMLAN_Send:  hmusb I:K
2013.12.23 18:03:08.488 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:0104390A IDcnt:0005
2013.12.23 18:03:33.475 0: HMLAN_Send:  hmusb I:K
2013.12.23 18:03:33.518 0: HMLAN_Parse: hmusb V:03C3 sNo:JEQ0701016 d:1EBEBB O:FECAFE t:01049AC5 IDcnt:0005

und nun der andere :

Code Auswählen

fhem> 2013-12-23 18:06:09.536 CUL_HM CUL_HM_HM_SEC_SC_2253BC Activity: alive



2013.12.23 18:06:09.264 0: HMLAN_Parse: hmusb R:E2253BC   stat:0000 t:0106FB34 d:FF r:FFCF     m:35 8400 2253BC 000000 21002F4B45513033363734313680810101
2013.12.23 18:06:09.266 1: General wait 2253BC :0
2013.12.23 18:06:09.368 0: HMLAN_Send:  hmusb S:+2253BC,01,01,FE1F
2013.12.23 18:06:09.370 0: HMLAN_Send:  hmusb S:S206BC283 stat:  00 t:00000000 d:01 r:206BC283 m:0B A001 FECAFE 2253BC 00040000000000
2013.12.23 18:06:09.587 0: HMLAN_Parse: hmusb R:E2253BC   stat:0100 t:0106FC6F d:FF r:FFD0     m:0B A010 2253BC FECAFE 02020109010AFE0BCA0CFE100114060000
2013.12.23 18:06:09.599 1: General wait 2253BC :5
2013.12.23 18:06:09.969 0: HMLAN_Parse: hmusb R:R206BC283 stat:0041 t:0106FC74 d:01 r:FFD0     m:0B A010 2253BC FECAFE 02020109010AFE0BCA0CFE100114060000
2013.12.23 18:06:09.971 1: General wait 2253BC :372
2013.12.23 18:06:10.057 0: HMLAN_Send:  hmusb S:+2253BC,01,01,FE1F
2013.12.23 18:06:10.065 0: HMLAN_Send:  hmusb S:S206BC54B stat:  00 t:00000000 d:01 r:206BC54B m:0C A001 FECAFE 2253BC 01040000000001
2013.12.23 18:06:10.352 0: HMLAN_Parse: hmusb R:E2253BC   stat:0100 t:0106FF77 d:FF r:FFCD     m:0C A010 2253BC FECAFE 02080020602100226430060000
2013.12.23 18:06:10.354 1: General wait 2253BC :0
2013.12.23 18:06:10.737 0: HMLAN_Parse: hmusb R:R206BC54B stat:0041 t:0106FF7C d:01 r:FFCD     m:0C A010 2253BC FECAFE 02080020602100226430060000
2013.12.23 18:06:10.739 1: General wait 2253BC :364
2013.12.23 18:06:10.798 0: HMLAN_Send:  hmusb S:S206BC830 stat:  00 t:00000000 d:01 r:206BC830 m:0D A001 FECAFE 2253BC 0103
2013.12.23 18:06:11.152 0: HMLAN_Parse: hmusb R:E2253BC   stat:0100 t:0107027B d:FF r:FFCD     m:0D A010 2253BC FECAFE 0100000000
2013.12.23 18:06:11.154 1: General wait 2253BC :12
2013.12.23 18:06:11.507 0: HMLAN_Parse: hmusb R:R206BC830 stat:0041 t:01070280 d:01 r:FFCD     m:0D A010 2253BC FECAFE 0100000000
2013.12.23 18:06:11.520 1: General wait 2253BC :364

martinp876 · 23 Dezember 2013, 19:41:57

hej,

bei diesem Versuch ist hat der 200D90 klar verweigert.
Das timing war perfect, FHEM war am Ball (kein delay).
Trotzdem keine Antwort.
Wenn es nur einer ist kann man einen HW defekt nicht ausschliessen.
Der 2. funktioniert immer? Also sehr oft

manchmal klemmt auch die FW. noch 2-3 Versuche sind sicher angebracht.

Gruss Martin

juppzupp · 23 Dezember 2013, 21:41:26

Also der 2te und 3te, die klappen bisher ohne Auffälligkeiten. Wobei mittlerweile traue ich mich ja kaum noch was anzufassen

Wobei der zickerich ja auch ein paar Monate ohne aufzufallen in betrieb war. Hmmmmm.....

Heute abend komm ich nicht weiter, setze mich morgen nochmal dran.

Vielen vielen dank für die Hilfe Martin.