Probleme mit virtuellem HM-CC-TC nach Update

frank · 24 Juni 2015, 23:43:07

hallo martin,

das heutige update verursacht extreme probleme beim steuern der hm-cc-vd mit virtuellen tc's. obwohl vtc und vd mit attr IOgrp=ccu:hmlan1 eigentlich über hmlan kommunizieren sollten, wird bei einigen grundlos ein hmusb1 genutzt. auch ein reassign der io scheint ohne wirkung. es sammeln sich dutzende pending cmds. die vd's können nicht mehr über längere zeit am leben gehalten werden. beim vd existiert auch kein internal LASTInputDev.

list vd

Code Auswählen

Internals: 
   DEF        1DFC2F 
   IODev      hmusb1 
   NAME       Ventil.SZ 
   NR         277 
   NTFY_ORDER 50-Ventil.SZ 
   STATE      Vsoll:99 %, Vist:0, Status:IOerr, Operation:errorTargetNotMet, OpErr:1073, Mot:stop, MotErr:ok, Bat:ok, Verr:0 % 
   TYPE       CUL_HM 
   peerList   VentilControler.SZ_Btn1, 
   protCmdDel 5 
   protCmdPend 64 CMDs pending 
   protIOdly  68 last_at:2015-06-24 23:14:52 
   protIOerr  1 last_at:2015-06-24 20:30:04 
   protSnd    68 last_at:2015-06-24 23:14:48 
   protState  CMDs_processing... 
   Readings: 
     2015-01-11 19:51:33   .D-devInfo      010100 
     2015-01-11 19:51:33   .D-stc          58 
     2015-06-24 14:43:00   .protLastRcv    2015-06-24 14:43:00 
     2015-06-24 20:20:06   Activity        alive 
     2015-06-24 14:42:59   CommandAccepted yes 
     2015-01-11 19:51:33   D-firmware      2.0 
     2015-01-11 19:51:33   D-serialNr      JEQ0531720 
     2015-01-11 19:51:33   PairedTo        0x1ACE1F 
     2014-11-02 19:37:21   R-pairCentral   0x1ACE1F 
     2014-11-02 12:01:29   R-valveErrorPos 0 % 
     2014-11-02 12:01:29   R-valveOffset   0 % 
     2015-01-11 19:51:33   RegL_00:        02:01 0A:1A 0B:CE 0C:1F 00:00 
     2015-01-11 19:51:34   RegL_05:        09:00 0A:00 00:00 
     2015-06-24 20:20:08   ValveDesired    99 % 
     2015-06-24 14:42:59   ValvePosition   0 
     2015-06-24 14:42:59   battery         ok 
     2015-06-24 14:42:59   motor           stop 
     2015-06-24 14:42:59   motorErr        ok 
     2015-06-24 14:42:59   operState       errorTargetNotMet 
     2015-06-24 14:42:59   operStateErrCnt 1073 
     2015-06-24 20:20:06   peerList        VentilControler.SZ_Btn1, 
     2015-01-11 19:48:24   powerOn         2015-01-11 19:48:24 
     2015-06-24 14:42:59   recentStateType ack 
     2014-11-02 17:35:47   rssi_at_cul868  -60.5 
     2014-11-02 19:37:22   rssi_at_hmlan1  -56 
     2014-11-02 19:37:22   rssi_at_hmusb1  -53 
     2014-11-02 19:37:19   rssi_hmlan1     -53 
     2015-06-24 20:30:04   state           IOerr 
   cmdStack: 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
     ++A258B2B2B21DFC2F00FD 
   Helper: 
     mId        003A 
     oldDes     0 
     rxType     12 
     Bm: 
       Cul_hm_get: 
         cnt        1 
         dmx        0 
         max        1 
         tot        1 
         mAr: 
           HASH(0x141d1b0) 
           Ventil.SZ 
           ? 
       Cul_hm_set: 
         cnt        9 
         dmx        0 
         max        18 
         tot        133 
         mAr: 
           HASH(0x141d1b0) 
           Ventil.SZ 
           ? 
     Io: 
       newChn     +1DFC2F,02,01,00 
       rxt        2 
       vccu       ccu 
       p: 
         1DFC2F 
         00 
         01 
         00 
       prefIO: 
         hmlan1 
     Mrssi: 
       mNo 
       Io: 
     Prt: 
       bErr       0 
       sProc      1 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       chn        1 
       dev        1 
Attributes: 
   IODev      hmlan1 
   IOgrp      ccu:hmlan1 
   actCycle   028:00 
   actStatus  alive 
   alias      40. Ventil 
   autoReadReg 5_readMissing 
   event-on-change-reading .* 
   expert     2_full 
   firmware   2.0 
   group      Heizung.SZ 
   model      HM-CC-VD 
   msgRepeat  0 
   peerIDs    00000000,B2B2B201, 
   room       50_SZ,98_Ventile 
   serialNr   JEQ0531720 
   stateFormat Vsoll:ValveDesired, Vist:ValvePosition, Status:state, Operation:operState, OpErr:operStateErrCnt, Mot:motor, MotErr:motorErr, Bat:battery, Verr:R-valveErrorPos 
   subType    thermostat 
   webCmd     getConfig

list vtc

Code Auswählen

Internals: 
   DEF        B2B2B2 
   IODev      hmlan1 
   LASTInputDev hmlan1 
   MSGCNT     224 
   NAME       VentilControler.SZ 
   NR         278 
   NTFY_ORDER 50-VentilControler.SZ 
   STATE      ??? 
   TYPE       CUL_HM 
   channel_01 VentilControler.SZ_Btn1 
   hmlan1_MSGCNT 215 
   hmlan1_RAWMSG EB2B2B2,0000,29196610,FF,FFDF,A5A258B2B2B21DFC2F00FD 
   hmlan1_RSSI -33 
   hmlan1_TIME 2015-06-24 23:32:15 
   hmusb1_MSGCNT 9 
   hmusb1_RAWMSG EB2B2B2,0000,009D6C9C,FF,FFDD,5CA258B2B2B21DFC2F00FD 
   hmusb1_RSSI -35 
   hmusb1_TIME 2015-06-24 20:27:25 
   lastMsg    No:A5 - t:58 s:B2B2B2 d:1DFC2F 00FD 
   protLastRcv 2015-06-24 23:32:15 
   rssi_at_hmlan1 avg:-32.79 min:-33 max:-31 lst:-33 cnt:215 
   rssi_at_hmusb1 avg:-35 min:-35 max:-35 lst:-35 cnt:9 
   Readings: 
     2015-06-24 23:32:15   .protLastRcv    2015-06-24 23:32:15 
   Helper: 
     HM_CMDNR   165 
     Bm: 
       Cul_hm_get: 
         cnt        1 
         dmx        0 
         max        1 
         tot        1 
         mAr: 
           HASH(0x141d410) 
           VentilControler.SZ 
           ? 
       Cul_hm_set: 
         cnt        1 
         dmx        0 
         max        2 
         tot        2 
         mAr: 
           HASH(0x141d410) 
           VentilControler.SZ 
           ? 
     Io: 
       nextSend   1435181536.05356 
       vccu       ccu 
       prefIO: 
         hmlan1 
     Mrssi: 
       mNo        A5 
       Io: 
         hmlan1     -31 
     Prt: 
       bErr       0 
       sProc      0 
       Rspwait: 
     Q: 
       qReqConf 
       qReqStat 
     Role: 
       dev        1 
       vrt        1 
     Rssi: 
       At_hmlan1: 
         avg        -32.7953488372093 
         cnt        215 
         lst        -33 
         max        -31 
         min        -33 
       At_hmusb1: 
         avg        -35 
         cnt        9 
         lst        -35 
         max        -35 
         min        -35 
Attributes: 
   IODev      hmlan1 
   IOgrp      ccu:hmlan1 
   group      Heizung.SZ 
   model      virtual_1 
   msgRepeat  0 
   room       50_SZ 
   subType    virtual 
   webCmd     :

list vtc_btn1

Code Auswählen

Internals: 
   DEF        B2B2B201 
   NAME       VentilControler.SZ_Btn1 
   NR         279 
   NTFY_ORDER 50-VentilControler.SZ_Btn1 
   STATE      Vsoll:99 %, Status:ValveAdjust:99 %, Kommunikation:lost, ErrCtr:3319, Modus:msgReduce:2 
   TYPE       CUL_HM 
   chanNo     01 
   device     VentilControler.SZ 
   peerList   Ventil.SZ, 
   .userreadings: 
     Msgreduce: 
       TIME       2015-06-24 20:32:43 
       modifier   none 
       perlCode   {AttrVal($name,"param","???")} 
       t          1435170763.08138 
       trigger 
       value      msgReduce:2 
   Readings: 
     2015-06-24 14:43:03   .next           88;1435149924.7054 
     2015-06-24 14:49:53   errorCtr        3319 
     2015-06-24 14:49:53   errorState      err 
     2015-06-24 20:32:43   msgReduce       msgReduce:2 
     2015-06-24 20:20:08   peerList        Ventil.SZ, 
     2015-06-24 20:20:08   state           ValveAdjust:99 % 
     2015-06-24 20:32:39   valveCtrl       lost 
     2015-06-24 20:32:43   valveCtrlRam    lost 
     2015-06-24 20:20:08   valvePosTC      99 % 
   Helper: 
     fkt        vdCtrl 
     virtTC     00 
     Bm: 
       Cul_hm_get: 
         cnt        2 
         dmx        0 
         max        1 
         tot        1 
         mAr: 
           HASH(0x141cf50) 
           VentilControler.SZ_Btn1 
           ? 
       Cul_hm_set: 
         cnt        8 
         dmx        0 
         max        13 
         tot        99 
         mAr: 
           HASH(0x141cf50) 
           VentilControler.SZ_Btn1 
           ? 
     Role: 
       chn        1 
       vrt        1 
     Vd: 
       ackT       2015-06-24 14:42:59 
       cmd        A258B2B2B21DFC2F 
       id         1DFC2F 
       idh        3573706 
       idl        45568 
       miss       78 
       msgCnt     166 
       msgRed     2 
       msgSent    0 
       nDev       Ventil.SZ 
       next       1435181841.99073 
       nextM      1435181841.99677 
       typ        1 
       val        FD 
       vin        99 
       virtTC     00 
Attributes: 
   alias      30. Controler 
   event-on-change-reading .* 
   event-on-update-reading state,valvePosTC 
   group      Heizung.SZ 
   model      virtual_1 
   param      msgReduce:2 
   peerIDs    1DFC2F01, 
   room       50_SZ,98_Ventile 
   stateFormat Vsoll:valvePosTC, Status:state, Kommunikation:valveCtrl, ErrCtr:errorCtr, Modus:msgReduce 
   userReadings msgReduce {AttrVal($name,"param","???")} 
   webCmd     press short:press long

edit: kurz nach mitternacht gab es einen timeout/disconnect bei meinem hmlan, wodurch ich immer einen apptime bericht erstelle. dieser zeigt mir zum ersten mal, dass die timer von den vtc's fhem blockieren. eventuell ein hinweis auf das problem.

Code Auswählen

                                name             function    max  count    total  average maxDly
                              hmlan1           HMLAN_Read   4158   3819   452425   118.47      0 HASH(hmlan1)
              tmr-CUL_HM_valvePosTmr    valveTmr:B1B1B101   4147     88    26217   297.92    560 valveTmr:B1B1B101
              tmr-CUL_HM_valvePosTmr    valveTmr:B4B4B401   3756     88    26268   298.50    748 valveTmr:B4B4B401
              tmr-CUL_HM_valvePosTmr    valveTmr:B5B5B501   3704     87    25500   293.10   2748 valveTmr:B5B5B501
              tmr-CUL_HM_valvePosTmr    valveTmr:B2B2B201   3676     88     6153    69.92   8846 valveTmr:B2B2B201
                                  lp         logProxy_Get   3519      1     3519  3519.00      0 HASH(lp); lp; CURRENT; INT; 2015-06-24_18:00:00; 2015-06-25_00:00:01; FileLog:FileLog_Technik_IO,predict,extend=60*60:4:ccu.hmusb1_Load\x3a::; FileLog:FileLog_Technik_IO,predict,extend=24*60*60:4:hmusb1.cond\x3a:0:$fld[3]=~"disconnected"?6:$fld[3]=~"timeout"?5:$fld[3]=~"Overload"?4:$fld[3]=~"HighLoad"?3:$fld[3]=~"init"?2:$fld[3]=~"ok"?0:1
              tmr-CUL_HM_valvePosTmr    valveTmr:B3B3B301   3499     87    21812   250.71   2628 valveTmr:B3B3B301

frank · 25 Juni 2015, 13:33:17

jetzt habe ich mal einen mit verbose 5 gelogged.

Code Auswählen

2015.06.25 11:49:31.088 5: CUL_HM VentilControler.Kueche_Btn1 m:153 ->154 t:1435225771.0813->1435225947.3313  M:1435225771.08755 :176.25
2015.06.25 11:49:31.092 0: HMLAN_Send:  hmlan1 S:S2A1F4DA1 stat:  00 t:00000000 d:01 r:2A1F4DA1 m:9A A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:31.149 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002ACFD6 d:FF r:FFDD     m:9A A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:31.262 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288E9F8 d:FF r:FFC0     m:9A 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:31.353 0: HMLAN_Send:  hmlan1 S:S2A1F4E5A stat:  00 t:00000000 d:01 r:2A1F4E5A m:A0 A112 1ACE1F 1BFC52 
2015.06.25 11:49:31.843 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288EAF0 d:FF r:FFC0     m:9A 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:31.866 0: HMLAN_Parse: hmlan1 R:R2A0DB77B stat:0008 t:00000000 d:FF r:7FFF     m:9A A112 1ACE1F 1BFC52 
2015.06.25 11:49:31.868 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:31.873 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD05A d:FF r:FFC1     m:9A 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:31.976 0: HMLAN_Send:  hmlan1 S:S2A1F50BC stat:  00 t:00000000 d:01 r:2A1F50BC m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.141 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD0D1 d:FF r:FFDD     m:9A A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.156 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD152 d:FF r:FFC1     m:9A 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:32.176 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AD292 d:FF r:FFDD     m:9A A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:32.199 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288ECB4 d:FF r:FFC0     m:9A 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:32.386 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD316 d:FF r:FFC1     m:9A 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:32.401 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD38D d:FF r:FFDD     m:9A A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.414 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD40D d:FF r:FFC1     m:9A 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:32.453 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288EDAC d:FF r:FFC0     m:9A 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:32.498 0: HMLAN_Parse: hmlan1 R:R2A0DB77B stat:0008 t:00000000 d:FF r:7FFF     m:9A A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.500 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:32.557 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AD54E d:FF r:FFDD     m:9A A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:32.589 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD577 d:FF r:FFDD     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.662 0: HMLAN_Parse: hmlan1 R:R2A1F4DA1 stat:0008 t:00000000 d:FF r:7FFF     m:9A A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:32.664 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:32.675 0: HMLAN_Send:  hmlan1 S:S2A1F53D0 stat:  00 t:00000000 d:01 r:2A1F53D0 m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.691 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288EF70 d:FF r:FFC0     m:9A 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:32.882 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD5D2 d:FF r:FFC1     m:9A 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:32.897 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD649 d:FF r:FFDD     m:9A A112 1ACE1F 1BFC52 
2015.06.25 11:49:32.924 0: HMLAN_Parse: hmlan1 R:R28AFC0A0 stat:0081 t:0288F06D d:FF r:FFC0     m:9A 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:33.007 0: HMLAN_Send:  hmlan1 S:S2A1F54E3 stat:  00 t:00000000 d:01 r:2A1F54E3 m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:33.030 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD6C9 d:FF r:FFC1     m:9A 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:33.197 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD7DA d:FF r:FFDE     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:33.229 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD804 d:FF r:FFDC     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:33.314 0: HMLAN_Parse: hmlan1 R:R2A1F54E3 stat:0001 t:0288F1FD d:FF r:FFC0     m:9B 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:33.406 0: HMLAN_Send:  hmlan1 S:S2A1F5662 stat:  00 t:00000000 d:01 r:2A1F5662 m:9C A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:33.422 0: HMLAN_Parse: hmlan1 R:R2A1F4E5A stat:0008 t:00000000 d:FF r:7FFF     m:A0 A112 1ACE1F 1BFC52 
2015.06.25 11:49:33.424 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:33.428 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AD85A d:FF r:FFC1     m:9B 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:33.678 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AD9B1 d:FF r:FFDD     m:9C A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:33.709 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AD9DA d:FF r:FFDD     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:33.785 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288F3D5 d:FF r:FFC0     m:9C 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:33.879 0: HMLAN_Send:  hmlan1 S:S2A1F5836 stat:  00 t:00000000 d:01 r:2A1F5836 m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.052 0: HMLAN_Parse: hmlan1 R:R2A1F50BC stat:0008 t:00000000 d:FF r:7FFF     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.054 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:34.057 0: HMLAN_Parse: hmlan1 R:R2A1F54E3 stat:0081 t:0288F4D1 d:FF r:FFC0     m:9C 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:34.081 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002ADA35 d:FF r:FFC1     m:9C 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:34.184 0: HMLAN_Send:  hmlan1 S:S2A1F595C stat:  00 t:00000000 d:01 r:2A1F595C m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.357 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002ADAAD d:FF r:FFDC     m:9C A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.372 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002ADB2C d:FF r:FFC1     m:9C 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:34.396 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002ADC3E d:FF r:FFDD     m:9C A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:34.409 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002ADC68 d:FF r:FFDD     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.438 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288F662 d:FF r:FFC0     m:9C 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:34.627 0: HMLAN_Parse: hmlan1 R:R2A1F53D0 stat:0008 t:00000000 d:FF r:7FFF     m:9B A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.629 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:34.633 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002ADCC2 d:FF r:FFC1     m:9C 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:34.647 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002ADD3A d:FF r:FFDD     m:9C A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.737 0: HMLAN_Send:  hmlan1 S:S2A1F5B9C stat:  00 t:00000000 d:01 r:2A1F5B9C m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.747 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288F759 d:FF r:FFC0     m:9C 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:34.771 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002ADDBA d:FF r:FFC1     m:9C 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:34.975 0: HMLAN_Parse: hmlan1 R:R28AFC0A0 stat:0008 t:00000000 d:FF r:7FFF     m:9C A112 1ACE1F 1BFC52 
2015.06.25 11:49:34.977 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:35.021 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002ADEF9 d:FF r:FFDD     m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:35.053 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002ADF24 d:FF r:FFDD     m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:35.131 0: HMLAN_Parse: hmlan1 R:R2A1F595C stat:0001 t:0288F91D d:FF r:FFC0     m:9D 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:35.230 0: HMLAN_Send:  hmlan1 S:S2A1F5D7B stat:  00 t:00000000 d:01 r:2A1F5D7B m:9E A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:35.246 0: HMLAN_Parse: hmlan1 R:R2A1F5662 stat:0008 t:00000000 d:FF r:7FFF     m:9C A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:35.248 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:35.252 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002ADF78 d:FF r:FFC1     m:9D 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:35.515 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AE0CF d:FF r:FFDC     m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:35.639 0: HMLAN_Parse: hmlan1 R:R2A1F5B9C stat:0001 t:0288FAF4 d:FF r:FFC0     m:9D 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:35.706 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AE0F9 d:FF r:FFDD     m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:35.751 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AE14E d:FF r:FFC1     m:9D 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:35.856 0: HMLAN_Parse: hmlan1 R:R2A1F5836 stat:0008 t:00000000 d:FF r:7FFF     m:9D A112 1ACE1F 1BFC52 
2015.06.25 11:49:35.862 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:36.030 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AE2BB d:FF r:FFDD     m:9E A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:36.099 0: HMLAN_Parse: hmlan1 R:E1BFC52   stat:0000 t:0288FCE1 d:FF r:FFC0     m:9E 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:36.194 0: HMLAN_Send:  hmlan1 S:S2A1F6140 stat:  00 t:00000000 d:01 r:2A1F6140 m:9F A112 1ACE1F 1BFC52 
2015.06.25 11:49:36.361 0: HMLAN_Parse: hmlan1 R:R2A1F5B9C stat:0081 t:0288FDDE d:FF r:FFC0     m:9E 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:36.385 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AE33F d:FF r:FFC1     m:9E 8202 1BFC52 B1B1B1 01011E003E
2015.06.25 11:49:36.488 0: HMLAN_Send:  hmlan1 S:S2A1F625C stat:  00 t:00000000 d:01 r:2A1F625C m:9F A112 1ACE1F 1BFC52 
2015.06.25 11:49:36.652 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AE3B7 d:FF r:FFDD     m:9E A112 1ACE1F 1BFC52 
2015.06.25 11:49:36.667 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AE437 d:FF r:FFC1     m:9E 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:36.694 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AE547 d:FF r:FFDD     m:9F A112 1ACE1F 1BFC52 
2015.06.25 11:49:36.714 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AE55F d:FF r:FFDE     m:9E A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:36.728 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AE574 d:FF r:FFDD     m:9F A112 1ACE1F 1BFC52 
2015.06.25 11:49:36.747 0: HMLAN_Parse: hmlan1 R:R2A1F625C stat:0001 t:0288FF6E d:FF r:FFC0     m:9F 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:36.846 0: HMLAN_Send:  hmlan1 S:S2A1F63CB stat:  00 t:00000000 d:01 r:2A1F63CB m:A0 A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:36.865 0: HMLAN_Parse: hmlan1 R:R2A1F5D7B stat:0008 t:00000000 d:FF r:7FFF     m:9E A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:36.866 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:49:36.870 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AE5C8 d:FF r:FFC1     m:9F 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:37.327 0: HMLAN_Parse: hmlan1 R:R2A1F6140 stat:0001 t:02890145 d:FF r:FFC0     m:9F 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:37.345 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:002AE71E d:FF r:FFDD     m:9F A112 1ACE1F 1BFC52 
2015.06.25 11:49:37.358 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AE735 d:FF r:FFDE     m:A0 A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:37.377 0: HMLAN_Parse: hmusb1 R:E1BFC52   stat:0000 t:002AE79F d:FF r:FFC1     m:9F 8002 1BFC52 1ACE1F 00
2015.06.25 11:49:37.517 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AE8B4 d:FF r:FFDD     m:A0 A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:37.715 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002AE97D d:FF r:FFDD     m:A0 A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:37.867 0: HMLAN_Parse: hmlan1 R:R2A1F63CB stat:0008 t:00000000 d:FF r:7FFF     m:A0 A258 B1B1B1 1BFC52 0000
2015.06.25 11:49:37.868 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52

#
#
#

2015.06.25 11:52:27.346 5: CUL_HM VentilControler.Kueche_Btn1 m:154 ->155 t:1435225947.33755->1435226109.33755  M:1435225947.34553 :162
2015.06.25 11:52:27.351 0: HMLAN_Send:  hmlan1 S:S2A21FE23 stat:  00 t:00000000 d:01 r:2A21FE23 m:9B A258 B1B1B1 1BFC52 0000
2015.06.25 11:52:27.403 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002D8057 d:FF r:FFDD     m:9B A258 B1B1B1 1BFC52 0000
2015.06.25 11:52:27.594 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002D811F d:FF r:FFDC     m:9B A258 B1B1B1 1BFC52 0000
2015.06.25 11:52:27.819 0: HMLAN_Parse: hmusb1 R:EB1B1B1   stat:0000 t:002D81E7 d:FF r:FFDD     m:9B A258 B1B1B1 1BFC52 0000
2015.06.25 11:52:27.960 0: HMLAN_Parse: hmlan1 R:R2A21FE23 stat:0008 t:00000000 d:FF r:7FFF     m:9B A258 B1B1B1 1BFC52 0000
2015.06.25 11:52:27.962 0: HMLAN_Parse: hmlan1 no ACK from 1BFC52
2015.06.25 11:52:37.353 5: CUL_HM VentilControler.Kueche_Btn1 virtualTC use fail-timer

bei diesem vd unterhalten sich vtc und vd immer extrem ausgiebig und seltsam. hier mischt sich die zentrale 1ACE1F in die unterhaltung ein und bringt alles durcheinander. anschliessend können sie sich nicht mehr treffen und der vd schläft ein. seltsam finde ich auch, dass der hmusb immer 3 messages registriert, obwohl eigentlich vd und vtc mit msgRepeat=0 gesetzt sind.

das entscheidende problem ist sicherlich, dass die "A258" message vom vtc während dieser unterhaltung 3 mal gesendet und 3 mal beantwortet wird, aber jedesmal mit einer anderen messagenummer (9A, 9C, 9E). da die letzte message mit messagenummer 9C gesendet wurde, berechnet der vd daraus das nächste meeting. fhem berechnet das meeting aber aus der ersten message mit nummer 9A. somit haben sie keine chance sich mehr zu treffen.

gruss frank

martinp876 · 25 Juni 2015, 22:14:07

Der hmlan sendet 3mal wenn eine antwort erwartet wird und er keine sieht. Und eine antwort an eine andere als die eigene id gilt nicht.ein problem der virtuellen sender. Das ist bei channels der vccu einfacher, da reicht idr eine

frank · 26 Juni 2015, 10:46:28

ok, aber irgendwas muss in der letzten woche geaendert worden sein, sodass die vd permanent schlafen. ich werde erstmal die batterien entfernen und am wochenende weiterschauen.

martinp876 · 26 Juni 2015, 13:08:24

Das a112 ist ein hallowach. Fhem will das device etwas fragen, evtl getconfig. Das ist wohl das probelm. Ist aber eigentlich nicht neu.

frank · 30 Juni 2015, 11:48:42

Zitat von: martinp876 am 26 Juni 2015, 13:08:24
Das a112 ist ein hallowach. Fhem will das device etwas fragen, evtl getconfig. Das ist wohl das probelm. Ist aber eigentlich nicht neu.

nein, es müssen die messagenummern gewesen sein. inzwischen laufen wieder alle vd und ich weiss nicht warum.

zuerst hatte ich getconfig probiert (manuell abgearbeitet), aber es half nicht. die daten sahen auch gut aus. nach mehreren updates der homatic module und letztendlich batterien raus und rein laufen sie nun wieder. nun werden die message nummern durch das A112 nicht mehr verändert, sondern immer die selbe benutzt. warum auch immer, so läuft es einigermassen zufriedenstellend.

Code Auswählen

2015.06.29 05:44:29.209 0: HMLAN_Send:  hmlan1 S:S3D6A8B66 stat:  00 t:00000000 d:01 r:3D6A8B66 m:84 A258 B4B4B4 1CE9F5 00FD
2015.06.29 05:44:29.216 0: HMLAN_Send:  hmlan1 I:+1CE9F5,02,01,00
2015.06.29 05:44:29.268 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:01BCEFF4 d:FF r:FFDD     m:84 A258 B4B4B4 1CE9F5 00FD
2015.06.29 05:44:29.461 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:01BCF0BC d:FF r:FFDD     m:84 A258 B4B4B4 1CE9F5 00FD
2015.06.29 05:44:29.579 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:0200EDA1 d:FF r:FFCE     m:84 8202 1CE9F5 B4B4B4 0101C60033
2015.06.29 05:44:29.601 0: HMLAN_Send:  hmlan1 I:+1CE9F5,00,01,00
2015.06.29 05:44:29.622 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:01BCF13F d:FF r:FFC8     m:84 8202 1CE9F5 B4B4B4 0101C60033
2015.06.29 05:44:29.716 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:01BCF1B7 d:FF r:FFDB     m:84 A112 1ACE1F 1CE9F5 
2015.06.29 05:44:29.824 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:0200EE97 d:FF r:FFCE     m:84 8002 1CE9F5 1ACE1F 00
2015.06.29 05:44:29.850 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:01BCF236 d:FF r:FFC8     m:84 8002 1CE9F5 1ACE1F 00
2015.06.29 05:44:30.116 0: HMLAN_Parse: hmlan1 R:R3D68AE9B stat:0008 t:00000000 d:FF r:7FFF     m:84 A112 1ACE1F 1CE9F5 
2015.06.29 05:44:30.118 0: HMLAN_Parse: hmlan1 no ACK from 1CE9F5
2015.06.29 05:44:30.164 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:01BCF378 d:FF r:FFDD     m:84 A258 B4B4B4 1CE9F5 00FD
2015.06.29 05:44:30.279 0: HMLAN_Parse: hmlan1 R:R3D6A8B66 stat:0008 t:00000000 d:FF r:7FFF     m:84 A258 B4B4B4 1CE9F5 00FD
2015.06.29 05:44:30.281 0: HMLAN_Parse: hmlan1 no ACK from 1CE9F5
2015.06.29 05:44:30.286 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:0200F05D d:FF r:FFCE     m:84 8202 1CE9F5 B4B4B4 0101C60032
2015.06.29 05:44:30.322 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:01BCF3FB d:FF r:FFC8     m:84 8202 1CE9F5 B4B4B4 0101C60032

trotzdem frage ich mich, warum fhem ständig die A112 message sendet. das einzige, was fhem nach dem beantworten der A112 macht, es wiederholt die A258 message, die bereits erfolgreich war. A112 kommt immer, sobald der vd auf eine routinemässig gesendete A258 antwortet. das war auf alle fälle nicht schon immer so (das habe ich mir aber auch schon länger nicht angeschaut). ausserdem kommt A112 ja nur, wenn der vd bereits wach ist. sozusagen eigentlich überflüssig, selbst wenn etwas sinnvolles gesendet werden müsste.

hminfo configcheck bringt auch keine hinweise auf sonstige probleme.

manchmal gibt es auch den fall, dass A112 gesendet und beantwortet wird, aber daraufhin keine weitere message gesendet wird. sehr seltsam.

Code Auswählen

2015.06.29 06:18:36.048 0: HMLAN_Send:  hmlan1 S:S3D89C6DC stat:  00 t:00000000 d:01 r:3D89C6DC m:A2 A258 B3B3B3 193A9A 0000
2015.06.29 06:18:36.059 0: HMLAN_Send:  hmlan1 I:+193A9A,02,01,00
2015.06.29 06:18:36.115 0: HMLAN_Parse: hmusb1 R:EB3B3B3   stat:0000 t:01DC2B49 d:FF r:FFDB     m:A2 A258 B3B3B3 193A9A 0000
2015.06.29 06:18:36.619 0: HMLAN_Parse: hmlan1 R:R3D89C6DC stat:0008 t:00000000 d:FF r:7FFF     m:A2 A258 B3B3B3 193A9A 0000
2015.06.29 06:18:36.621 0: HMLAN_Parse: hmlan1 no ACK from 193A9A
2015.06.29 06:18:36.626 0: HMLAN_Parse: hmusb1 R:EB3B3B3   stat:0000 t:01DC2C11 d:FF r:FFDB     m:A2 A258 B3B3B3 193A9A 0000
2015.06.29 06:18:36.643 0: HMLAN_Parse: hmusb1 R:EB3B3B3   stat:0000 t:01DC2CD9 d:FF r:FFDB     m:A2 A258 B3B3B3 193A9A 0000
2015.06.29 06:18:36.656 0: HMLAN_Parse: hmusb1 R:E193A9A   stat:0000 t:01DC2D5C d:FF r:FFB9     m:A2 8202 193A9A B3B3B3 010100003A
2015.06.29 06:18:36.680 0: HMLAN_Send:  hmlan1 I:+193A9A,00,01,00
2015.06.29 06:18:36.707 0: HMLAN_Parse: hmlan1 R:E193A9A   stat:0000 t:02202AF7 d:FF r:FFC5     m:A2 8202 193A9A B3B3B3 010100003A
2015.06.29 06:18:36.754 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:01DC2DD4 d:FF r:FFDC     m:A2 A112 1ACE1F 193A9A 
2015.06.29 06:18:36.864 0: HMLAN_Parse: hmlan1 R:R3BAFF4AB stat:0081 t:02202BF2 d:FF r:FFC6     m:A2 8002 193A9A 1ACE1F 00
2015.06.29 06:18:36.889 0: HMLAN_Parse: hmusb1 R:E193A9A   stat:0000 t:01DC2E52 d:FF r:FFBA     m:A2 8002 193A9A 1ACE1F 00

gruss frank

martinp876 · 30 Juni 2015, 20:17:01

A112 ist ein hallowach. Sendet ein hmlan wenn es meint es sei etwas zu senden. Man sieht kein send, kommt also automatisch. Das muss ich abschalten.... schadet nicht, kostet aber sinlos funkzeit

frank · 30 Juni 2015, 20:36:42

ZitatDas muss ich abschalten....

hört sich gut an.

martinp876 · 01 Juli 2015, 20:43:30

logge einmal mit loglevel 5 für das Device.

Das Problem ist die 02 in
2015.06.29 05:44:29.216 0: HMLAN_Send: hmlan1 I:+1CE9F5,02,01,00

das muss hier 0 werden

frank · 01 Juli 2015, 23:43:47

Zitatlogge einmal mit loglevel 5 für das Device.

Code Auswählen

2015.07.01 23:33:53.352 0: HMLAN_Send:  hmlan1 S:S4B8A5494 stat:  00 t:00000000 d:01 r:4B8A5494 m:9A A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:33:53.355 0: HMLAN_Send:  hmlan1 I:K
2015.07.01 23:33:53.361 5: CUL_HM Ventil.WZ protEvent:CMDs_processing...
2015.07.01 23:33:53.363 0: HMLAN_Send:  hmlan1 I:+1CE9F5,02,01,00
2015.07.01 23:33:53.382 0: HMLAN_Parse: hmlan1 V:03C4 sNo:JEQ0315335 d:1C671E O:1ACE1F t:004D4F4D IDcnt:000D L:26 %
2015.07.01 23:33:53.395 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:001040D0 d:FF r:FFD3     m:9A A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:33:53.619 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:00104199 d:FF r:FFD3     m:9A A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:33:53.811 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:00104261 d:FF r:FFD3     m:9A A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:33:53.962 0: HMLAN_Parse: hmlan1 R:R4B8A5494 stat:0008 t:00000000 d:FF r:7FFF     m:9A A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:33:53.964 0: HMLAN_Parse: hmlan1 no ACK from 1CE9F5
2015.07.01 23:33:57.858 5: CUL_HM Ventil.WZ protEvent:CMDs_done_Errors:1
2015.07.01 23:33:57.862 0: HMLAN_Send:  hmlan1 I:+1CE9F5,00,01,00
2015.07.01 23:34:08.586 0: HMLAN_Send:  hmlan1 I:+1DFC2F,02,01,00
2015.07.01 23:34:08.816 0: HMLAN_Send:  hmlan1 I:+1DFC2F,00,01,00
2015.07.01 23:34:11.200 0: HMLAN_Send:  hmusb1 I:K
2015.07.01 23:34:11.252 0: HMLAN_Parse: hmusb1 V:03C7 sNo:KEQ1111271 d:263408 O:1ACE1F t:00108682 IDcnt:0011 L:6 %
2015.07.01 23:34:18.361 0: HMLAN_Send:  hmlan1 I:K
2015.07.01 23:34:18.373 0: HMLAN_Parse: hmlan1 V:03C4 sNo:JEQ0315335 d:1C671E O:1ACE1F t:004DB0E9 IDcnt:000D L:26 %
2015.07.01 23:34:36.205 0: HMLAN_Send:  hmusb1 I:K
2015.07.01 23:34:36.275 0: HMLAN_Parse: hmusb1 V:03C7 sNo:KEQ1111271 d:263408 O:1ACE1F t:0010E841 IDcnt:0011 L:6 %
2015.07.01 23:34:43.367 0: HMLAN_Send:  hmlan1 I:K
2015.07.01 23:34:43.378 0: HMLAN_Parse: hmlan1 V:03C4 sNo:JEQ0315335 d:1C671E O:1ACE1F t:004E129A IDcnt:000D L:26 %
2015.07.01 23:35:01.451 0: HMLAN_Send:  hmusb1 I:K
2015.07.01 23:35:01.492 0: HMLAN_Parse: hmusb1 V:03C7 sNo:KEQ1111271 d:263408 O:1ACE1F t:00114AC0 IDcnt:0011 L:6 %
2015.07.01 23:35:03.077 0: HMLAN_Send:  hmlan1 I:+193A9A,02,01,00
2015.07.01 23:35:07.305 0: HMLAN_Send:  hmlan1 I:+193A9A,00,01,00
2015.07.01 23:35:08.414 0: HMLAN_Send:  hmlan1 I:K
2015.07.01 23:35:08.426 0: HMLAN_Parse: hmlan1 V:03C4 sNo:JEQ0315335 d:1C671E O:1ACE1F t:004E7477 IDcnt:000D L:25 %
2015.07.01 23:35:26.457 0: HMLAN_Send:  hmusb1 I:K
2015.07.01 23:35:26.517 0: HMLAN_Parse: hmusb1 V:03C7 sNo:KEQ1111271 d:263408 O:1ACE1F t:0011AC7F IDcnt:0011 L:6 %
2015.07.01 23:35:33.420 0: HMLAN_Send:  hmlan1 I:K
2015.07.01 23:35:33.465 0: HMLAN_Parse: hmlan1 V:03C4 sNo:JEQ0315335 d:1C671E O:1ACE1F t:004ED635 IDcnt:000D L:25 %
2015.07.01 23:35:51.464 0: HMLAN_Send:  hmusb1 I:K
2015.07.01 23:35:51.525 0: HMLAN_Parse: hmusb1 V:03C7 sNo:KEQ1111271 d:263408 O:1ACE1F t:00120E3E IDcnt:0011 L:6 %
2015.07.01 23:35:58.352 0: HMLAN_Send:  hmlan1 S:S4B8C3CDC stat:  00 t:00000000 d:01 r:4B8C3CDC m:9B A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:35:58.357 5: CUL_HM Ventil.WZ protEvent:CMDs_processing...
2015.07.01 23:35:58.359 0: HMLAN_Send:  hmlan1 I:+1CE9F5,02,01,00
2015.07.01 23:35:58.404 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:00122922 d:FF r:FFD3     m:9B A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:35:58.427 0: HMLAN_Send:  hmlan1 I:K
2015.07.01 23:35:58.437 0: HMLAN_Parse: hmlan1 V:03C4 sNo:JEQ0315335 d:1C671E O:1ACE1F t:004F37E6 IDcnt:000D L:25 %
2015.07.01 23:35:58.596 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:001229EA d:FF r:FFD3     m:9B A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:35:58.723 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:004F38FB d:FF r:FFCF     m:9B 8202 1CE9F5 B4B4B4 0101C60032
2015.07.01 23:35:58.744 5: CUL_HM Ventil.WZ protEvent:CMDs_done
2015.07.01 23:35:58.746 0: HMLAN_Send:  hmlan1 I:+1CE9F5,00,01,00
2015.07.01 23:35:58.941 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:00122A6E d:FF r:FFBE     m:9B 8202 1CE9F5 B4B4B4 0101C60032
2015.07.01 23:35:58.952 4: CUL_HM Ventil.WZ dupe: dont process
2015.07.01 23:35:58.958 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:00122AE5 d:FF r:FFD3     m:9B A112 1ACE1F 1CE9F5 
2015.07.01 23:35:58.977 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:004F39F2 d:FF r:FFCF     m:9B 8002 1CE9F5 1ACE1F 00
2015.07.01 23:35:59.001 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:00122B64 d:FF r:FFBE     m:9B 8002 1CE9F5 1ACE1F 00
2015.07.01 23:35:59.013 4: CUL_HM Ventil.WZ dupe: dont process
2015.07.01 23:35:59.259 0: HMLAN_Parse: hmlan1 R:R4B84CFFC stat:0008 t:00000000 d:FF r:7FFF     m:9B A112 1ACE1F 1CE9F5 
2015.07.01 23:35:59.261 0: HMLAN_Parse: hmlan1 no ACK from 1CE9F5
2015.07.01 23:35:59.300 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:00122CA5 d:FF r:FFD3     m:9B A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:35:59.421 0: HMLAN_Parse: hmlan1 R:R4B8C3CDC stat:0008 t:00000000 d:FF r:7FFF     m:9B A258 B4B4B4 1CE9F5 00FD
2015.07.01 23:35:59.423 0: HMLAN_Parse: hmlan1 no ACK from 1CE9F5
2015.07.01 23:35:59.441 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:004F3BB6 d:FF r:FFCF     m:9B 8202 1CE9F5 B4B4B4 0101C60032
2015.07.01 23:35:59.475 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:00122D29 d:FF r:FFBC     m:9B 8202 1CE9F5 B4B4B4 0101C60032
2015.07.01 23:35:59.491 4: CUL_HM Ventil.WZ dupe: dont process

martinp876 · 04 Juli 2015, 08:12:45

sollte im nächsten Update korrigiert sein

frank · 06 Juli 2015, 10:23:38

ein erster test sieht vielversprechend aus, danke.

Code Auswählen

2015.07.06 09:50:25.453 0: HMLAN_Send:  hmlan1 S:+1CE9F5,00,00,00
2015.07.06 09:50:25.456 0: HMLAN_Send:  hmlan1 S:S625838F9 stat:  00 t:00000000 d:01 r:625838F9 m:CD A258 B4B4B4 1CE9F5 03FD
2015.07.06 09:50:25.496 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:002D8F13 d:FF r:FFCD     m:CD A258 B4B4B4 1CE9F5 03FD
2015.07.06 09:50:25.647 0: HMLAN_Parse: hmlan1 R:E1CE9F5   stat:0000 t:016F3E7B d:FF r:FFCC     m:CD 8202 1CE9F5 B4B4B4 0101C60035
2015.07.06 09:50:25.808 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:002D8F96 d:FF r:FFBF     m:CD 8202 1CE9F5 B4B4B4 0101C60035
2015.07.06 09:50:25.824 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:002D8F96 d:FF r:FFBF     m:CD 8202 1CE9F5 B4B4B4 0101C60035
2015.07.06 09:50:25.839 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:002D8FB4 d:FF r:FFCD     m:CD A258 B4B4B4 1CE9F5 03FD
2015.07.06 09:50:25.880 0: HMLAN_Parse: hmusb1 R:EB4B4B4   stat:0000 t:002D907C d:FF r:FFCC     m:CD A258 B4B4B4 1CE9F5 03FD
2015.07.06 09:50:26.026 0: HMLAN_Parse: hmlan1 R:R625838F9 stat:0008 t:00000000 d:FF r:7FFF     m:CD A258 B4B4B4 1CE9F5 03FD
2015.07.06 09:50:26.028 0: HMLAN_Parse: hmlan1 no ACK from 1CE9F5

seltsam finde ich noch, dass seit einiger zeit "zu viele" logzeilen produziert werden. eventuell keine duplikate mehr verworfen werden. diese beiden messages sehen für mich eigentlich identisch aus:

Code Auswählen

2015.07.06 09:50:25.808 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:002D8F96 d:FF r:FFBF     m:CD 8202 1CE9F5 B4B4B4 0101C60035
2015.07.06 09:50:25.824 0: HMLAN_Parse: hmusb1 R:E1CE9F5   stat:0000 t:002D8F96 d:FF r:FFBF     m:CD 8202 1CE9F5 B4B4B4 0101C60035

vom gefühl her würde ich behaupten, das passiert mehr beim hmusb, obwohl doch hmlan und hmusb gleich behandelt werden.

gruss frank

martinp876 · 06 Juli 2015, 20:34:18

Fhem ist ok. Das sind gemeldete messages des io. Kann man eindeutig am io timestamp sehen.
Das problem liegt im usbdevice und dessen fw
Moeglich waere, dass das sendedevice wiederholt, ist aber hier nicht der fall

frank · 07 Juli 2015, 15:19:22

Zitat von: martinp876 am 06 Juli 2015, 20:34:18
Fhem ist ok. Das sind gemeldete messages des io. Kann man eindeutig am io timestamp sehen.
Das problem liegt im usbdevice und dessen fw
Moeglich waere, dass das sendedevice wiederholt, ist aber hier nicht der fall

ok.
der hmusb ist schon immer der selbe, auch seine fw. aber hmland habe ich in letzter zeit mehrmals aktualisiert. ~~vielleicht hat sich hier etwas diesbezüglich geändert.~~ mal schauen.

edit: das "problem" hat auch der hmlan. wenn fhem über hmusb sendet empfängt hmlan als monitor 3 mal die gesendete message. ich habe mal mit wireshark geschaut. da sieht man das die sendung von fhem 1x an das sendende io geht. und das monitor-io sendet dann jeweils 3x den empfang an fhem zurück. demnach funken wohl hmusb und hmlan von sich aus jede zu sendende message 3-fach.
seltsam. war das schon immer so?

Code Auswählen

2015.07.07 17:34:10.543 0: HMLAN_Send:  hmusb1 S:S692728BB stat:  00 t:00000000 d:01 r:692728BB m:4C A258 B2B2B2 1DFC2F 0000
2015.07.07 17:34:10.590 0: HMLAN_Parse: hmlan1 R:EB2B2B2   stat:0000 t:083E6949 d:FF r:FFD3     m:4C A258 B2B2B2 1DFC2F 0000
2015.07.07 17:34:10.721 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:083E69CC d:FF r:FFCD     m:4C 8202 1DFC2F B2B2B2 0101000031
2015.07.07 17:34:10.763 0: HMLAN_Parse: hmlan1 R:EB2B2B2   stat:0000 t:083E69E9 d:FF r:FFD3     m:4C A258 B2B2B2 1DFC2F 0000
2015.07.07 17:34:10.778 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:00606603 d:FF r:FFCA     m:4C 8202 1DFC2F B2B2B2 0101000031
2015.07.07 17:34:10.950 0: HMLAN_Parse: hmlan1 R:EB2B2B2   stat:0000 t:083E6AB1 d:FF r:FFD3     m:4C A258 B2B2B2 1DFC2F 0000
2015.07.07 17:34:11.130 0: HMLAN_Parse: hmusb1 R:R692728BB stat:0008 t:00000000 d:FF r:7FFF     m:4C A258 B2B2B2 1DFC2F 0000
2015.07.07 17:34:11.133 0: HMLAN_Parse: hmusb1 no ACK from 1DFC2F

martinp876 · 08 Juli 2015, 20:36:42

das ist immer so. 3mal da eine Antwort erwartet wird. Die Antwort kommt, HMLAN ignoriert die, weil sie nicht die eigene ID ist. Blöd implementiert, aber ich kann es nicht ändern.