HMUARTLGW myHMLGW: Can't send ACK not originating from my hmId (firmware bug), p

maxritti · 07 Oktober 2020, 07:09:18

Hallo zusammen,

nach langer Zeit habe ich mal wieder einen neuen HM Schaltaktor HM-LC-SW1-PL-DN-R1 in mein FHEM integriert.
Klappt auch alles soweit. Habe den mit der VCCU angelernt und schaltet einwandfrei.

Nur immer, wenn ich den Aktor schalte, kommt diese Meldung im Log. Bzw. die kommt eigentlich, bevor im Log steht, dass der Aktor geschaltet wird.

Code Auswählen

2020.10.07 06:09:45 0: HMUARTLGW myHMLGW: Can't send ACK not originating from my hmId (firmware bug), please use a VCCU virtual device!
2020.10.07 06:09:45 3: CUL_HM set OG_el_SD_Bett toggle noArg

Hat dazu jemand eine Idee?

Im Einsatz bei mir ist diese CUL_HM.pm

Code Auswählen

10_CUL_HM.pm 22806 2020-09-20 18:12:13Z martinp876

Sollte ja ziemlich aktuell sein und dies hier finde ich da auch nicht wirklich drin.

https://forum.fhem.de/index.php?topic=79145.0

frank · 07 Oktober 2020, 09:18:04

zeig mal je ein list von device, vccu und hmuart.

maxritti · 07 Oktober 2020, 09:59:46

Hier das Device:

Code Auswählen

Internals:
   DEF        70CC6F
   FUUID      5f74c4eb-f33f-7b3d-a803-88d2371faf494dc8
   IODev      myHMLGW_Schlafzimmer
   LASTInputDev myHMLGW
   MSGCNT     60
   NAME       OG_el_SD_Bett
   NOTIFYDEV  global
   NR         256
   NTFY_ORDER 50-OG_el_SD_Bett
   STATE      off
   TYPE       CUL_HM
   chanNo     01
   lastMsg    No:5D - t:02 s:70CC6F d:9A234E 0101000037
   myHMLGW_MSGCNT 28
   myHMLGW_RAWMSG 050000475D800270CC6F9A234E0101000037
   myHMLGW_RSSI -71
   myHMLGW_Schlafzimmer_MSGCNT 32
   myHMLGW_Schlafzimmer_RAWMSG 040300375D800270CC6F9A234E0101000037
   myHMLGW_Schlafzimmer_RSSI -55
   myHMLGW_Schlafzimmer_TIME 2020-10-07 06:10:19
   myHMLGW_TIME 2020-10-07 06:10:19
   protLastRcv 2020-10-07 06:10:19
   protRcv    32 last_at:2020-10-07 06:10:19
   protSnd    32 last_at:2020-10-07 06:10:18
   protState  CMDs_done
   rssi_at_myHMLGW cnt:28 min:-90 max:-62 avg:-72.17 lst:-71 
   rssi_at_myHMLGW_Schlafzimmer cnt:32 min:-76 max:-46 avg:-55.12 lst:-55 
   rssi_myHMLGW_Schlafzimmer cnt:28 min:-73 max:-51 avg:-57.78 lst:-55 
   READINGS:
     2020-10-07 06:10:19   CommandAccepted yes
     2020-09-30 19:48:32   D-firmware      2.6
     2020-09-30 19:48:32   D-serialNr      REQ0108290
     2020-10-01 15:38:08   PairedTo        0x9A234E
     2020-10-01 15:38:08   RegL_00.        00:00 02:01 0A:9A 0B:23 0C:4E 15:FF 18:00
     2020-10-01 15:38:09   RegL_01.        00:00 08:00 30:06 56:00 57:24 93:5F 94:B3
     2020-10-01 07:10:21   aesCommToDev    ok
     2020-10-01 07:10:21   aesKeyNbr       00
     2020-10-01 15:38:08   cfgState        updating
     2020-10-07 06:10:19   commState       CMDs_done
     2020-10-07 06:10:19   deviceMsg       off (to myVCCU)
     2020-10-07 06:10:19   level           0
     2020-10-07 06:10:19   pct             0
     2020-10-01 15:38:07   powerOn         2020-10-01 15:38:07
     2020-10-07 06:10:19   recentStateType ack
     2020-10-07 06:10:19   state           off
     2020-10-07 06:10:19   timedOn         off
   helper:
     HM_CMDNR   93
     cSnd       119A234E70CC6F0201C80000,119A234E70CC6F0201000000
     dlvlCmd    ++A0119A234E70CC6F0201000000
     mId        0002
     peerFriend peerSens,peerVirt
     peerOpt    3:switch
     regLst     0,1,3p
     rxType     1
     supp_Pair_Rep 0
     ack:
     cmds:
       TmplKey    :no:1601627431.23265
       TmplTs     1601627431.23265
       cmdKey     1:1:0::OG_el_SD_Bett:0002:01:
       cmdLst:
         assignHmKey noArg
         clear      [(readings|trigger|register|oldRegs|rssi|msgEvents|{msgErrors}|attack|all)]
         deviceRename -newName-
         fwUpdate   -filename- [-bootTime-]
         getConfig  noArg
         getDevInfo noArg
         getRegRaw  (List0|List1|List2|List3|List4|List5|List6) [-peerChn-]
         getVersion noArg
         inhibit    [(on|{off})]
         off        noArg
         on         noArg
         on-for-timer -ontime-
         on-till    -time-
         pair       noArg
         peerBulk   -peer1,peer2,...- [({set}|unset)]
         peerIODev  [IO] -btn- [({set}|unset)] 'not for future use'
         peerSmart  -peerOpt-
         press      [(long|{short})] [(-peer-|{self01})] [(-repCount-|{0})] [(-repDelay-|{0.25})]
         raw        -data- [...]
         regBulk    -list-.-peerChn- -addr1:data1- -addr2:data2-...
         regSet     [(prep|{exec})] -regName- -value- [-peerChn-]
         reset      noArg
         sign       [(on|{off})]
         statusRequest noArg
         toggle     noArg
         tplDel     -tplDel-
         unpair     noArg
       lst:
         condition  slider,0,1,255
         peer       
         peerOpt    DG_hz_TK_Dachfenster,DG_wz_TK_Dachfenster,DG_xx_RS_Markise_Rain,EG_fl_TK_Haustuer,EG_ku_TK_Strasse,EG_wc_BM_Motion,EG_wz_Licht_1,EG_wz_Licht_2,EG_wz_TK_Carport,EG_wz_TK_Terrasse,HMRemote_AAnlage_On_Off,HMRemote_C_armExt,HMRemote_C_armIn,HMRemote_C_disarm,HMRemote_C_light,HMRemote_Carport,HMRemote_Kueche_WW,HMRemote_LichtSofa,HMRemote_LichtTV,HMRemote_Markise,HMRemote_RollosAll,HMRemote_RollosMarkiseStop,HMRemote_StrasseLinks,HMRemote_StrasseRechts,HMRemote_TerrasseLinks,HMRemote_TerrasseRechts,OG_bz_BM_Motion,OG_elt_TK_Strasse,OG_ki1_TK_Garten,OG_ki2_SD_Kyo_SenF,OG_ki2_SD_Kyo_SenI,OG_ki2_SD_Kyo_SenPwr,OG_ki2_SD_Kyo_SenU,OG_szk_TK_SchlafenTim,myVCCU_Btn1,va_Btn1,va_Btn10,va_Btn11,va_Btn12,va_Btn13,va_Btn14,va_Btn15,va_Btn16,va_Btn2,va_Btn3,va_Btn4,va_Btn5,va_Btn6,va_Btn7,va_Btn8,va_Btn9
         tplDel     
       rtrvLst:
         cmdList    [({short}|long)]
         deviceInfo [({short}|long)]
         param      -param-
         reg        -addr- -list- [-peerChn-]
         regList    noArg
         regTable   noArg
         regVal     -addr- -list- [-peerChn-]
         saveConfig [-filename-]
         tplInfo    noArg
     expert:
       def        0
       det        0
       raw        1
       tpl        0
     io:
       newChn     +70CC6F,00,00,00
       nextSend   1602043819.2597
       rxt        0
       vccu       myVCCU
       p:
         70CC6F
         00
         00
         00
       prefIO:
         myHMLGW_Schlafzimmer
     mRssi:
       mNo        5D
       io:
         myHMLGW:
           -71
           -71
         myHMLGW_Schlafzimmer:
           -49
           -49
     prt:
       bErr       0
       sProc      0
     q:
       qReqConf   
       qReqStat   
     role:
       chn        1
       dev        1
       prs        1
     rssi:
       at_myHMLGW:
         avg        -72.1785714285714
         cnt        28
         lst        -71
         max        -62
         min        -90
       at_myHMLGW_Schlafzimmer:
         avg        -55.125
         cnt        32
         lst        -55
         max        -46
         min        -76
       myHMLGW_Schlafzimmer:
         avg        -57.7857142857143
         cnt        28
         lst        -55
         max        -51
         min        -73
     tmpl:
Attributes:
   IODev      myHMLGW_Schlafzimmer
   IOgrp      myVCCU:myHMLGW_Schlafzimmer
   alias      Licht Eltern
   autoReadReg 4_reqStatus
   devStateIcon off:black_FS20.off on:black_FS20.on
   expert     rawReg
   firmware   2.6
   group      Switches
   model      HM-LC-SW1-PL-DN-R1
   peerIDs    00000000,
   room       Schalter,Tablet
   serialNr   REQ0108290
   sortby     05
   subType    switch
   webCmd     :

Die VCCU

Code Auswählen

Internals:
   DEF        9A234E
   FUUID      5c549a89-f33f-b047-2598-4e99a49f16aaae8e
   IODev      myHMLGW
   LASTInputDev myHMLGW_Schlafzimmer
   MSGCNT     3550
   NAME       myVCCU
   NOTIFYDEV  global
   NR         25
   NTFY_ORDER 50-myVCCU
   STATE      myHMLGW:ok,myHMLGW_Schlafzimmer:ok
   TYPE       CUL_HM
   assignedIOs myHMLGW,myHMLGW_Schlafzimmer
   channel_01 myVCCU_Btn1
   lastMsg    No:43 - t:02 s:9A234E d:E55919 00
   myHMLGW_MSGCNT 60
   myHMLGW_RAWMSG 0500003C5DA0119A234E70CC6F0201000000
   myHMLGW_RSSI -60
   myHMLGW_Schlafzimmer_MSGCNT 3490
   myHMLGW_Schlafzimmer_RAWMSG 0500003F4380029A234EE5591900
   myHMLGW_Schlafzimmer_RSSI -63
   myHMLGW_Schlafzimmer_TIME 2020-10-07 09:55:23
   myHMLGW_TIME 2020-10-07 06:10:19
   protLastRcv 2020-10-07 09:55:23
   protRcv    3435 last_at:2020-10-07 09:55:23
   protRcvB   45 last_at:2020-10-07 09:42:37
   rssi_at_myHMLGW cnt:60 min:-69 max:-54 avg:-60.63 lst:-60 
   rssi_at_myHMLGW_Schlafzimmer cnt:3467 min:-93 max:-51 avg:-59.14 lst:-63 
   READINGS:
     2020-10-07 09:55:23   CommandAccepted yes
     2020-10-02 22:02:50   IOopen          2
     2020-10-01 07:10:21   aesReqTo        OG_el_SD_Bett
     2020-09-30 18:25:50   commState       CMDs_done
     2020-10-02 22:02:50   state           myHMLGW:ok,myHMLGW_Schlafzimmer:ok
     2020-09-30 18:18:03   unknown_70CC6F  received
     2020-05-23 11:23:20   unknown_BAAC49  received
     2020-10-02 21:57:41   unknown_F10000  received
   helper:
     HM_CMDNR   67
     PONtest    1
     alarmNo    01
     mId        FFF0
     peerFriend peerSens,peerAct
     peerOpt    -:virtual
     regLst     
     rxType     1
     supp_Pair_Rep 0
     ack:
     cmds:
       TmplKey    :no:1601627431.27496
       TmplTs     1601627431.27496
       cmdKey     0:1:1::myVCCU::01:
       cmdLst:
         assignHmKey noArg
         assignIO   -IO- [({set}|unset)]
         clear      [(readings|rssi|msgErrors|{msgErrors}|unknownDev)]
         defIgnUnknown noArg
         deviceRename -newName-
         fwUpdate   -filename- [-bootTime-]
         getDevInfo noArg
         hmPairForSec [-sec-]
         hmPairSerial -serial-
         peerSmart  -peerOpt-
         raw        -data- [...]
         reset      noArg
         unpair     noArg
         update     noArg
         virtual    [(1..50;1|{1})]
       lst:
         condition  slider,0,1,255
         peer       
         peerOpt    DG_hz_SD_Zirkpumpe,DG_hz_TK_Dachfenster,DG_wz_TK_Dachfenster,DG_xx_RS_Markise_Rain,EG_fl_SD_Licht,EG_fl_TK_Haustuer,EG_ku_RO_StrasseLinks,EG_ku_RO_StrasseRechts,EG_ku_TK_Strasse,EG_wc_BM_Motion,EG_wz_4K_Switch_1,EG_wz_4K_Switch_2,EG_wz_4K_Switch_3,EG_wz_4K_Switch_4,EG_wz_Licht_1,EG_wz_Licht_2,EG_wz_RO_Carport,EG_wz_RO_Markise,EG_wz_RO_TerrasseLinks,EG_wz_RO_TerrasseRechts,EG_wz_SD_LichtSofa,EG_wz_SW_SteckdoseAussen,EG_wz_TK_Carport,EG_wz_TK_Terrasse,HMRemote_AAnlage_On_Off,HMRemote_C_armExt,HMRemote_C_armIn,HMRemote_C_disarm,HMRemote_C_light,HMRemote_Carport,HMRemote_Kueche_WW,HMRemote_LichtSofa,HMRemote_LichtTV,HMRemote_Markise,HMRemote_RollosAll,HMRemote_RollosMarkiseStop,HMRemote_StrasseLinks,HMRemote_StrasseRechts,HMRemote_TerrasseLinks,HMRemote_TerrasseRechts,HM_3174EE,OG_bz_BM_Motion,OG_el_SD_Bett,OG_el_SD_TV,OG_elt_RO_Strasse,OG_elt_TK_Strasse,OG_ki1_RO_Carport,OG_ki1_RO_Garten,OG_ki1_TK_Garten,OG_ki2_RO_Garten,OG_ki2_SD_Kyo_SW,OG_ki2_SD_Kyo_SenF,OG_ki2_SD_Kyo_SenI,OG_ki2_SD_Kyo_SenPwr,OG_ki2_SD_Kyo_SenU,OG_szk_TK_SchlafenTim
         tplDel     
       rtrvLst:
         cmdList    [({short}|long)]
         listDevice noArg
         param      -param-
     expert:
       def        1
       det        0
       raw        1
       tpl        0
     io:
       nextSend   1602057323.95609
       prefIO     
       vccu       
       ioList:
         myHMLGW
         myHMLGW_Schlafzimmer
     mRssi:
       mNo        43
       io:
         myHMLGW:
         myHMLGW_Schlafzimmer:
           -63
           -63
     prt:
       bErr       0
       sProc      0
       rspWait:
     q:
       qReqConf   
       qReqStat   
     role:
       dev        1
       vrt        1
     rssi:
       at_myHMLGW:
         avg        -60.6333333333333
         cnt        60
         lst        -60
         max        -54
         min        -69
       at_myHMLGW_Schlafzimmer:
         avg        -59.1485434092876
         cnt        3467
         lst        -63
         max        -51
         min        -93
     tmpl:
Attributes:
   IODev      myHMLGW
   IOList     myHMLGW,myHMLGW_Schlafzimmer
   expert     defReg,rawReg
   model      CCU-FHEM
   room       CUL_HM
   subType    virtual
   webCmd     virtual:update

und das LAN GW:

Code Auswählen

Internals:
   AssignedPeerCnt 24
   CNT        218
   Clients    :CUL_HM:
   DEF        192.168.178.2
   DEVCNT     218
   DevState   99
   DevType    LGW
   DeviceName 192.168.178.2:2000
   FD         4
   FUUID      5cbf333f-f33f-b047-4bea-37f9022d5dc749c9
   LastOpen   1601668968.02248
   NAME       myHMLGW
   NOTIFYDEV  global
   NR         27
   NTFY_ORDER 50-myHMLGW
   PARTIAL    
   RAWMSG     040204
   RSSI       -23
   STATE      opened
   TYPE       HMUARTLGW
   XmitOpen   1
   model      eQ3-HM-LGW
   msgLoadCurrent 2
   msgLoadHistory 0/0/0/1/0/0/0/0/0/0/0/0
   msgLoadHistoryAbs 2/2/2/2/1/1/1/1/1/1/1/1/1
   owner      9A234E
   owner_CCU  myVCCU
   Helper:
     CreditTimer 25949
     FW         66561
     Initialized 1
     SendCnt    268
     AckPending:
     LastSendLen:
       3
       3
     Log:
       IDs:
     PendingCMD:
     RoundTrip:
       Delay      0.00369095802307129
     loadLvl:
       lastHistory 1602057470.79351
   MatchList:
     1:CUL_HM   ^A......................
   Peers:
     20C7F0     +20C7F0,00,00,00
     22A266     +22A266,00,00,00
     239F8D     +239F8D,00,00,00
     23FB45     +23FB45,00,00,00
     24AAD3     +24AAD3,00,00,00
     290801     +290801,00,00,00
     290824     +290824,00,00,00
     290825     +290825,00,00,00
     29082D     +29082D,00,00,00
     2D1292     +2D1292,00,00,00
     3174EE     +3174EE,00,00,00
     34CB74     +34CB74,00,00,00
     34CBF5     +34CBF5,00,00,00
     495B93     +495B93,00,00,00
     52D383     +52D383,00,00,00
     52D38F     +52D38F,00,00,00
     52D39F     +52D39F,00,00,00
     52D3F3     +52D3F3,00,00,00
     52D492     +52D492,00,00,00
     53E197     +53E197,00,00,00
     643754     +643754,00,00,00
     653AC1     +653AC1,00,00,00
     65A163     +65A163,00,00,00
     E55919     +E55919,00,00,00
   READINGS:
     2020-10-02 22:02:50   D-HMIdAssigned  9A234E
     2020-10-02 22:02:50   D-HMIdOriginal  FFFFFF
     2020-10-02 22:02:48   D-LANfirmware   1.1.5
     2020-10-02 22:02:50   D-firmware      1.4.1
     2020-10-02 22:02:48   D-serialNr      PEQ1716493
     2020-10-02 22:02:48   D-type          eQ3-HM-LGW
     2020-10-02 22:02:50   cond            ok
     2020-10-07 09:42:42   load            2
     2020-10-02 22:02:50   loadLvl         low
     2020-10-02 22:02:48   state           opened
   helper:
   keepAlive:
     CNT        204
     DEVCNT     203
     DevState   99
     DevType    LGW-KeepAlive
     DeviceName 192.168.178.2:2001
     FD         15
     LastOpen   1601668968.02635
     NAME       myHMLGW:keepAlive
     NR         4147
     PARTIAL    
     STATE      opened
     TEMPORARY  1
     TYPE       HMUARTLGW
     XmitOpen   0
     Helper:
       NextKeepAlive 1602057534.7412
       Log:
         Resolve    1
         IDs:
     READINGS:
       2020-10-02 22:02:48   state           opened
Attributes:
   hmId       9A234E
   lgwPw      ccANTcv3ya
   room       CUL_HM

frank · 07 Oktober 2020, 10:37:18

1. in der vccu fehlt das attr IOgrp. also mal ergänzen.

2. beim senden zum device wird das io myHMLGW_Schlafzimmer genutzt. daher eigentlich unklar, warum myHMLGW im log meckert.
zeig vom myHMLGW_Schlafzimmer auch noch ein list.

3. das reading cfgState im aktor hängt scheinbar auf updating fest.
versuch mal ein getConfig, um hier ein "ok" zu bekommen.

maxritti · 07 Oktober 2020, 10:45:33

1. Check erledigt.

2.

Code Auswählen

Internals:
   AssignedPeerCnt 1
   CNT        189
   Clients    :CUL_HM:
   DEF        192.168.178.3
   DEVCNT     189
   DevState   99
   DevType    LGW
   DeviceName 192.168.178.3:2000
   FD         12
   FUUID      5cc08015-f33f-b047-5f07-b3ef464701da2119
   LastOpen   1601627431.31678
   NAME       myHMLGW_Schlafzimmer
   NOTIFYDEV  global
   NR         28
   NTFY_ORDER 50-myHMLGW_Schlafzimmer
   PARTIAL    
   RAWMSG     040202
   RSSI       -32
   STATE      opened
   TYPE       HMUARTLGW
   XmitOpen   1
   model      eQ3-HM-LGW
   msgLoadCurrent 1
   msgLoadHistory 0/0/0/0/0/0/1/0/0/0/0/0
   msgLoadHistoryAbs 1/1/1/1/1/1/1/0/0/0/0/0/0
   owner      9A234E
   owner_CCU  myVCCU
   Helper:
     CreditTimer 28894
     FW         66561
     Initialized 1
     SendCnt    28
     AckPending:
     LastSendLen:
       3
       3
     Log:
       IDs:
     PendingCMD:
     RoundTrip:
       Delay      0.00347495079040527
     loadLvl:
       lastHistory 1602060034.08069
   MatchList:
     1:CUL_HM   ^A......................
   Peers:
     70CC6F     +70CC6F,00,00,00
   READINGS:
     2020-10-02 10:30:34   D-HMIdAssigned  9A234E
     2020-10-02 10:30:34   D-HMIdOriginal  FFFFFF
     2020-10-02 10:30:31   D-LANfirmware   1.1.5
     2020-10-02 10:30:34   D-firmware      1.4.1
     2020-10-02 10:30:31   D-serialNr      NEQ0381844
     2020-10-02 10:30:31   D-type          eQ3-HM-LGW
     2020-10-02 10:30:34   cond            ok
     2020-10-07 10:07:13   load            1
     2020-10-02 10:30:34   loadLvl         low
     2020-10-02 10:30:31   state           opened
   helper:
   keepAlive:
     CNT        142
     DEVCNT     141
     DevState   99
     DevType    LGW-KeepAlive
     DeviceName 192.168.178.3:2001
     FD         14
     LastOpen   1601627431.32298
     NAME       myHMLGW_Schlafzimmer:keepAlive
     NR         261
     PARTIAL    
     STATE      opened
     TEMPORARY  1
     TYPE       HMUARTLGW
     XmitOpen   0
     Helper:
       NextKeepAlive 1602060096.13967
       Log:
         Resolve    1
         IDs:
     READINGS:
       2020-10-02 10:30:31   state           opened
Attributes:
   hmId       9A234E
   lgwPw      r3W+Fmvm4C
   room       CUL_HM

getConfig im Aktor habe ich ausgeführt.
cfgState verbleibt aber in updating

Aber insgesamt scheint es schon was gebracht zu haben.
Zumindest habe ich den Aktor ein paar mal geschaltet und die Meldung kommt nicht mehr im Log.

frank · 07 Oktober 2020, 11:34:22

das cfgState=updating problem habe/hatte ich mit einem fensterkontakt: https://forum.fhem.de/index.php/topic,113931.msg1089044.html#msg1089044

heute ist das updating plötzlich wieder weg.
vielleicht durch fhem restart, keine ahnung.

das list vom io sieht unauffällig aus.

maxritti · 07 Oktober 2020, 11:51:08

Hm, ich gaube ich muss noch ein wenig suchen.
Denn die Meldung ist doch noch nicht weg.

Ich hatte den Aktor direkt über das Webinterface geschaltet, da ich dachte es kommt daher.

Aber ich habe im Schlafzimmer eine kleine HM-RC-SEC4-2 liegen, mit der ich abends Licht schalte.
Und wenn ich diese benutze, kommt die Meldung. Aber nur bei dem neuen Aktor, den ich letzte Woche eingebunden habe.

Mal das Konstrukt anschauen, da die einzelnen Taster mit virtuellen Devices verbunden sind und DOIFs hängen auch noch dran.

Rächt sich nun vielleicht, da ich das nie ganz verstanden habe.

Auf jeden Fall schon mal Danke für Deine Mühe.