HM-CC-RT-DN Missing ACK

wollebe · 01 Januar 2014, 19:28:07

Hallo Martin,

Danke für die schnelle Information

Gruß
Wolfgang

wollebe · 03 Januar 2014, 13:32:55

Hallo Martin,
großes Kompliment an dich und das gesamte Team.
Ein glücklicher Mensch schreibt dieses. Es funktioniert wieder alles.
Musste zwar alle RT´s neu peeren, aber was soll´s
Ich habe es mitbekommen, dass du (ihr) wohl viel Zeit verbracht habt.

FB ist 6.01
Fhem letztes update

Vielen Dank und viele Grüße
Wolfgang

DosiRocker · 31 Januar 2014, 10:00:56

Hallo Wollebe oder Martinp876,

ich habe ein ähnliches Problem.
Irgendwie fehlt mir der letzte Schritt, wie das Problem gelöst wurde?
Könnt ihr Infos dazu geben?

Gruß,
Martin
P.S: LogFile:

Code Auswählen

2014.01.31 10:04:21.194 4: CUL_send:  CUL_0As 0A 85 8002 F11034 21F454 00
2014.01.31 10:04:21.436 4: CUL_Parse: CUL_0 A 1A 85 A010 21F454 F11034 03012A22093D18030016073000640F050034 -48
2014.01.31 10:04:21.446 4: CUL_send:  CUL_0As 0A 85 8002 F11034 21F454 00
2014.01.31 10:04:21.694 4: CUL_Parse: CUL_0 A 1A 85 A010 21F454 F11034 03012A22093D18030016073000640F050034 -48
2014.01.31 10:04:21.704 4: CUL_send:  CUL_0As 0A 85 8002 F11034 21F454 00
2014.01.31 10:04:24.548 4: CUL_Parse: CUL_0 A 0F E9 8610 21BC53 000000 0AB0F08F161808 -70
2014.01.31 10:04:24.651 4: CUL_send:  CUL_0As 09 86 A112 F11034 21BC53 
2014.01.31 10:04:24.812 4: CUL_Parse: CUL_0 A 0A 86 8002 21BC53 F11034 0007 -70.5
2014.01.31 10:04:24.914 4: CUL_send:  CUL_0As 10 87 A001 F11034 21BC53 00040000000007
2014.01.31 10:04:25.090 4: CUL_Parse: CUL_0 A 1A 87 A010 21BC53 F11034 03012A22093D18030016073000640F050008 -70
2014.01.31 10:04:25.119 4: CUL_send:  CUL_0As 0A 87 8002 F11034 21BC53 00
2014.01.31 10:04:25.349 4: CUL_Parse: CUL_0 A 1A 87 A010 21BC53 F11034 03012A22093D18030016073000640F050008 -70
2014.01.31 10:04:25.609 4: CUL_Parse: CUL_0 A 1A 87 A010 21BC53 F11034 03012A22093D18030016073000640F050008 -70
2014.01.31 10:04:25.618 4: CUL_send:  CUL_0As 0A 87 8002 F11034 21BC53 00
2014.01.31 10:04:40.304 4: CUL_send:  CUL_0As 09 88 B112 F11034 21BC53 
2014.01.31 10:06:20.104 4: CUL_Parse: CUL_0 A 0F 60 8610 22E1B9 000000 0A9CCE0F10290B -68.5
2014.01.31 10:06:20.207 4: CUL_send:  CUL_0As 09 89 A112 F11034 22E1B9 
2014.01.31 10:06:20.365 4: CUL_Parse: CUL_0 A 0A 89 8002 22E1B9 F11034 000D -67.5
2014.01.31 10:06:20.467 4: CUL_send:  CUL_0As 10 8A A001 F11034 22E1B9 00040000000007
2014.01.31 10:06:20.642 4: CUL_Parse: CUL_0 A 1A 8A A010 22E1B9 F11034 03012A22093D18030016073000640F050007 -70.5
2014.01.31 10:06:20.662 4: CUL_send:  CUL_0As 0A 8A 8002 F11034 22E1B9 00
2014.01.31 10:06:20.899 4: CUL_Parse: CUL_0 A 1A 8A A010 22E1B9 F11034 03012A22093D18030016073000640F050012 -65
2014.01.31 10:06:20.908 4: CUL_send:  CUL_0As 0A 8A 8002 F11034 22E1B9 00
2014.01.31 10:06:21.157 4: CUL_Parse: CUL_0 A 1A 8A A010 22E1B9 F11034 03012A22093D18030016073000640F050013 -64.5
2014.01.31 10:06:21.166 4: CUL_send:  CUL_0As 0A 8A 8002 F11034 22E1B9 00
2014.01.31 10:06:30.628 4: CUL_Parse: CUL_0 A 0F 72 8610 21F454 000000 0A98C60E072C2D -51.5
2014.01.31 10:06:30.731 4: CUL_send:  CUL_0As 09 8B A112 F11034 21F454 
2014.01.31 10:06:30.891 4: CUL_Parse: CUL_0 A 0A 8B 8002 21F454 F11034 0030 -50
2014.01.31 10:06:30.993 4: CUL_send:  CUL_0As 10 8C A001 F11034 21F454 00040000000007
2014.01.31 10:06:31.167 4: CUL_Parse: CUL_0 A 1A 8C A010 21F454 F11034 03012A22093D18030016073000640F050035 -47.5
2014.01.31 10:06:31.183 4: CUL_send:  CUL_0As 0A 8C 8002 F11034 21F454 00
2014.01.31 10:06:31.425 4: CUL_Parse: CUL_0 A 1A 8C A010 21F454 F11034 03012A22093D18030016073000640F050035 -47.5
2014.01.31 10:06:31.434 4: CUL_send:  CUL_0As 0A 8C 8002 F11034 21F454 00
2014.01.31 10:06:31.683 4: CUL_Parse: CUL_0 A 1A 8C A010 21F454 F11034 03012A22093D18030016073000640F050034 -48
2014.01.31 10:06:31.692 4: CUL_send:  CUL_0As 0A 8C 8002 F11034 21F454 00
2014.01.31 10:06:36.252 4: CUL_Parse: CUL_0 A 0F D8 8610 22E0FA 000000 0A9CCE0F0E2B16 -63
2014.01.31 10:07:26.046 4: CUL_Parse: CUL_0 A 0F EA 8610 21BC53 000000 0AB0F08F161807 -70.5
2014.01.31 10:07:26.149 4: CUL_send:  CUL_0As 09 8D B112 F11034 21BC53 
2014.01.31 10:07:26.167 4: CUL_send:  CUL_0As 09 8E A112 F11034 21BC53

und noch ein list:

Code Auswählen

Internals:
   CUL_0_MSGCNT 4447
   CUL_0_RAWMSG A1A94A01021BC53F1103403012A22093D18030016073000640F050007
   CUL_0_RSSI -70.5
   CUL_0_TIME 2014-01-31 10:10:14
   DEF        21BC53
   IODev      CUL_0
   LASTInputDev CUL_0
   MSGCNT     4447
   NAME       CUL_HM_HM_CC_RT_DN_21BC53
   NR         535
   STATE      CMDs_pending
   TYPE       CUL_HM
   channel_01 CUL_HM_HM_CC_RT_DN_21BC53_Weather
   channel_02 CUL_HM_HM_CC_RT_DN_21BC53_Climate
   channel_03 CUL_HM_HM_CC_RT_DN_21BC53_WindowRec
   channel_04 CUL_HM_HM_CC_RT_DN_21BC53_Clima
   channel_05 CUL_HM_HM_CC_RT_DN_21BC53_ClimaTeam
   channel_06 CUL_HM_HM_CC_RT_DN_21BC53_remote
   lastMsg    No:94 - t:10 s:21BC53 d:F11034 03012A22093D18030016073000640F0500
   protCmdPend 21 CMDs pending
   protCondBurst off
   protLastRcv 2014-01-31 10:10:14
   protResnd  949 last_at:2014-01-31 10:10:15
   protSnd    4390 last_at:2014-01-31 10:10:14
   protState  CMDs_pending
   rssi_at_CUL_0 avg:-69.74 min:-79.5 max:-66.5 lst:-70.5 cnt:4447 
   Readings:
     2014-01-29 17:53:53   Activity        alive
     2014-01-31 10:10:13   CommandAccepted yes
     2014-01-27 22:53:15   PairedTo        0xF11034
     2014-01-27 22:53:15   R-backOnTime    10 s
     2014-01-31 10:04:40   R-btnLock       unlock 
     2014-01-31 10:04:40   R-burstRx       set_on 
     2014-01-31 10:04:40   R-cyclicInfoMsg on 
     2014-01-31 10:04:40   R-cyclicInfoMsgDis 0 
     2014-01-31 10:04:40   R-globalBtnLock off 
     2014-01-31 10:04:40   R-localResDis   off 
     2014-01-27 22:53:15   R-lowBatLimitRT 2.1 V
     2014-01-31 10:04:40   R-modusBtnLock  off 
     2014-01-31 10:04:40   R-pairCentral   0xF11034 
     2014-01-27 22:53:15   RegL_00:        01:00 02:01 09:01 0A:F1 0B:10 0C:34 0E:0A 0F:00  11:00 12:15 16:00 18:00 19:00 1A:00 00:00
     2014-01-31 10:10:13   actuator        22 %
     2014-01-31 10:10:13   battery         ok
     2014-01-31 10:10:13   batteryLevel    3 V
     2014-01-31 10:10:13   desired-temp    22
     2014-01-31 10:10:13   measured-temp   24.0
     2014-01-27 20:43:55   powerOn         -
     2014-01-27 20:43:55   recentStateType info
     2014-01-31 10:10:15   state           CMDs_pending
     2014-01-30 16:59:53   time-request    -
     Regl_07::
       VAL        
   cmdStack:
     ++A001F1103421BC5300040000000007
     ++A001F1103421BC530503
     ++A001F1103421BC5305040000000001
     ++A001F1103421BC530603
     ++A001F1103421BC5306040000000001
     ++A011F1103421BC5386042B
     ++A001F1103421BC5300050000000000
     ++A001F1103421BC5300080101
     ++A001F1103421BC530006
     ++A001F1103421BC5300040000000000
     ++A001F1103421BC530103
     ++A001F1103421BC5301040000000001
     ++A001F1103421BC530203
     ++A001F1103421BC5302040000000001
     ++A001F1103421BC530303
     ++A001F1103421BC5303040000000001
     ++A001F1103421BC5304040000000001
     ++A001F1103421BC5300040000000007
     ++A001F1103421BC530503
     ++A001F1103421BC5305040000000001
     ++A001F1103421BC530603
     ++A001F1103421BC5306040000000001
   Helper:
     cSnd       01F1103421BC5300040000000007
     mId        0095
     rxType     140
     Io:
       nextSend   1391159414.46629
     Prt:
       awake      0
       bErr       0
       sProc      2
       wakeup     0
       wuReSent   2
     Q:
       qReqConf   
       qReqStat   
     Role:
       dev        1
     Rpt:
       IO         CUL_0
       flg        A
       ts         1391159414.37235
       ack:
         HASH(0x16122b0)
         948002F1103421BC5300
     Rssi:
       At_cul_0:
         avg        -69.745783674387
         cnt        4447
         lst        -70.5
         max        -66.5
         min        -79.5
     Shregw:
       07         04
     Shadowreg:
       RegL_00:   01:01 02:01 09:01 0A:F1 0B:10 0C:34 0E:0A 0F:00  11:00 12:15 16:00 18:00 19:00 1A:00 00:00
       RegL_07:   
Attributes:
   IODev      CUL_0
   actCycle   000:10
   actStatus  alive
   autoReadReg 4_reqStatus
   burstAccess 1_auto
   expert     2_full
   firmware   1.0
   model      HM-CC-RT-DN
   peerIDs    
   room       CUL_HM
   serialNr   KEQ0576965
   subType    thermostat
   webCmd     :burstXmit

und ein List von Channel 4

Code Auswählen

Internals:
   CUL_0_MSGCNT 954
   CUL_0_RAWMSG A0FEB861021BC530000000AB0F08F161808
   CUL_0_RSSI -70
   CUL_0_TIME 2014-01-31 10:10:13
   DEF        21BC5304
   LASTInputDev CUL_0
   MSGCNT     954
   NAME       CUL_HM_HM_CC_RT_DN_21BC53_Clima
   NR         540
   STATE      T: 24.0 desired: 22 valve: 22 %
   TYPE       CUL_HM
   chanNo     04
   device     CUL_HM_HM_CC_RT_DN_21BC53
   Readings:
     2014-01-26 13:45:07   H               0
     2014-01-29 18:00:27   R-sign          off 
     2014-01-31 10:10:13   RegL_07:         01:2A 02:22 03:09 04:3D 05:18 06:03 07:00 08:16 09:07 0A:30 0B:00 0C:64 0D:0F 0E:05 0F:00
     2014-01-26 13:57:03   T               0
     2014-01-31 10:10:13   ValvePosition   22 %
     2014-01-31 10:10:13   desired-temp    22
     2014-01-31 10:10:13   measured-temp   24.0
     2014-01-31 10:10:13   mode            auto
     2014-01-31 10:10:13   motorErr        communicationERR
     2014-01-31 10:10:13   state           T: 24.0 desired: 22 valve: 22 %
   Helper:
     getCfgListNo 
     Prt:
       wakeup     1
     Role:
       chn        1
     Shregr:
       07         00
     Shadowreg:
Attributes:
   model      HM-CC-RT-DN
   peerIDs

martinp876 · 31 Januar 2014, 10:39:04

Hallo Martin,

das ist ein getConfig, das schief geht?
Sieht aus, als ob der RT die ACKs nicht versteht - evtl kommen die zu früh. (Gerade war es anderen Devices zu spät...)

Du konntest einmal im HMLAN die Bremse wieder einbauen, dann ein getConfig loggen

00_HMLAN etwa Zeile 689

Code Auswählen

    my $tn = gettimeofday();
    
    if($typ ne "02" &&
       $modules{CUL_HM}{defptr}{$dst} && 
       $modules{CUL_HM}{defptr}{$dst}{helper}{io} &&
       $modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend}
       ){
      my $DevDelay = $modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend} - $tn;

ersetzen (eine Zeile löschen) mit

Code Auswählen

    my $tn = gettimeofday();
    
    if($modules{CUL_HM}{defptr}{$dst} && 
       $modules{CUL_HM}{defptr}{$dst}{helper}{io} &&
       $modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend}
       ){
      my $DevDelay = $modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend} - $tn;

einfach ein reload 00_HMLAN und dann ein getConfig
ggf müssen wir einen verküzten Delay einbauen.

Du hast eine CUL, keine CUNO über LAN - oder? Die geht eh nicht, schlechtes Timing
Gruss Martn

DosiRocker · 31 Januar 2014, 11:14:00

Zitat von: martinp876 am 31 Januar 2014, 10:39:04

Du hast eine CUL, keine CUNO über LAN - oder? Die geht eh nicht, schlechtes Timing
Gruss Martn

Ich habe einen CUL, den Cuno nutze ich für SlowRF

Deinen Vorschlag probiere ich aus. Vielen, Vielen Dank, war schon ziemlich frustriert, da seit Tagen/2 Wochen alle meine restlichen Versuche fehlgeschlagen sind.
Gruß,
Martin

martinp876 · 31 Januar 2014, 11:41:26

sorry, wo habe ich meinen Kopf. Du hast CUL - also

00_CUL line 687

Code Auswählen

  if($id &&
     $mTy ne "02" &&
     $modules{CUL_HM}{defptr}{$id} && 
     $modules{CUL_HM}{defptr}{$id}{helper}{io} && 
     $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend}) {
    my $dDly = $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend} - $now;

Code Auswählen

  if($id &&
     $modules{CUL_HM}{defptr}{$id} && 
     $modules{CUL_HM}{defptr}{$id}{helper}{io} && 
     $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend}) {
    my $dDly = $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend} - $now;

Gruss Martin

DosiRocker · 31 Januar 2014, 12:25:42

Zitat von: martinp876 am 31 Januar 2014, 11:41:26
sorry, wo habe ich meinen Kopf. Du hast CUL - also

00_CUL line 687

Gruss Martin

Hallo Martin,

oh deswegen funktioniert es nicht

dann mache ich es wieder rückgängig

Dankeschön,
Martin

DosiRocker · 31 Januar 2014, 13:06:11

Hallo Martin,

das hat mein Problem wahrschenlich behoben. Vielen Vielen lieben Dank für deine Hilfe

Ich beobachte und hoffe, daß es jetzt geht

Martin
P.S: Muß ich dies jetzt bei jedem Update wieder ändern?

martinp876 · 31 Januar 2014, 13:49:58

ich habe jetzt ein Problem: Einige devs wollen sofort ein ACK, der RT erst spätern. Ich kenne den Unterscheider noch nicht.

wenndu es einmal austesten könntest, wie lange man warten darf?

Code Auswählen

  if($id &&
     $modules{CUL_HM}{defptr}{$id} && 
     $modules{CUL_HM}{defptr}{$id}{helper}{io} && 
     $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend}) {
    my $dDly = $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend} - $now;
    $dDly -= 0.08 if ($mTy eq "02");
    if ($dDly > 0.01){# wait less then 10 ms will not work
      $dDly = 0.1 if($dDly > 0.1);
      Log3 $hash->{NAME}, 5, "CUL $id dly:".int($dDly*1000)."ms";
      select(undef, undef, undef, $dDly);
    }
  }

die 0.08, die abgezogen werden sollten möglicht klein sein.
0.00 wäre der ursprüngliche Zustand, funktoniert also nicht.
0.10 ist der max-wert (kein warten) - funktioniert bei remotes nicht.
also könnte man werte um die 0.03 bis 0.07 probieren, je größer um so besser. Ich werde einmal mit den remotes testen, in die gegenrichtung.

p.s. code neu!

kvo1 · 31 Januar 2014, 14:20:11

hallo Martin,

ich habe in Zeile 687 (habe einen CUL)
$mTy ne "02" &&

entfernt und schon geht es wieder !

sieht also so aus !

if($id &&
$modules{CUL_HM}{defptr}{$id} &&
$modules{CUL_HM}{defptr}{$id}{helper}{io} &&
$modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend}) {
my $dDly = $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend} - $now;
if ($dDly > 0.01){# wait less then 10 ms will not work
$dDly = 0.1 if($dDly > 0.1);
Log3 $hash->{NAME}, 5, "CUL $id dly:".int($dDly*1000)."ms";
select(undef, undef, undef, $dDly);
}

martinp876 · 31 Januar 2014, 14:27:45

Hi Karl,

kannst du auch den oben beschriebenen Test machen?
So können wir es nicht lassen - die Zeile war schlieslich nitwendig

DosiRocker · 31 Januar 2014, 14:36:00

Zitat von: martinp876 am 31 Januar 2014, 13:49:58
ich habe jetzt ein Problem: Einige devs wollen sofort ein ACK, der RT erst spätern. Ich kenne den Unterscheider noch nicht.

wenndu es einmal austesten könntest, wie lange man warten darf?
Code Auswählen Erweitern
if($id && $modules{CUL_HM}{defptr}{$id} && $modules{CUL_HM}{defptr}{$id}{helper}{io} && $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend}) { my $dDly = $modules{CUL_HM}{defptr}{$id}{helper}{io}{nextSend} - $now; $dDly -= 0.08 if ($mTy eq "02"); if ($dDly > 0.01){# wait less then 10 ms will not work $dDly = 0.1 if($dDly > 0.1); Log3 $hash->{NAME}, 5, "CUL $id dly:".int($dDly*1000)."ms"; select(undef, undef, undef, $dDly); } }

die 0.08, die abgezogen werden sollten möglicht klein sein.
0.00 wäre der ursprüngliche Zustand, funktoniert also nicht.
0.10 ist der max-wert (kein warten) - funktioniert bei remotes nicht.
also könnte man werte um die 0.03 bis 0.07 probieren, je größer um so besser. Ich werde einmal mit den remotes testen, in die gegenrichtung.

p.s. code neu!

Hallo Martin,
mir ist nicht so ganz klar wie der Test aussieht (kann nicht programmieren

)
Die obigen Zeilen einfügen und dann? getConfig auslösen? Wird irgendein LOG geschrieben?
Gruß,
Martin

kvo1 · 31 Januar 2014, 14:38:19

noch ein Hinweis meinerseits

$dDly -= 0.08 if ($mTy eq "02");

fehlt in meiner 00_CUL.pm

(s.o.)

klaus

DosiRocker · 31 Januar 2014, 15:39:03

$dDly -= 0.08 if ($mTy eq "02");

Ich habe jetzt mit dieser Zeile etwas rumgespielt:
Bei Werten 0.03 -0.05 funktioniert ein getconfig, bei Werten größer 0.05 hat es nicht funktioniert.
Ich habe es mit 2 Thermostaten und mit mehrmaligen Test probiert. Bei 0.05 wird es langsam unsicher.

Ich hoffe es war nicht kompletter Quatsch und hilft euch?!?

Gruß,
Martin

martinp876 · 31 Januar 2014, 15:48:49

Hi,
@klaus
klar - das wäre auch eine neue Zeile zur Korrektur

@martin & klaus
damit der teste infacher wird im Anhang eine Datei - man kann das ackDelay einstellen mit
attr <CUL> ackDly 0.8

Der wert ist hier "positiv" - will sagen
Probleme hattet ihr bei "0.0"
Funitioniert hat es bei "0.1" (100ms)
Aufgabe ist, auszumessen wann es 'noch' geht. Stufen sind 0.01 0.02,... 0.09.

Kriterium ist, dass die Kommandos (getConfig oder ähnlich) abgearbeitet werden ohne protokoll-fehler. Hierzu kann man HMInfo
set hm protoEvents short

oder
set hm protoEvents -f <name> short

Gruss Martin

p.s. sehe gerade die Antwort - ja, die Werte hören sich sinnvoll an