Winmatic - gelegentlich NACK und Nack

[dadoc]

Hallo zusammen,
eigentlich soll meine neue Winmatic um 04:45 die Balkontür schließen. Macht sie auch meistens, manchmal aber auch einfach nicht, und ich habe keinen Plan, weshalb.
Wenn es klappt, steht das im Winmatic-Log:

Code Auswählen Erweitern

2017-03-29_04:45:00 HM_3AE272 CMDs_pending
2017-03-29_04:45:00 HM_3AE272 aesCommToDev: pending
2017-03-29_04:45:00 HM_3AE272 aesKeyNbr: 00
2017-03-29_04:45:00 HM_3AE272 aesCommToDev: ok
2017-03-29_04:45:00 HM_3AE272 CMDs_done
2017-03-29_04:45:08 HM_3AE272 CMDs_done
Wenn es nicht klappt, steht das im Log:

Code Auswählen Erweitern

2017-03-30_04:45:00 HM_3AE272 CMDs_pending
2017-03-30_04:45:00 HM_3AE272 aesCommToDev: pending
2017-03-30_04:45:00 HM_3AE272 aesKeyNbr: 00
2017-03-30_04:45:00 HM_3AE272 aesCommToDev: ok
2017-03-30_04:45:00 HM_3AE272 CMDs_done_Errors:1
2017-03-30_04:45:00 HM_3AE272 NACK
2017-03-30_04:45:00 HM_3AE272 Nack
im fhem Log steht lapidar:

Code Auswählen Erweitern

2017.03.30 04:45:00 3: CUL_HM set Winmatic_SZ level 0 0 10
Habt Ihr irgendeinen Tipp, worauf sich die beiden NACK (einmal NACK, einmal Nack) beziehen können bzw. was kann bei einem HM-Gerät ein NACK auslösen? Kann das an zu großer Entfernung zum Sender liegen (hmusb_RSSI -51)? Oder wie kann ich das - dem WAF zuliebe - am Besten einkreisen? Ist ja leider nicht beliebig reproduzierbar.
Danke & Grüße
Martin

[dadoc]

Guten Morgen,
Heute noch eine neue Variante: Keine Fehler im Log, Tür wurde aber dennoch nicht geschlossen:

Code Auswählen Erweitern

2017-03-31_04:45:00 HM_3AE272 CMDs_pending
2017-03-31_04:45:00 HM_3AE272 aesCommToDev: pending
2017-03-31_04:45:00 HM_3AE272 aesKeyNbr: 00
2017-03-31_04:45:00 HM_3AE272 aesCommToDev: ok
2017-03-31_04:45:00 HM_3AE272 CMDs_done
2017-03-31_04:45:08 HM_3AE272 CMDs_done
Welchen Reim könnte man sich darauf machen?
Grüße
Martin

[frank]

wenn es grundsätzlich funktioniert, nur manchmal nicht, tippe ich auf timingprobleme. eventuell sogar ein cul am werk?

deine infos sind allerdings sehr sparsam.
welche io's? vccu? wie eingebunden? list's aller beteiligten komponenten posten. fhem aktuell?
sniffen des vorgangs, wie im wiki beschrieben.

[dadoc]

deine infos sind allerdings sehr sparsam.

Du hast völlig recht, sorry. Dachte bloß, dass es vielleicht etwas ganz Offensichtliches wäre.
Was ich im List heute sehe ist dass motorErr auf TiltError steht. Dazu konnte ich mir aber nichts ergooglen.
Die Details:
fhem 5.8

Code Auswählen Erweitern

fhem.pl 13840 2017-03-29 07:52:29Z rudolfkoenig
Definition der Winmatic

Code Auswählen Erweitern

define HM_3AE272 CUL_HM 3AE272
attr HM_3AE272 IODev hmusb
attr HM_3AE272 autoReadReg 4_reqStatus
attr HM_3AE272 expert 2_raw
attr HM_3AE272 firmware 1.6
attr HM_3AE272 model HM-SEC-WIN
attr HM_3AE272 msgRepeat 1
attr HM_3AE272 room CUL_HM,Schlafzimmer
attr HM_3AE272 serialNr MEQ046426
attr HM_3AE272 subType winMatic
attr HM_3AE272 webCmd getConfig:clear msgEvents
define FileLog_HM_3AE272 FileLog ./log/HM_3AE272-%Y.log HM_3AE272
attr FileLog_HM_3AE272 logtype text
attr FileLog_HM_3AE272 room CUL_HM
define Winmatic_SZ CUL_HM 3AE27201
attr Winmatic_SZ model HM-SEC-WIN
attr Winmatic_SZ peerIDs 00000000,
attr Winmatic_SZ room Schlafzimmer
define Winmatic_SZ_Akku CUL_HM 3AE27202
attr Winmatic_SZ_Akku model HM-SEC-WIN
attr Winmatic_SZ_Akku room Schlafzimmer

list Winmatic_SZ:

Code Auswählen Erweitern

:
Internals:
   DEF        3AE27201
   NAME       Winmatic_SZ
   NOTIFYDEV  global
   NR         1215
   NTFY_ORDER 50-Winmatic_SZ
   STATE      72.5
   TYPE       CUL_HM
   chanNo     01
   device     HM_3AE272
   Readings:
     2017-03-31 04:45:00   CommandAccepted yes
     2017-03-18 15:47:44   R-pullForce     50 %
     2017-03-18 15:47:44   R-pushForce     50 %
     2017-03-18 15:47:44   R-tiltMax       255
     2017-03-20 23:38:45   RegL_01.        16:01 1C:64 1D:64 1E:FF 00:00
     2017-03-31 04:45:08   direction       undefined
     2017-03-30 09:21:28   level           70
     2017-03-31 04:45:08   lock            unlocked
     2017-03-31 04:45:08   motorErr        TiltError
     2017-03-31 04:45:08   recentStateType info
     2017-03-31 04:45:08   state           72.5
   Helper:
     Expert:
       def        1
       det        0
       raw        1
       tpl        0
     Role:
       chn        1
     Tmpl:
Attributes:
   model      HM-SEC-WIN
   peerIDs    00000000,
   room       Schlafzimmer
HMLAN: list hmusb

Code Auswählen Erweitern

     
   Internals:
   DEF        127.0.0.1:1234
   DeviceName 127.0.0.1:1234
   FD         10
   IFmodel    USB
   NAME       hmusb
   NR         46
   NTFY_ORDER 50-hmusb
   PARTIAL
   RAWMSG     E38EB2E,0000,5589CDA5,FF,FFC0,EB861038EB2E0000000A78D50D0000
   RSSI       -64
   STATE      opened
   TYPE       HMLAN
   XmitOpen   1
   assignedIDsCnt 30
   hmusb_MSGCNT 4224
   hmusb_TIME 2017-03-31 10:16:01
   msgKeepAlive dlyMax:4.398 bufferMin:0
   msgLoadCurrent 2
   msgLoadHistoryAbs 5min steps: 2/2/3/3/3/3/2/3/3/2/2/2
   msgParseDly min:-12 max:4415 last:10 cnt:4164
   owner      424242
   uptime     016 398:38:17.239
   Readings:
     2017-03-30 22:56:11   D-HMIdAssigned  424242
     2017-03-30 22:56:11   D-HMIdOriginal  1EBD70
     2017-03-30 22:56:11   D-firmware      0.967
     2017-03-30 22:56:11   D-serialNr      JEQ0700605
     2017-03-30 22:56:11   Xmit-Events     ok:1 init:1 disconnected:1
     2017-03-30 22:56:11   cond            ok
     2017-03-31 10:16:04   loadLvl         low
     2017-02-01 20:25:45   prot_ERROR-Overload last
     2017-02-01 20:30:03   prot_Warning-HighLoad last
     2017-03-30 22:56:00   prot_disconnected last
     2017-03-30 22:56:00   prot_init       last
     2017-03-30 22:56:11   prot_ok         last
     2017-03-30 22:56:00   state           opened
   Helper:
     assIdCnt   30
     assIdRep   30
     info       03C7,JEQ0700605,1EBD70,424242
     setTime    45500
     Cnd:
       0          1
       253        1
       255        1
     Dly:
       cnt        4164
       lst        10
       max        4415
       min        -12
     Ids:
       107841:
         cfg        +107841,00,01,00
         name       RC19
       1269dd:
         cfg        +1269DD,00,01,00
         name       HM_1269DD
       24e2f3:
         cfg        +24E2F3,00,01,00
         name       Regensensor
       299a2f:
         cfg        +299A2F,00,01,00
         chn        01
         flg        0
         msg
         name       SD01
         to         1490907382.27712
       2f45f8:
         cfg        +2F45F8,00,01,00
         name       keyremote_1
       3037dc:
         cfg        +3037DC,00,01,00
         name       HM_3037DC
       303933:
         cfg        +303933,00,01,00
         name       HM_303933
       315940:
         cfg        +315940,00,01,00
         chn        02
         flg        0
         msg
         name       HT_KUE_RE
         to         1490930400.80847
       353dc1:
         cfg        +353DC1,00,01,00
         chn        02
         flg        0
         msg
         name       HM_353DC1
         to         1490939236.66189
       36716c:
         cfg        +36716C,00,01,00
         name       THERMOSTAT_Bad
       3679e4:
         cfg        +3679E4,00,01,00
         chn        02
         flg        0
         msg
         name       THERMOSTAT_Kueche
         to         1490941327.00176
       37f824:
         cfg        +37F824,00,01,00
         chn        02
         flg        0
         msg
         name       HT_KUE_LI
         to         1490930053.43883
       37f82c:
         cfg        +37F82C,00,01,00
         chn        02
         flg        0
         msg
         name       HT_AZ
         to         1490932603.99429
       389eda:
         cfg        +389EDA,00,01,00
         name       reedcontrol
       38e037:
         cfg        +38E037,00,01,00
         name       HT_WZ_LI
       38e057:
         cfg        +38E057,00,01,00
         name       HT_WC
       38e49b:
         cfg        +38E49B,00,01,00
         name       HM_38E49B
       38eb2e:
         cfg        +38EB2E,00,01,00
         name       HT_BAD_FEN
       38ec14:
         cfg        +38EC14,00,01,00
         chn        02
         flg        0
         msg
         name       HT_WZ_RE
         to         1490912557.12375
       38ee12:
         cfg        +38EE12,00,01,00
         name       HM_38EE12
       391c84:
         cfg        +391C84,00,01,00
         name       THERMOSTAT_WZ
       3ae272:
         cfg        +3AE272,00,01,00
         chn        81
         flg        0
         msg
         name       HM_3AE272
         to         1490928302.04297
       3ffee0:
         cfg        +3FFEE0,00,01,00
         chn        01
         flg        0
         msg
         name       HM_3FFEE0
         to         1490907373.58136
       3ffefe:
         cfg        +3FFEFE,00,01,00
         chn        02
         flg        0
         msg
         name       HM_3FFEFE
         to         1490939238.71789
       40bb46:
         cfg        +40BB46,00,01,00
         chn        02
         flg        0
         msg
         name       HM_40BB46
         to         1490939244.89128
       40bb9c:
         cfg        +40BB9C,00,01,00
         chn        02
         flg        0
         msg
         name       HM_40BB9C
         to         1490939245.27052
       40d63f:
         cfg        +40D63F,00,01,00
         chn        01
         flg        0
         msg
         name       LI.FLU.STRAHLER
         to         1490907381.17916
       43dca6:
         cfg        +43DCA6,00,01,00
         chn        02
         flg        0
         msg
         name       schaltaktor_portal
         to         1490946624.4677
       4a1d21:
         cfg        +4A1D21,00,01,00
         chn        01
         flg        0
         msg
         name       motion_portal
         to         1490946635.02339
       4b118e:
         cfg        +4B118E,00,01,00
         chn        03
         flg        0
         msg
         name       HM_4B118E
         to         1490907380.06534
     K:
       BufMin     0
       DlyMax     4.398
       Next       1490948189.43206
       Start      1490948164.43206
     Loadlvl:
       bl         40
       a:
         99
         90
         40
         0
       H:
         0          low
         40         batchLevel
         90         high
         99         suspended
     Log:
       all        0
       sys        0
       ids:
         ARRAY(0x1347960)
     Q:
       HMcndN     0
       answerPend 0
       hmLanQlen  1
       keepAliveRec 1
       keepAliveRpt 0
       loadLastMax 2
       loadNo     3
       scnt       4
       ald:
         2
         2
         3
         3
         3
         3
         2
         3
         3
         2
         2
         2
       apIDs:
     Ref:
       drft       -0.00024004800960192
       hmtL       1435097239
       kTs        0
       offL       1489513067201
       sysL       1490948164440
Attributes:
   hmId       424242
   hmKey      01:7a89cc18e82474af4f2720a0fe18527a
   hmLanQlen  1_min
   loadLevel  0:low,40:batchLevel,90:high,99:suspended

Sniffen
Habe mal aus der Ferne einen Schließen und einen Öffnen-Vorgang gesniffed, aber wahrscheinlich müsste man ja sniffen, wenn es gerade mal *nicht* funktiniert:

Code Auswählen Erweitern

2017.03.31 10:56:26.949 0: HMLAN_Parse: hmusb R:E36716C   stat:0000 t:55AECF78 d:FF r:FFB8     m:D5 8470 36716C 000000 00D430
2017.03.31 10:56:30.252 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:56:30.277 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55AEDC82 IDcnt:001E L:0 %
2017.03.31 10:56:32.197 0: HMLAN_Parse: hmusb R:E38EB2E   stat:0000 t:55AEE3F2 d:FF r:FFC0     m:FB 8610 38EB2E 000000 0A78D40D0000
2017.03.31 10:56:34.085 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55AEEB69 d:FF r:FFC7     m:26 8470 3679E4 000000 00D729
2017.03.31 10:56:44.769 0: HMLAN_Send:  hmusb S:S2394C93B stat:  00 t:00000000 d:01 r:2394C93B m:45 B011 424242 3AE272 81018CFFC8
2017.03.31 10:56:45.319 0: HMLAN_Parse: hmusb R:E3AE272   stat:0100 t:55AF1731 d:FF r:FFCD     m:45 A002 3AE272 424242 04E2FF79C8F3A100
2017.03.31 10:56:45.543 0: HMLAN_Parse: hmusb R:R2394C93B stat:0021 t:55AF1736 d:00 r:FFCD     m:45 8002 3AE272 424242 807CEAC6EC
2017.03.31 10:56:55.253 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:56:55.272 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55AF3E2A IDcnt:001E L:2 %
2017.03.31 10:57:05.482 0: HMLAN_Parse: hmusb R:E37F824   stat:0000 t:55AF6610 d:FF r:FFBE     m:CC 8610 37F824 000000 0AA8D70B0000
2017.03.31 10:57:06.601 0: HMLAN_Parse: hmusb R:E38E057   stat:0000 t:55AF6A5B d:FF r:FFC3     m:97 8610 38E057 000000 0A50CA0C0040
2017.03.31 10:57:20.255 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:57:20.267 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55AF9FD5 IDcnt:001E L:2 %
2017.03.31 10:57:20.811 0: HMLAN_Parse: hmusb R:E38EC14   stat:0000 t:55AFA1F0 d:FF r:FFCD     m:2F 8610 38EC14 000000 0A78E40A0000
2017.03.31 10:57:32.943 1: Perfmon: possible freeze starting at 10:57:31, delay is 1.943
2017.03.31 10:57:45.256 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:57:45.262 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B0017E IDcnt:001E L:2 %
2017.03.31 10:58:02.929 0: HMLAN_Send:  hmusb S:S2395FA8B stat:  00 t:00000000 d:01 r:2395FA8B m:46 B011 424242 3AE272 81010000C8
2017.03.31 10:58:03.472 0: HMLAN_Parse: hmusb R:E3AE272   stat:0100 t:55B04881 d:FF r:FFCD     m:46 A002 3AE272 424242 04179A9CB675A000
2017.03.31 10:58:03.728 0: HMLAN_Parse: hmusb R:R2395FA8B stat:0021 t:55B04886 d:00 r:FFCD     m:46 8002 3AE272 424242 80D2C0B427
2017.03.31 10:58:10.258 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:58:10.302 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B06327 IDcnt:001E L:3 %
2017.03.31 10:58:12.946 0: HMLAN_Parse: hmusb R:E38E037   stat:0000 t:55B06D8E d:FF r:FFD7     m:A8 8610 38E037 000000 0A78E40F0000
2017.03.31 10:58:13.234 0: HMLAN_Parse: hmusb R:E37F82C   stat:0000 t:55B06EB5 d:FF r:FFBD     m:65 8610 37F82C 000000 0A44CD090040
2017.03.31 10:58:19.443 0: HMLAN_Parse: hmusb R:E36716C   stat:0000 t:55B086ED d:FF r:FFB3     m:D6 865A 36716C 000000 78D430
2017.03.31 10:58:35.259 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:58:35.284 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B0C4D0 IDcnt:001E L:3 %
2017.03.31 10:58:37.173 0: HMLAN_Parse: hmusb R:E315940   stat:0000 t:55B0CC20 d:FF r:FFBF     m:52 8610 315940 000000 0AA8D70A0000
2017.03.31 10:58:37.684 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B0CE2B d:FF r:FFBB     m:03 865A 391C84 000000 78E429
2017.03.31 10:58:39.445 0: HMLAN_Parse: hmusb R:E36716C   stat:0000 t:55B0D50D d:FF r:FFB4     m:D6 8470 36716C 000000 00D430
2017.03.31 10:58:43.828 1: Perfmon: possible freeze starting at 10:58:42, delay is 1.828
2017.03.31 10:58:43.831 0: HMLAN_Parse: hmusb R:E38EE12   stat:0000 t:55B0E56F d:FF r:FFC6     m:8A 8610 38EE12 000000 0A78D40A0000
2017.03.31 10:58:57.687 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B11C4B d:FF r:FFBB     m:03 8470 391C84 000000 00E429
2017.03.31 10:59:00.260 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:59:00.279 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B12679 IDcnt:001E L:3 %
2017.03.31 10:59:05.016 0: HMLAN_Parse: hmusb R:E38E49B   stat:0000 t:55B138F7 d:FF r:FFCA     m:04 8610 38E49B 000000 0A24D00F0040
2017.03.31 10:59:05.336 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55B13A3C d:FF r:FFC7     m:27 865A 3679E4 000000 A8D729
2017.03.31 10:59:11.317 1: Perfmon: possible freeze starting at 10:59:09, delay is 2.317
2017.03.31 10:59:11.323 0: HMLAN_Parse: hmusb R:E38EB2E   stat:0000 t:55B14A34 d:FF r:FFBF     m:FC 8610 38EB2E 000000 0A78D40D0000
2017.03.31 10:59:22.074 0: HMLAN_Parse: hmusb R:E38EC14   stat:0000 t:55B17B92 d:FF r:FFCD     m:30 8610 38EC14 000000 0A78E40A0000
2017.03.31 10:59:25.262 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:59:25.275 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B18823 IDcnt:001E L:3 %
2017.03.31 10:59:25.338 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55B1885C d:FF r:FFC6     m:27 8470 3679E4 000000 00D729
2017.03.31 10:59:28.347 1: Perfmon: possible freeze starting at 10:59:26, delay is 2.347
2017.03.31 10:59:47.005 0: HMLAN_Parse: hmusb R:E37F824   stat:0000 t:55B1DCEA d:FF r:FFBF     m:CD 8610 37F824 000000 0AA8D70B0000
2017.03.31 10:59:50.264 0: HMLAN_Send:  hmusb I:K
2017.03.31 10:59:50.302 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B1E9D0 IDcnt:001E L:3 %
2017.03.31 11:00:10.336 0: HMLAN_Parse: hmusb R:E38E057   stat:0000 t:55B23825 d:FF r:FFC3     m:98 8610 38E057 000000 0A50CA0C0040
2017.03.31 11:00:15.266 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:00:15.297 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B24B79 IDcnt:001E L:3 %
2017.03.31 11:00:32.995 0: HMLAN_Parse: hmusb R:E37F82C   stat:0000 t:55B2909E d:FF r:FFBD     m:66 8610 37F82C 000000 0A44CD090040
2017.03.31 11:00:36.195 0: HMLAN_Parse: hmusb R:E38E037   stat:0000 t:55B29D24 d:FF r:FFD7     m:A9 8610 38E037 000000 0A78E40F0000
2017.03.31 11:00:38.404 0: HMLAN_Parse: hmusb R:E315940   stat:0000 t:55B2A5C4 d:FF r:FFBF     m:53 8610 315940 000000 0AA8D70A0000
2017.03.31 11:00:40.267 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:00:40.292 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B2AD22 IDcnt:001E L:3 %
2017.03.31 11:01:05.269 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:01:05.287 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B30ECC IDcnt:001E L:3 %
2017.03.31 11:01:20.485 1: Perfmon: possible freeze starting at 11:01:19, delay is 1.485
2017.03.31 11:01:21.448 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B34DD5 d:FF r:FFBB     m:04 865A 391C84 000000 78E529
2017.03.31 11:01:30.271 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:01:30.282 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B37078 IDcnt:001E L:3 %
2017.03.31 11:01:31.434 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B374E7 d:FF r:FFBB     m:5D 8410 391C84 000000 0B78E50C00
2017.03.31 11:01:31.626 0: HMLAN_Parse: hmusb R:E38EE12   stat:0000 t:55B375B4 d:FF r:FFC6     m:8B 8610 38EE12 000000 0A78D40A0000
2017.03.31 11:01:32.202 0: HMLAN_Parse: hmusb R:E38EB2E   stat:0000 t:55B377D7 d:FF r:FFC0     m:FD 8610 38EB2E 000000 0A78D40D0000
2017.03.31 11:01:41.420 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B39BF5 d:FF r:FFBB     m:04 8470 391C84 000000 00E529
2017.03.31 11:01:42.347 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55B39F89 d:FF r:FFC7     m:28 865A 3679E4 000000 A8D729
2017.03.31 11:01:47.276 0: HMLAN_Parse: hmusb R:E38E49B   stat:0000 t:55B3B2C5 d:FF r:FFCA     m:05 8610 38E49B 000000 0A24D00F0040
2017.03.31 11:01:55.273 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:01:55.277 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B3D221 IDcnt:001E L:3 %
2017.03.31 11:02:02.350 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55B3EDA9 d:FF r:FFC7     m:28 8470 3679E4 000000 00D729
2017.03.31 11:02:12.821 0: HMLAN_Parse: hmusb R:E38EC14   stat:0000 t:55B41693 d:FF r:FFCD     m:31 8610 38EC14 000000 0A78E50A0000
2017.03.31 11:02:13.999 0: HMLAN_Parse: hmusb R:E37F824   stat:0000 t:55B41B24 d:FF r:FFBE     m:CE 8610 37F824 000000 0AA8D70B0000
2017.03.31 11:02:20.275 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:02:20.304 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B433CA IDcnt:001E L:3 %
2017.03.31 11:02:38.482 0: HMLAN_Parse: hmusb R:E37F82C   stat:0000 t:55B47ADB d:FF r:FFBD     m:67 8610 37F82C 000000 0A44CD090040
2017.03.31 11:02:44.947 0: HMLAN_Parse: hmusb R:E38E037   stat:0000 t:55B49414 d:FF r:FFD7     m:AA 8610 38E037 000000 0A78E50F0000
2017.03.31 11:02:45.276 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:02:45.299 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B49574 IDcnt:001E L:3 %
2017.03.31 11:02:59.605 0: HMLAN_Parse: hmusb R:E38E057   stat:0000 t:55B4CD4B d:FF r:FFC3     m:99 8610 38E057 000000 0A50CA0C0040
2017.03.31 11:03:10.277 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:03:10.294 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B4F71D IDcnt:001E L:3 %
2017.03.31 11:03:29.144 0: HMLAN_Parse: hmusb R:E315940   stat:0000 t:55B540C2 d:FF r:FFBF     m:54 8610 315940 000000 0AA8D70A0000
2017.03.31 11:03:35.279 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:03:35.289 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B558C6 IDcnt:001E L:3 %
2017.03.31 11:03:40 0: HMLAN_Parse: hmusb R:E38EB2E   stat:0000 t:55B56DCC d:FF r:FFBF     m:FE 8610 38EB2E 000000 0A78D40D0000
2017.03.31 11:03:50 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B594D6 d:FF r:FFBB     m:05 865A 391C84 000000 78E529
2017.03.31 11:04:00 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:04:00 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B5BA70 IDcnt:001E L:3 %
2017.03.31 11:04:04 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55B5CC2E d:FF r:FFC7     m:29 865A 3679E4 000000 A8D729
2017.03.31 11:04:05 0: HMLAN_Parse: hmusb R:E38EE12   stat:0000 t:55B5CD4F d:FF r:FFC7     m:8C 8610 38EE12 000000 0A78D40A0000
2017.03.31 11:04:09 0: HMLAN_Parse: hmusb R:E36716C   stat:0000 t:55B5DD28 d:FF r:FFB8     m:D8 865A 36716C 000000 78D430
2017.03.31 11:04:10 0: HMLAN_Parse: hmusb R:E391C84   stat:0000 t:55B5E2F6 d:FF r:FFBB     m:05 8470 391C84 000000 00E529
2017.03.31 11:04:15 0: HMLAN_Parse: hmusb R:E38E49B   stat:0000 t:55B5F3E9 d:FF r:FFCA     m:06 8610 38E49B 000000 0A24D00F0040
2017.03.31 11:04:24 0: HMLAN_Parse: hmusb R:E3679E4   stat:0000 t:55B61A4E d:FF r:FFC7     m:29 8470 3679E4 000000 00D729
2017.03.31 11:04:25 0: HMLAN_Send:  hmusb I:K
2017.03.31 11:04:25 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:55B61C18 IDcnt:001E L:3 %
2017.03.31 11:04:26 0: HMLAN_Parse: hmusb R:E37F824   stat:0000 t:55B620B7 d:FF r:FFBF     m:CF 8610 37F824 000000 0AA8D70B0000
2017.03.31 11:04:29 0: HMLAN_Parse: hmusb R:E36716C   stat:0000 t:55B62B48 d:FF r:FFB5     m:D8 8470 36716C 000000 00D430


[automatisierer]

besser im List vom HMUSB den hmKey unkenntlich machen...

der Winmatic nutzt noch den Original hmKey und nicht den von dir am hmUSB eingesetellten. daran zu erkennen:

Code Auswählen Erweitern

2017-03-31_04:45:00 HM_3AE272 aesKeyNbr: 00
da müsste '02' stehen

Dann ist da noch ein:

Code Auswählen Erweitern

2017-03-31 04:45:08   motorErr        TiltError
im list vom Winmatic. Das dürfte die erklärung dafür sein, dass das Fenster trotz richtiger Kommunikation nicht geschlossen war. Also ein 'Hardwarefehler' - Fenster schwergängig - Einbrecher hatte grad den Arm durchs gekippte Fenster gesteckt... - oder oder oder

[frank]

das list vom winmatic hauptdevice wäre noch interessant.

beim sniffen reicht auch erst einmal statt logIds=sys,all nur das device einzutragen, um das log zu "schonen" bis der fehler mal wieder auftaucht. die millisekunden aber wieder einschalten.

bei einem nack wird eigentlich immer etwas abgelehnt.

der akku ist noch tip-top?

[dadoc]

besser im List vom HMUSB den hmKey unkenntlich machen...

Ist schon ein anderer als der tatsächliche

der Winmatic nutzt noch den Original hmKey und nicht den von dir am hmUSB eingesetellten. daran zu erkennen:

Code Auswählen Erweitern

2017-03-31_04:45:00 HM_3AE272 aesKeyNbr: 00
da müsste '02' stehen

Aber dann dürfte die Winmatic doch eigentlich nie funktionieren bzw. funktioniert haben, was sie aber tat/tut, oder? Im Log wird sie immer nur mit dem 00er key angesprochen, und das wir d ja auch immer mit aesCommToDev: ok quittiert, oder hat das nichts zu sagen?

Dann ist da noch ein:

Code Auswählen Erweitern

2017-03-31 04:45:08   motorErr        TiltError
im list vom Winmatic. Das dürfte die erklärung dafür sein, dass das Fenster trotz richtiger Kommunikation nicht geschlossen war. Also ein 'Hardwarefehler' - Fenster schwergängig - Einbrecher hatte grad den Arm durchs gekippte Fenster gesteckt... - oder oder oder

Jo, das schrieb ich ja eingangs. Konnte allerdings keine Infow zu TiltError finden und auch keinen mechanischen Hinderungsgrund für das Schließen...

Beim Öffnen/Schließen vorhin gab es BTW wieder die NACKs:

Code Auswählen Erweitern

2017-03-31_10:56:44 HM_3AE272 CMDs_pending
2017-03-31_10:56:45 HM_3AE272 aesCommToDev: pending
2017-03-31_10:56:45 HM_3AE272 aesKeyNbr: 00
2017-03-31_10:56:45 HM_3AE272 aesCommToDev: ok
2017-03-31_10:56:45 HM_3AE272 CMDs_done_Errors:1
2017-03-31_10:56:45 HM_3AE272 NACK
2017-03-31_10:56:45 HM_3AE272 Nack
2017-03-31_10:58:02 HM_3AE272 CMDs_pending
2017-03-31_10:58:03 HM_3AE272 aesCommToDev: pending
2017-03-31_10:58:03 HM_3AE272 aesKeyNbr: 00
2017-03-31_10:58:03 HM_3AE272 aesCommToDev: ok
2017-03-31_10:58:03 HM_3AE272 CMDs_done_Errors:1
2017-03-31_10:58:03 HM_3AE272 NACK
2017-03-31_10:58:03 HM_3AE272 Nack
Grüße
Martin

[dadoc]

das list vom winmatic hauptdevice wäre noch interessant.

Code Auswählen Erweitern

Internals:
   DEF        3AE272
   IODev      hmusb
   LASTInputDev hmusb
   MSGCNT     12
   NAME       HM_3AE272
   NOTIFYDEV  global
   NR         1213
   NTFY_ORDER 50-HM_3AE272
   STATE      Nack
   TYPE       CUL_HM
   channel_01 Winmatic_SZ
   channel_02 Winmatic_SZ_Akku
   hmusb_MSGCNT 12
   hmusb_RAWMSG R2395FA8B,0021,55B04886,00,FFCD,4680023AE27242424280D2C0B427
   hmusb_RSSI -51
   hmusb_TIME 2017-03-31 10:58:03
   lastMsg    No:46 - t:02 s:3AE272 d:424242 80D2C0B427
   protCmdDel 2
   protEvt_AESCom-ok 3 last_at:2017-03-31 10:58:03
   protLastRcv 2017-03-31 10:58:03
   protNack   2 last_at:2017-03-31 10:58:03
   protSnd    6 last_at:2017-03-31 10:58:02
   protState  CMDs_done_Errors:1
   rssi_at_hmusb lst:-51 avg:-51.5 cnt:6 min:-52 max:-51
   rssi_hmusb max:-53 min:-53 avg:-53 cnt:1 lst:-53
   Readings:
     2017-03-31 10:58:03   CommandAccepted no
     2017-03-18 15:47:39   D-firmware      1.6
     2017-03-18 15:47:39   D-serialNr      MEQ0464267
     2017-03-20 23:36:09   PairedTo        0x424242
     2017-03-18 15:47:44   R-pairCentral   0x424242
     2017-03-20 23:36:09   RegL_00.        02:01 03:19 0A:42 0B:42 0C:42 00:00
     2017-03-31 10:58:03   aesCommToDev    ok
     2017-03-31 10:58:03   aesKeyNbr       00
     2017-03-19 05:52:46   powerOn         2017-03-19 05:52:46
     2017-03-31 10:58:03   state           Nack
   Helper:
     HM_CMDNR   70
     cSnd       114242423AE27281018CFFC8,114242423AE27281010000C8
     mId        0028
     rxType     2
     supp_Pair_Rep 0
     Expert:
       def        1
       det        0
       raw        1
       tpl        0
     Io:
       newChn     +3AE272,00,01,00
       nextSend   1490950683.53793
       prefIO
       rxt        0
       vccu
       p:
         3AE272
         00
         01
         00
     Mrssi:
       mNo        46
       Io:
         hmusb      -49
     Prt:
       bErr       0
       sProc      0
       Rspwait:
     Q:
       qReqConf
       qReqStat
     Role:
       dev        1
       prs        1
     Rssi:
       At_hmusb:
         avg        -51.5
         cnt        6
         lst        -51
         max        -51
         min        -52
       Hmusb:
         avg        -53
         cnt        1
         lst        -53
         max        -53
         min        -53
     Tmpl:
Attributes:
   IODev      hmusb
   autoReadReg 4_reqStatus
   expert     2_raw
   firmware   1.6
   model      HM-SEC-WIN
   msgRepeat  1
   room       CUL_HM,Schlafzimmer
   serialNr   MEQ0464267
   subType    winMatic
   webCmd     getConfig:clear msgEvents

der akku ist noch tip-top?

Ja, das Teil ist nagelneu, der Akku aktuell bei 75%

[frank]

Code Auswählen Erweitern

2017.03.31 10:56:44.769 0: HMLAN_Send:  hmusb S:S2394C93B stat:  00 t:00000000 d:01 r:2394C93B m:45 B011 424242 3AE272 81018CFFC8
2017.03.31 10:56:45.319 0: HMLAN_Parse: hmusb R:E3AE272   stat:0100 t:55AF1731 d:FF r:FFCD     m:45 A002 3AE272 424242 04E2FF79C8F3A100
2017.03.31 10:56:45.543 0: HMLAN_Parse: hmusb R:R2394C93B stat:0021 t:55AF1736 d:00 r:FFCD     m:45 8002 3AE272 424242 807CEAC6EC

---

2017.03.31 10:58:02.929 0: HMLAN_Send:  hmusb S:S2395FA8B stat:  00 t:00000000 d:01 r:2395FA8B m:46 B011 424242 3AE272 81010000C8
2017.03.31 10:58:03.472 0: HMLAN_Parse: hmusb R:E3AE272   stat:0100 t:55B04881 d:FF r:FFCD     m:46 A002 3AE272 424242 04179A9CB675A000
2017.03.31 10:58:03.728 0: HMLAN_Parse: hmusb R:R2395FA8B stat:0021 t:55B04886 d:00 r:FFCD     m:46 8002 3AE272 424242 80D2C0B427


das sind die 2 sequenzen, die von der winmatic jeweils mit nack beantwortet werden.
hier fehlt jeweils als dritte message die signing response vom hmusb zum device. siehe zb hier https://git.zerfleddert.de/hmcfgusb/AES/
das könnte allerdings normal sein, falls der hmusb die antwort selber generiert und fhem davon nichts mitteilt, wie auch bei ack ohne aes. mit einem sniffenden 2. io würde man es ggf sehen können. da aesCommToDev=ok gemeldet wird, würde ich erstmal meinen, dass die kommunikation funktioniert. dann bliebe die frage, warum die aktion abgelehnt wird.

vielleicht liegt es an dem motorerror, der vorher aufgetreten ist.

[dadoc]

Vielen Dank Frank,

da aesCommToDev=ok gemeldet wird, würde ich erstmal meinen, dass die kommunikation funktioniert. dann bliebe die frage, warum die aktion abgelehnt wird.

vielleicht liegt es an dem motorerror, der vorher aufgetreten ist.

Über den Tilterror finde ich leider nichts an Info, und standardmäßig erscheint er ja nicht im Winmatic-Log (meine mich zu erinnern, dass man das aber von Hand konfigurieren kann...). Aber was Du sagst, scheint sehr plausibel. Es handelt sich um eine Art Balkontür, ziemlich groß und nicht eben leicht, aber neu und leichtgängig. Die Zugkraft der Winmatic habe ich auf 50% stehen, und bei manuellem Auslösen läuft das auch ganz smooth durch beim Schließen. Wenn ich mir jetzt aber vorstelle, dass es beim frühmorgentlichen Schließprozess vielleicht mal eine Windlast auf den immerhin fast 3m2 gibt, so kann das natürlich alles in Frage stellen.
Heute Nacht hat alles bestens funktioniert. Werde jetzt mal die Zugkraft erhöhen und weiter beobachten.
Schönes Wochenende & nochmals vielen Dank für die Hilfe
Martin

[dadoc]

Bei der Einstellung der Parameter am Device habe ich im Kleingedruckten gelesen:

Wird ein mechanischer Fehler (Verklemmen oder ähnliches) detektiert ertönt ein-malig ein Piepton. Über angelernte Bedienelemente ist dann kein Bedienen mehr möglich (Fernbedienungen und Taster signalisieren dies durch eine Fehlermeldung: ,,Befehl konnte nicht ausgeführt werden.") Sie können diesen Zustand durch einmaliges Bedienen direkt an der WinMatic zu-rücksetzen.

D.h. für mich, dass das Teil nach einem Fehler auch keine Befehle von fhem mehr annimmt, bevor man sich nicht physikalisch vor Ort begibt und die Lage checkt. Das dürfte zumindest die NACKs nach einem Motorerror (von dem ich allerdings nach wie vor nicht weiß, ober er wirklich vor jedem Nack aufgetreten ist) erklären.

BTW und etwas OT: Die Konfiguration der wenigen Winmatic-Einstellungen am Device ist ja wirklich ein Horror, da man lt. Handbuch jedesmal den kompletten Prozess (inkl. Ausklinken der Zahnstange und Ausbau des Vierkants!) durchlaufen muss, auch wenn man nur z.B die Zugkraft eine Stufe höher setzen will.
Könnte man das nicht via fhem konfigurieren?
Grüße
Martin

[Per]

wenn man nur z.B die Zugkraft eine Stufe höher setzen will.
Könnte man das nicht via fhem konfigurieren?

Geht das inzwischen?

[dadoc]

Nicht soweit ich das sehen könnte.
Bei mir ist das o.g. Problem seit dem Hochsetzen der Zugkraft BTW nie mehr aufgetreten, so dass es wirklich daran gelegen haben dürfte.