Hallo,
ich habe mehrere HM-CC-RT-DN im Einsatz
System : FEHM auf Raspberry mit letzten update
RT: V1.2
Seit gestern (Samstag früh) nehmen die RT keine Befehle vom FHEM mehr an.
Die "decalcTime" habe ich auf Samstag 03.00 gesetzt. (Kann man das auch deaktivieren?)
Samstag früh nun folgender Effekt:
ALLE RT's haben eine blinkende Antenne
Nachdem ich alle neu gepaart habe, scheinbar erfolgreich, wenn ich auf die Antenne schaue,
ist im FHEM immer noch ein Dauer "Pending" und "CMDs-Processing" zu beobachten.
Einen RT habe ich komplett resetet und neu gepairt, mit offensichtlichem Erfolg.
Aber auch hier ein Dauer Pending und Processing.
Im Logfile habe ich folgenden Eintrag gefunden:
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
(weiß nicht ob das damit zusammenhängt, ist aber ein neuer Effekt)
Den Raspberry habe ich auch schon neu gestartet.
Leider ohne Erlog.
Im Fhem werden händische Veränderungen am RT angezeigt.
Die Temperatur und Valve wird angezeigt.
Kontakt scheint zu bestehen. (?!)
Ich hoffe es gibt jemanden der eine Idee hat. :-)
*grübel* sowas ähnliches wurde doch heute schonmal beschrieben:
http://forum.fhem.de/index.php/topic,19612.0.html
Sehr eigenartig. Bei mir funktionieren die RT aber auch nach dem heutigen fhem-Update.
Zitat von: betateilchen am 02 Februar 2014, 10:33:45
*grübel* sowas ähnliches wurde doch heute schonmal beschrieben:
http://forum.fhem.de/index.php/topic,19612.0.html
Sehr eigenartig. Bei mir funktionieren die RT aber auch nach dem heutigen fhem-Update.
Das ist ein nur ähnliches Verhalten.
Im anderen Fall habe ich das auch beobachtet, aber die über das GUI eingegebene Temperatur wird manchmal sehr zeitversetzt übermittelt.
Ist mit dem merkwürdigen Verhalten bei mir, aus meiner Sicht, erst einmal nicht gleich.
... heute morgen habe ich ein weiters Update der FHEM eingespielt.
Hier gab's auch ein Update für die "/FHEM/01_FHEMWEB.pm"
Leider ist der Effekt mit dem Dauer Processing und Pending nicht behoben.
nun - wie immer hätte ich gerne die Messages im rohformat von den Aktionen.
Ausserdem ein List des Device.
Könnte sein, dass irgendwelche Kommandos im Stack hängen, die nicht verarbeitet werden. Das konnte dann dazu führen, dass x Versuche mit dieser Message gemacht werden (attribut gesteuert). Dann kommt die nächste.... Jeder Versuch dauert 2,5min... das dauert insgesamt mit unter sehr lange.
=> ein List, damit ich sehen kann, welche messages anstehen.
=> rohmessages, damit ich sehen kann, was das Device antwortet
++> clear protoEvents löscht den commandstack - dann könnte evtl der Mist gelöscht werden
Gruss Martin
moin,
ich bin scheinbar wieder Herr über die Stellantriebe ;-) ....
Ich habe verschiedenste Infoschnipsel aus dem Forum zusammengetragen und konnte mir damit helfen.
- Antenne im Display der RT's blinkte.
- dann neu gepairt (zuerst mit hmPairForSec und boost Taste)
- später hab ich festgestellt, dass im FHEM Register der RT's die Seriennummer der RT's verschwunden war.
ein "set xxxx pair" hat mir dann den Eintrag wieder zurück gebracht
- die im Stack aufgelaufenen Befehle (teilweise >30) habe ich dann jeweils mit "set xxxx burstXmit"
an die RT's geschickt. Teilweise musste ich das mehrfach wieder holen.
- nachdem dann alle RT's wieder auf CMDs_done standen konnte ich wieder ordentlich mit den RT's "reden"
Wie es zu diesem Umstand kam, kann ich nicht genau sagen.
Ich vermute das hängt mit dem automatisierten "deCalc" zusammen (Vermutung)
Warum das aber bewirken soll, dass die Seriennummer der RT'S im FHEM Register gelöscht wird, weiß ich nicht.
Zumindest war am Samstag morgen (nach dem R-decalcTime um 03.00) die Verbindung unterbrochen, angezeigt durch die blinkende Antenne.
Vielleicht hilft dieser Eintrag einem weiteren User bei einem ähnlichen Fehler.
? kann man R-decalcTime oder R-decalcWeekday deaktivieren ?
Das war jetzt die 2.Woche wo ich ein Problem mit dieser Funktion habe.
Beim ersten Problem war es aber so, dass einige Stellantriebe gar nicht mehr zur Ruhe kamen und ständig auf und zu gingen.
Hier half nur Abbauen, abkühlen lassen und neu montieren.
Danach ging auch der Adaptieren wieder.
Hallo,
ich habe das gleiche Problem: Die HM-CC-RT-DN nehmen keine Kommandos mehr über die Weboberfläche an.
Die Temperaturen/Ventilstellungen werden problemlos empfangen und geloggt.
Wenn ich die Temperatur setzte mit:
2014.02.02 19:44:29 2: CUL_HM set HK2_ClimRT_tr desired-temp 18.5
Steht im Log:
2014-02-02_19:44:46 HK2 battery: ok
2014-02-02_19:44:46 HK2 batteryLevel: 3 V
2014-02-02_19:44:46 HK2 measured-temp: 21.5
2014-02-02_19:44:46 HK2 desired-temp: 19
2014-02-02_19:47:22 HK2 battery: ok
2014-02-02_19:47:22 HK2 batteryLevel: 3 V
2014-02-02_19:47:22 HK2 measured-temp: 21.4
2014-02-02_19:47:22 HK2 desired-temp: 19
2014-02-02_19:47:22 HK2 actuator: 0 %
2014-02-02_19:49:44 HK2 battery: ok
2014-02-02_19:49:44 HK2 batteryLevel: 3 V
2014-02-02_19:49:44 HK2 measured-temp: 21.4
2014-02-02_19:49:44 HK2 desired-temp: 19
2014-02-02_19:49:44 HK2 actuator: 0 %
2014-02-02_19:51:51 HK2 battery: ok
2014-02-02_19:51:51 HK2 batteryLevel: 3 V
2014-02-02_19:51:51 HK2 measured-temp: 21.4
2014-02-02_19:51:51 HK2 desired-temp: 19
2014-02-02_19:51:51 HK2 actuator: 0 %
2014-02-02_19:51:54 HK2 ResndFail
2014-02-02_19:51:54 HK2 MISSING ACK
Im der Weboberfläche steht zum HK2:
HMLAN1_MSGCNT 5
HMLAN1_RAWMSG E21DC2E,0000,2BAE5640,FF,FFB3,43861021DC2E0000000A98D60F0014
HMLAN1_RSSI -77
HMLAN1_TIME 2014-02-02 19:54:48
IODev HMLAN1
LASTInputDev HMLAN1
MSGCNT 5
NAME HK2
NR 109
STATE MISSING ACK
TYPE CUL_HM
channel_01 HK2_Weather
channel_02 HK2_Climate
channel_03 HK2_WindowRec
channel_04 HK2_ClimRT_tr
channel_05 HK2_ClimaTeam
channel_06 HK2_remote
lastMsg No:43 - t:10 s:21DC2E d:000000 0A98D60F0014
protCmdDel 2
protLastRcv 2014-02-02 19:54:48
protResnd 3 last_at:2014-02-02 19:49:48
protResndFail 1 last_at:2014-02-02 19:51:54
protSnd 4 last_at:2014-02-02 19:51:51
protState CMDs_done_Errors:1
rssi_at_HMLAN1 avg:-76.59 min:-78 max:-76 lst:-77 cnt:5
Mir scheint das seit dem letzten Update so zu sein.
Nachtrag: Ein getConfig geht.
Herr 3x
So, ich habe mal die eine Version von 10_CUL_HM.pm vom 25.01.14 aus den Backup geholt. Damit klappt die Temperaturänderung problemlos. Seitdem scheint der Bug reingekommen zu sein.
Herr 3x
Hallo Herr 3x,
welche Version des 10_CUL_HM.pm wäre denn das? Ich habe nämlich auch ein sehr ähnliches Problem...
Moin,
wie im anderen Thread schon beschrieben, mühe ich mich ebenfalls mit einem HM-CC-RT-DN, der sich zwar pairen und auslesen lässt, den jegliche Änderungen aber kalt lassen.
Hier mal ausführliche Logs meiner Sessions mit dem HM-CC-RT-DN. Ich habe das Geräte-Logfile incl. Raw-Messages, einen Auszug aus der Log-Datei sowie den Konsolen-Mitschnitt der Debug-Ausgabe von hmland beigefügt. Wenn ich noch weitere Daten beisteuern kann, sagt Bescheid - ich bin erst seit dem Wochenende bei fhem ;-)
Ciao,
Sebi
Internals:
DEF <no definition>
LASTInputDev hmusb
MSGCNT 4
NAME global
NR 1
STATE <no definition>
TYPE Global
currentlogfile ./log/fhem-2014-02.log
hmusb_MSGCNT 4
hmusb_RAWMSG RF546ED01,0201,00471DBE,FF,FFDC,0E800222BBF542424200
hmusb_RSSI -36
hmusb_TIME 2014-02-03 02:05:02
logfile ./log/fhem-%Y-%m.log
Attributes:
autoload_undefined_devices 1
configfile fhem.cfg
logfile ./log/fhem-%Y-%m.log
modpath .
motd SecurityCheck:
WEB,WEBphone,WEBtablet has no basicAuth attribute.
telnetPort has no password/globalpassword attribute.
Running with root privileges.
Restart fhem for a new check if the problem is fixed,
or set the global attribute motd to none to supress this message.
statefile ./log/fhem.save
updateInBackground 1
userattr devStateIcon devStateStyle icon sortby webCmd
verbose 3
version $Id: fhem.pl 4769 2014-01-29 08:14:58Z rudolfkoenig $
wegen der RT probleme -
a) welche Versionen?
b) IO device ist HM oder CUL?
c) kann einer rohmessages loggen?
Danke
p.s.
@sebixvi
dein HMUSB ist in overload - zu viele burst-versuche.
Hallo Martin,
das mit dem Overload hatte ich gesehen, aber ich dachte, das war heute Nacht um 2:00 Uhr?! Sollte er sich nicht danach wieder beruhigt haben, oder muss ich den Stick abziehen und neu starten, um das zurückzusetzen?
RT-Version ist bei mir 1.2, IO-Device ein HM-CFG-USB per hmland.
Sebi
Hi Sebi,
ja, beruhigt sich nach spätestens 1h, wenn man ruhe gibt:
02:10:52 1: HMLAN_Parse: hmusb new condition Warning-HighLoad
02:40:52 1: HMLAN_Parse: hmusb new condition ok
Du arbeitest mit burstXmit - ist vorher das Register burstRx auch gesetzt worden?
kannst du einmal roh-mesages mirschneiden?
Gruss Martin
Hallo Martin,
ich werde heute Abend mal die nötigen Einstellungen vornehmen, um Rohdaten zu erhalten und entsprechende Logs posten.
Derzeit habe ich das Gefühl, als wenn der RT dem CFG-USB noch zu neu ist. In einem anderen Thread habe ich gelesen, dass die ACKs vom CFG-USB selbst generiert werden. Da das Firmwareupdate einwandfrei geklappt hat und dabei eine Menge Daten übertragen werden, dürfte die Funkstrecke in Ordnung sein. Auslesen und pairen klappt auch, nur beim Schreiben von Daten gibt´s Probleme, das aber nicht nur mit fhem, sondern auch mit der Homematic-Software. Und die sollte es ja eigenltich können...
Sebi
Zitat von: themaxx32000 am 03 Februar 2014, 10:53:48
Hallo Herr 3x,
welche Version des 10_CUL_HM.pm wäre denn das? Ich habe nämlich auch ein sehr ähnliches Problem...
Ich glaube, dass das die aus dem fhem 5.5 tar ist. Ich prüfe das nachher mal.
Herr 3x
ok Sebi - guter Hinweis, dass auch HM-SW probleme hat.
Der Update begeht voraussichtlich über eine andere Frequenz... nicht, dass dein HMUSB ein anderes Problem mit HW hat...
Was sagen die RSSI Werte?
Zitat von: Herr 3x am 03 Februar 2014, 17:35:59
Ich glaube, dass das die aus dem fhem 5.5 tar ist. Ich prüfe das nachher mal.
Es war die Version aus dem Tar-File ohne Updates. Leider hatte die auch die Fehler, allerdings nicht sofort.
Scheint an etwas anderem zu liegen.
Herr 3x
Die version ist wohl uralt (nach meiner Zeitrechnung).
Wenn du noch einmal probleme hast, logge die rohmessages
Zitat von: martinp876 am 04 Februar 2014, 11:58:04
Die version ist wohl uralt (nach meiner Zeitrechnung).
Wenn du noch einmal probleme hast, logge die rohmessages
Hallo Martin,
die Logs stehen hier: http://forum.fhem.de/index.php/topic,18091.msg133171/topicseen.html#msg133171 (http://forum.fhem.de/index.php/topic,18091.msg133171/topicseen.html#msg133171)
Danke
Herr 3x
Hi Herr 3x
das ist alles zu langsam.
21:01:29.416 Parse: HMLAN1 R:E234FA4 stat:0000 t:005590C5 d:FF r:FFBB m:A9 8610 234FA4 000000 0A80A40F0018
21:01:29.418 set HK8_remote getConfig
21:01:29.627 Send: HMLAN1 S:SF957608D stat: 00 t:00000000 d:01 r:F957608D m:45 A112 121212 234FA4
da sind über 200ms vom Empfangen zum senden - immer.
warum kommt ein "set" immer genau nach der Info vom RT? Hast du da etwas eingeschaltet?
du hast nicht HMprotocolEvents eingeschaltet?
Gruss Martin
mmmh: hab das gleiche Problem (heute frisch ge"updated") - das Problem gibt es aber schon länger
2014.02.06 20:21:25 2: CUL_HM set DIM_Ampel regSet shOnLevel 80% MOT_Nord_chn:01
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
Use of uninitialized value $c[0] in regexp compilation at ./FHEM/01_FHEMWEB.pm line 1223.
...
Kommt immer wieder - nach den unterschiedlichsten Befehlen.
Allerdings scheint es die Funktionalität nicht zu stören - macht den nur das logfile grooooß.
hi nine42,
ist ein bug (unsauber) in fhemweb.
mache ein
define hm HMInfo
set hm param -cd webCmd
Prüfe, ob "leere" kommandos dabei sind - so wie
attr x webCmd cmd1:
und dann leite es an den entsprechenden Threat weiter - ich denke Rudi wird sich kümmern.
Sollte das webCmd automatisch angelegt worden sein gibt mir die Details
Gruss Martin
Hallo,
yepp - das scheint es zu sein:
set hm param -cd webCmd
entity : webCmd |
BTN_Bildwand : getConfig
BTN_Jalousien : getConfig
BTN_K : getConfig
BTN_Master : getConfig
BTN_Werkstatt : getConfig
DIM_Ampel : on:off:20:40:60:80
DIM_Bildwand : on:off:20:40:60:80
DIM_Bildwand_2 : toggle:on:off:up:down:statusRequest
DIM_Bildwand_3 : toggle:on:off:up:down:statusRequest
DIM_Bildwand_DEV : getConfig
JAL_Arbeitszimmer : open:close:stop:cross
JAL_Esszimmer : open:close:stop:cross
JAL_Kueche : open:close:stop:cross
JAL_Wohnzimmer_L : open:close:stop:cross
JAL_Wohnzimmer_R : open:close:stop:cross
SW_Aussendose : on:off: : : :
SW_Haustuerlampe : on:off: : : : :
SW_Jonas : on:off: : : : :
SW_K : on:off: : : : :
SW_Speicher : on:off: : : : :
SW_Steckdose : on:off: : : : :
SW_Testlampe : on:off: : : :
SW_Werkstatt : on:off: : : : :
VIR_Master : press short:press long
... ich hab die Schalter (SW_.*) und die Dimmer (DIM_.*) in die gleiche Gruppe getan (beide schalten Lichter), damit sie "schön formatiert", d.h. alphabetisch eingeordnet, spaltenweise untereinander erscheinen.
Dafür habe ich "dummy"(also leere)-Schaltwerte bei den Schaltern verwendet - denn die müssen ja nicht dimmen.
also:
attr DIM_Ampel webCmd on:off:20:40:60:80
attr SW_Aussendose webCmd on:off: : : :
attr DIM_Bildwand webCmd on:off:20:40:60:80
attr SW_Steckdose webCmd on:off: : : : :
attr SW_Haustuerlampe webCmd on:off: : : : :
attr SW_Jonas webCmd on:off: : : : :
...
attr SW_Testlampe webCmd on:off: : : :
... das bedeutet wohl - Entwarnung - die webCmds sind nicht automatisch generiert worden, sondern meinem kranken Hirn entsprungen;-)
Das ganze sieht dann ordentlich formatiert so wie im Anhang aus.
... und: sorry - ich weiß nicht so recht, was der "entsprechende Thread" ist, daher schreibe ich es hier
... und: vielen Dank!
du kannst natürlich ein '-' oder 'nix' reinschreiben - nur draufclicken darfst du dann nicht.
oder bei Rudi einkippen
Hallo Martin,
hab das Problem in http://forum.fhem.de/index.php/topic,19920.0.html nochmals kurz dargestellt.
ich hoffe damit ist es "bei Rudi eingekippt"
(oder gibt es da einen anderen Weg?)
Grüße, Peter
das müsste klappen
Hallo Martin,
ich war ein paar Tage unterwegs, darum erst jetzt die Rückmeldung
Zitat von: martinp876 am 05 Februar 2014, 10:15:33
da sind über 200ms vom Empfangen zum senden - immer.
warum kommt ein "set" immer genau nach der Info vom RT? Hast du da etwas eingeschaltet?
du hast nicht HMprotocolEvents eingeschaltet?
Das set kann ich mir nicht erklären.
Die HMprotocolEvents habe ich mal eingeschaltet:
2014.02.08 23:31:34.045 5: HMLAN_Parse: HMLAN1 R:E221F50 stat:0000 t:000A3D59 d:FF r:FFCA m:91 8610 221F50 000000 0A88B10F0018
2014.02.08 23:31:34.046 5: HMLAN1 dispatch A0F918610221F500000000A88B10F0018::-54:HMLAN1
2014.02.08 23:31:34.046 4: RCV L:0F N:91 F:86 CMD:10 SRC:HK3 DST:broadcast 0A88B10F0018 (INFO_TEMP SET:0x88B1 ACT:0x88B1 ERR:0x0F VALVE:0x0F MODE:0x0F) (,WAKEMEUP,CFG,RPTEN)
2014.02.08 23:31:34.202 5: HMLAN_Send: HMLAN1 S:S13A092E2 stat: 00 t:00000000 d:01 r:13A092E2 m:07 A112 121212 221F50
2014.02.08 23:31:34.203 4: SND L:09 N:07 F:A1 CMD:12 SRC:121212 DST:HK3 (HAVE_DATA) (,WAKEUP,BIDI,RPTEN)
2014.02.08 23:31:34.805 5: HMLAN_Parse: HMLAN1 R:R13A092E2 stat:0008 t:00000000 d:FF r:7FFF m:07 A112 121212 221F50
2014.02.08 23:31:34.805 5: HMLAN_Parse: HMLAN1 no ACK from 221F50
2014.02.08 23:31:37.839 4: CUL_HM_Resend: HK3 nr 3
Wenn ich mal zum Test eine Steckdose schalte sieht das so aus:
2014.02.09 00:00:40.949 2: CUL_HM set SW_Dose_1 toggle
2014.02.09 00:00:40.949 5: HMLAN_Send: HMLAN1 S:S13BB3AB9 stat: 00 t:00000000 d:01 r:13BB3AB9 m:11 A011 121212 21D6F0 0201000000
2014.02.09 00:00:40.950 4: SND L:0E N:11 F:A0 CMD:11 SRC:121212 DST:SW_Dose_1 0201000000 (SET CHANNEL:0x01 VALUE:0x00 RAMPTIME:0x0000) (,BIDI,RPTEN)
2014.02.09 00:00:41.106 5: HMLAN_Parse: HMLAN1 R:R13BB3AB9 stat:0001 t:0024E6D7 d:FF r:FFC2 m:11 8002 21D6F0 121212 0101000040
2014.02.09 00:00:41.106 5: HMLAN1 dispatch A0E11800221D6F01212120101000040::-62:HMLAN1
2014.02.09 00:00:41.107 4: RCV L:0E N:11 F:80 CMD:02 SRC:SW_Dose_1 DST:121212 0101000040 (ACK_STATUS CHANNEL:0x01 STATUS:0x00 UP:0x00 DOWN:0x00 LOWBAT:0x00 RSSI:0x40) (,RPTEN)
2014.02.09 00:01:04.589 5: HMLAN_Send: HMLAN1 I:K
2014.02.09 00:01:04.592 5: HMLAN_Parse: HMLAN1 V:03C1 sNo:KEQ0852586 d:23A75A O:121212 t:00254299 IDcnt:0005
Das Steckdose schaltet auch brav.
Sonst funktioniert ja auch alles, nur die Thermostat kann ich nicht setzen :-\ Wenn du einen Tipp für mich hättest wäre ich echt dankbar.
Herr 3x
Hi,
kannst du mit der aktuellen version bitte noch einmal rohmessages erzeugen vom Problemfall?
Und bitte HMprotocolEvents abschalten - das ist ein performance-fresser und macht genau hier Probleme!
Gruss Martin
Hallo Martin,
wie erzeuge ich denn die Rohmessages? Ich dachte eigentlich, dass das mit dem HMprotocolEvents geht. Habe ich wohl falsch verstanden :-[
Danke
Herr 3x
siehe
http://forum.fhem.de/index.php/topic,16563.0.html
HMprotocolEvents macht mehr, triggert events, parsed und gibt high-level output. Das geht nicht verlustfrei
Hallo Martin,
alles klar, ich habe das Log umgestellt. Ich schaue mir nur einen HM-CC-RT-DN an. Ein list HK1 gibt:
Internals:
DEF 2324A7
HMLAN1_MSGCNT 93
HMLAN1_RAWMSG E2324A7,0000,03D40BF0,FF,FFD4,0086102324A70000000AA4E10F0F18
HMLAN1_RSSI -44
HMLAN1_TIME 2014-02-09 17:10:41
IODev HMLAN1
LASTInputDev HMLAN1
MSGCNT 93
NAME HK1
NR 47
STATE CMDs_done
TYPE CUL_HM
channel_01 HK1_Weather
channel_02 HK1_Climate
channel_03 HK1_WindowRec
channel_04 HK1_ClimRT_tr
channel_05 HK1_ClimaTeam
channel_06 HK1_remote
lastMsg No:00 - t:10 s:2324A7 d:000000 0AA4E10F0F18
protCmdDel 14
protLastRcv 2014-02-09 17:10:41
protResnd 6 last_at:2014-02-09 11:44:21
protResndFail 2 last_at:2014-02-09 11:47:19
protSnd 9 last_at:2014-02-09 13:30:08
protState CMDs_done
rssi_at_HMLAN1 avg:-40.08 min:-44 max:-38 lst:-44 cnt:93
Readings:
2014-02-09 15:49:03 Activity alive
2014-02-04 18:50:03 CommandAccepted yes
2014-02-07 17:32:42 D-firmware 1.1
2014-02-07 17:32:42 D-serialNr KEQ0734405
2014-02-04 18:50:04 PairedTo 0x121212
2013-12-25 21:35:23 R-backOnTime 10 s
2014-02-09 00:41:36 R-btnLock unlock
2014-02-09 00:41:36 R-burstRx set_on
2014-02-09 00:41:36 R-cyclicInfoMsg on
2014-02-09 00:41:36 R-cyclicInfoMsgDis 0
2014-02-09 00:41:36 R-globalBtnLock off
2014-02-09 00:41:36 R-localResDis off
2013-12-25 21:35:23 R-lowBatLimitRT 2.1 V
2014-02-09 00:41:36 R-modusBtnLock off
2014-02-09 00:41:36 R-pairCentral 0x121212
2014-02-04 18:50:04 RegL_00: 01:00 02:01 09:01 0A:12 0B:12 0C:12 0E:0A 0F:00 11:00 12:15 16:00 18:00 19:00 1A:00 00:00
2014-02-04 20:21:46 RegL_07: 0
2013-12-25 21:35:22 ValvePosition 61 %
2014-02-09 17:10:41 actuator 15 %
2014-02-09 17:10:41 battery ok
2014-02-09 17:10:41 batteryLevel 3 V
2014-02-09 17:10:41 desired-temp 20.5
2014-02-09 17:10:41 measured-temp 22.5
2013-12-25 21:35:22 mode auto
2013-12-25 21:35:22 motorErr ok
2014-01-07 16:21:33 noReceiver src:2324A7 A010 0500000000000700
2014-02-03 20:58:05 powerOn -
2014-02-03 20:58:05 recentStateType info
2014-02-09 13:30:08 state CMDs_done
2014-02-09 13:30:08 time-request -
2013-12-25 21:35:22 unknown0 40
Helper:
mId 0095
rxType 140
Io:
nextSend 1391962242.04079
Prt:
bErr 0
sProc 0
sleeping 1
Rspwait:
Q:
qReqConf
qReqStat
Role:
dev 1
Rssi:
At_hmlan1:
avg -40.0860215053763
cnt 93
lst -44
max -38
min -44
Shregw:
07 04
Attributes:
actCycle 000:10
actStatus alive
autoReadReg 4_reqStatus
expert 2_full
firmware 1.1
model HM-CC-RT-DN
peerIDs
room EG_Buero
serialNr KEQ0734405
subType thermostat
webCmd getConfig:burstXmit
Das HMLAN sieht so aus:
Internals:
DEF 10.1.1.62:1000
DeviceName 10.1.1.62:1000
FD 4
HMLAN1_MSGCNT 950
HMLAN1_TIME 2014-02-09 17:13:57
HM_CMDNR 28
NAME HMLAN1
NR 24
NTFY_ORDER 50-HMLAN1
PARTIAL
RAWMSG E234FB1,0000,03D7097F,FF,FFB8,918610234FB10000000A789910001B
RSSI -72
STATE opened
TYPE HMLAN
XmitOpen 1
assignedIDs 234FB1,2386F4,21D6F0,20853C,15D167,2324A7,234FA4,207BAB,235C66
assignedIDsCnt 9
assignedIDsReport 0
firmware 0.961
msgKeepAlive dlyMax:0.15 bufferMin:4
msgLoadEst 1hour:0% 10min steps: 0/0/0/0/0/0
msgParseDly min:1 max:1843 last:7 cnt:903
owner 121212
serialNr KEQ0852586
uptime 000 17:53:46.436
Readings:
2014-02-09 15:48:35 Xmit-Events ok:1
2014-02-09 15:48:35 cond ok
2014-02-03 19:27:22 prot_ERROR-Overload last
2014-02-03 19:22:39 prot_Warning-HighLoad last
2014-02-09 13:50:28 prot_disconnected last
2014-02-09 15:48:35 prot_init last
2014-02-09 15:48:35 prot_ok last
2014-02-09 13:50:28 prot_timeout last
Assids:
15D167 1
207BAB 1
20853C 1
21D6F0 1
2324A7 1
234FA4 1
234FB1 1
235C66 1
2386F4 1
Helper:
assId 0
000001:
flg 0
msg
to 1391957317.41549
121212:
flg 0
15d167:
chn 02
flg 0
msg
name SW1SM_1
newChn +15D167,00,01,
to 1391958541.91639
207bab:
chn 01
flg 0
msg
name SW1PBU_1
newChn +207BAB,00,01,
to 1391941744.22776
20853c:
chn 01
flg 0
msg
name Esstischlicht
newChn +20853C,00,01,
to 1391941743.11015
21d6f0:
chn 01
flg 0
msg
name SW_Dose_1
newChn +21D6F0,00,01,
to 1391941746.43395
221f50:
flg 0
msg
to 1391942276.26405
2324a7:
chn 02
flg 0
msg
name HK1
newChn +2324A7,00,01,
to 1391949009.8188
234fa4:
chn 02
flg 0
msg
name HK8
newChn +234FA4,00,01,
to 1391945297.1563
234fb1:
chn 02
flg 0
msg
name HK9
newChn +234FB1,00,01,
to 1391945269.22377
235c66:
chn 02
flg 0
msg
name HK7
newChn +235C66,00,01,
to 1391945258.13035
2386f4:
chn 01
flg 0
msg
name Fenster_DG_Bad
newChn +2386F4,01,01,FE1F
to 1391948013.34729
Assids:
Dly:
cnt 903
lst 7
max 1843
min 1
K:
BufMin 4
DlyMax 0.15
Next 1391962465.00813
Start 1391962440.00813
Log:
all 0
sys 0
ids:
2324A7
Q:
HMcndN 0
answerPend 0
hmLanQlen 1
keepAliveRec 1
keepAliveRpt 0
apIDs:
Cap:
0 0
1 0
2 0
3 0
4 0
5 0
last 1
sum 0
Ref:
drft -0.000119655392469687
hmtL 64426436
kTs 0
offL 1391898013575
sysL 1391962440011
Attributes:
hmId 121212
hmLanQlen 1_min
icon it_radio
logIDs HK1
Jetzt zum Log. Einmal hat das setzen einer Temperatur geklappt. Danach dann nicht.
2014.02.09 17:13:13.198 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03D65AD9 d:FF r:FFD5 m:01 8610 2324A7 000000 0AA4E20F0F18
2014.02.09 17:15:29.949 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03D8711C d:FF r:FFD5 m:02 8610 2324A7 000000 0AA4E20F0F18
2014.02.09 17:17:32.200 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03DA4EB9 d:FF r:FFD5 m:03 8610 2324A7 000000 0AA4E20F0F18
2014.02.09 17:20:24.202 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03DCEEB4 d:FF r:FFD5 m:04 8610 2324A7 000000 0AA4E30F0F18
2014.02.09 17:23:01.703 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03DF5608 d:FF r:FFD5 m:05 8610 2324A7 000000 0AA4E30F0F18
2014.02.09 17:25:24.705 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03E184B6 d:FF r:FFD5 m:06 8610 2324A7 000000 0AA4E30F0F18
2014.02.09 17:25:24.865 0: HMLAN_Send: HMLAN1 S:S1777B526 stat: 00 t:00000000 d:01 r:1777B526 m:1D A112 121212 2324A7
2014.02.09 17:25:25.468 0: HMLAN_Parse: HMLAN1 R:R1777B526 stat:0008 t:00000000 d:FF r:7FFF m:1D A112 121212 2324A7
2014.02.09 17:25:25.468 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 17:27:33.456 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03E37BB8 d:FF r:FFD5 m:07 8610 2324A7 000000 0AA4E30F0F18
2014.02.09 17:27:33.591 0: HMLAN_Send: HMLAN1 S:S1779AC15 stat: 00 t:00000000 d:01 r:1779AC15 m:1E A112 121212 2324A7
2014.02.09 17:27:33.750 0: HMLAN_Parse: HMLAN1 R:R1779AC15 stat:0001 t:03E37CE3 d:FF r:FFD5 m:1E 8002 2324A7 121212 00
2014.02.09 17:27:33.920 0: HMLAN_Send: HMLAN1 S:+2324A7,00,01,
2014.02.09 17:27:33.921 0: HMLAN_Send: HMLAN1 S:S1779AD3B stat: 00 t:00000000 d:01 r:1779AD3B m:1F A011 121212 2324A7 860428
2014.02.09 17:27:34.152 0: HMLAN_Parse: HMLAN1 R:R1779AD3B stat:0001 t:03E37E77 d:FF r:FFD6 m:1F 8002 2324A7 121212 00
2014.02.09 17:30:31.706 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03E6341E d:FF r:FFD6 m:08 8610 2324A7 000000 0AA0E30F0018
2014.02.09 17:33:15.457 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03E8B3DD d:FF r:FFD7 m:09 8610 2324A7 000000 0AA0E30F0018
2014.02.09 17:33:15.695 0: HMLAN_Send: HMLAN1 S:S177EE406 stat: 00 t:00000000 d:01 r:177EE406 m:20 A112 121212 2324A7
2014.02.09 17:33:16.298 0: HMLAN_Parse: HMLAN1 R:R177EE406 stat:0008 t:00000000 d:FF r:7FFF m:20 A112 121212 2324A7
2014.02.09 17:33:16.298 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 17:35:44.959 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03EAFBF1 d:FF r:FFD6 m:0A 8610 2324A7 000000 0AA0E30F0018
2014.02.09 17:35:45.198 0: HMLAN_Send: HMLAN1 S:S17812C04 stat: 00 t:00000000 d:01 r:17812C04 m:21 A112 121212 2324A7
2014.02.09 17:35:45.801 0: HMLAN_Parse: HMLAN1 R:R17812C04 stat:0008 t:00000000 d:FF r:7FFF m:21 A112 121212 2324A7
2014.02.09 17:35:45.801 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 17:37:59.960 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03ED0B5D d:FF r:FFD5 m:0B 8610 2324A7 000000 0AA0E20F0018
2014.02.09 17:38:00.200 0: HMLAN_Send: HMLAN1 S:S17833B5D stat: 00 t:00000000 d:01 r:17833B5D m:22 A112 121212 2324A7
2014.02.09 17:38:00.803 0: HMLAN_Parse: HMLAN1 R:R17833B5D stat:0008 t:00000000 d:FF r:7FFF m:22 A112 121212 2324A7
2014.02.09 17:38:00.803 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 17:40:00.461 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03EEE224 d:FF r:FFD5 m:0C 8610 2324A7 000000 0AA0E10F0018
2014.02.09 17:40:00.664 0: HMLAN_Send: HMLAN1 S:S17851212 stat: 00 t:00000000 d:01 r:17851212 m:23 A112 121212 2324A7
2014.02.09 17:40:01.268 0: HMLAN_Parse: HMLAN1 R:R17851212 stat:0008 t:00000000 d:FF r:7FFF m:23 A112 121212 2324A7
2014.02.09 17:40:01.268 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 17:42:50.462 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:03F17A4F d:FF r:FFD5 m:0D 8610 2324A7 000000 0AA0E10F0018
Sollte es ein Timing-Problem sein: Wie bekomme ich raus, was da in der Zwischenzeit passiert?
Danke
Herr 3x
hm -sehr seltsam, dass es so langsam ist bei dir. das ist definitv zu spät.
Habe ich sicher schon oft gefraft - das ist die aktuellen version?
if ($modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend}){
my $dDly = $modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend} - $tn;
$dDly -= 0.05 if ($typ eq "02");# delay at least 50ms for ACK, but not 100
Log 1,"HMLAN delay:".(($dDly > 0.1)?0.1:$dDly) if ($dDly > 0.01);
select(undef, undef, undef, (($dDly > 0.1)?0.1:$dDly))
if ($dDly > 0.01);
}
Du kannst einmal folgende Zeile in 00_HMLAN einbauen, ob das delay hier gemacht wird. Zeile 699
Gruss Martin
Hallo Martin,
die Version vom fhem ist aktuell. Habe vor den Tests extra noch ein Update gemacht.
2014.02.09 19:32:22.970 0: Server started with 195 defined entities (version $Id: fhem.pl 4829 2014-02-07 07:27:47Z rudolfkoenig $, os darwin, user admin, pid 27688)
2014.02.09 19:32:22.971 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.09 19:32:30.284 1: HMLAN delay:0.0990369319915771
2014.02.09 19:32:41.220 1: HMLAN delay:0.0985410213470459
2014.02.09 19:34:40.525 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:0457E15A d:FF r:FFD8 m:39 8610 2324A7 000000 0AA0E00F0018
2014.02.09 19:34:40.526 1: HMLAN delay:0.0931150913238525
2014.02.09 19:34:40.693 0: HMLAN_Send: HMLAN1 S:S17EE0D52 stat: 00 t:00000000 d:01 r:17EE0D52 m:0C A112 121212 2324A7
2014.02.09 19:34:41.296 0: HMLAN_Parse: HMLAN1 R:R17EE0D52 stat:0008 t:00000000 d:FF r:7FFF m:0C A112 121212 2324A7
2014.02.09 19:34:41.297 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 19:34:50.221 1: HMLAN delay:0.0924179553985596
2014.02.09 19:37:21.527 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045A565C d:FF r:FFD8 m:3A 8610 2324A7 000000 0AA0DF0F0018
2014.02.09 19:37:21.528 1: HMLAN delay:0.0942509174346924
2014.02.09 19:37:21.686 0: HMLAN_Send: HMLAN1 S:S17F0823B stat: 00 t:00000000 d:01 r:17F0823B m:0E A112 121212 2324A7
2014.02.09 19:37:22.289 0: HMLAN_Parse: HMLAN1 R:R17F0823B stat:0008 t:00000000 d:FF r:7FFF m:0E A112 121212 2324A7
2014.02.09 19:37:22.289 0: HMLAN_Parse: HMLAN1 no ACK from 2324A7
2014.02.09 19:37:48.726 1: HMLAN delay:0.0912721157073975
2014.02.09 19:39:48.277 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C93B0 d:FF r:FFD3 m:3B 8610 2324A7 000000 0AA0DF0F0018
2014.02.09 19:39:48.278 1: HMLAN delay:0.0952539443969727
2014.02.09 19:39:48.384 0: HMLAN_Send: HMLAN1 S:S17F2BF7A stat: 00 t:00000000 d:01 r:17F2BF7A m:10 A112 121212 2324A7
2014.02.09 19:39:48.541 0: HMLAN_Parse: HMLAN1 R:R17F2BF7A stat:0001 t:045C94BD d:FF r:FFD3 m:10 8002 2324A7 121212 00
2014.02.09 19:39:48.542 1: HMLAN delay:0.0988528728485107
2014.02.09 19:39:48.713 0: HMLAN_Send: HMLAN1 S:+2324A7,00,01,
2014.02.09 19:39:48.714 0: HMLAN_Send: HMLAN1 S:S17F2C082 stat: 00 t:00000000 d:01 r:17F2C082 m:11 A011 121212 2324A7 860429
2014.02.09 19:39:48.945 0: HMLAN_Parse: HMLAN1 R:R17F2C082 stat:0001 t:045C9651 d:FF r:FFD3 m:11 8002 2324A7 121212 00
2014.02.09 19:39:48.946 1: HMLAN delay:0.0990457534790039
2014.02.09 19:39:49.112 0: HMLAN_Send: HMLAN1 S:+2324A7,00,01,
2014.02.09 19:39:49.113 0: HMLAN_Send: HMLAN1 S:S17F2C216 stat: 00 t:00000000 d:01 r:17F2C216 m:12 A001 121212 2324A7 04040000000001
2014.02.09 19:39:49.352 0: HMLAN_Parse: HMLAN1 R:R17F2C216 stat:0001 t:045C97E8 d:FF r:FFD3 m:12 8010 2324A7 121212 0208000000
2014.02.09 19:39:49.353 1: HMLAN delay:0.0987308025360107
2014.02.09 19:39:49.504 0: HMLAN_Send: HMLAN1 S:+2324A7,00,01,
2014.02.09 19:39:49.505 0: HMLAN_Send: HMLAN1 S:S17F2C3AD stat: 00 t:00000000 d:01 r:17F2C3AD m:13 A001 121212 2324A7 00040000000007
2014.02.09 19:39:49.769 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9984 d:FF r:FFD3 m:13 A010 2324A7 121212 03012B22093D18030016073000640F0500
2014.02.09 19:39:49.881 0: HMLAN_Parse: HMLAN1 R:R17F2C3AD stat:0001 t:045C9989 d:FF r:FFD3 m:13 A010 2324A7 121212 03012B22093D18030016073000640F0500
2014.02.09 19:39:50.028 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9A87 d:FF r:FFD3 m:14 A010 2324A7 121212 03100000098E486656F049204520452045
2014.02.09 19:39:50.286 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9B89 d:FF r:FFD3 m:15 A010 2324A7 121212 031F204520452045204520452045204520
2014.02.09 19:39:50.544 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9C8B d:FF r:FFD3 m:16 A010 2324A7 121212 032E486656F04920452045204520452045
2014.02.09 19:39:50.803 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9D8E d:FF r:FFD3 m:17 A010 2324A7 121212 033D2045204520452045204520485456F0
2014.02.09 19:39:51.061 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9E90 d:FF r:FFD3 m:18 A010 2324A7 121212 034C492055084520452045204520452045
2014.02.09 19:39:51.319 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045C9F92 d:FF r:FFD3 m:19 A010 2324A7 121212 035B20452045204520485456F049205508
2014.02.09 19:39:51.577 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA094 d:FF r:FFD3 m:1A A010 2324A7 121212 036A452045204520452045204520452045
2014.02.09 19:39:51.836 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA197 d:FF r:FFD3 m:1B A010 2324A7 121212 0379204520485456F04920550845204520
2014.02.09 19:39:52.094 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA299 d:FF r:FFD3 m:1C A010 2324A7 121212 0388452045204520452045204520452048
2014.02.09 19:39:52.352 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA39B d:FF r:FFD3 m:1D A010 2324A7 121212 03975456F0492055084520452045204520
2014.02.09 19:39:52.611 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA49E d:FF r:FFD3 m:1E A010 2324A7 121212 03A645204520452045204520485456F049
2014.02.09 19:39:52.869 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA5A0 d:FF r:FFD3 m:1F A010 2324A7 121212 03B5205508452045204520452045204520
2014.02.09 19:39:53.124 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA69F d:FF r:FFD3 m:20 A010 2324A7 121212 03C445204520452012212D0F1E1E
2014.02.09 19:39:53.371 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:045CA797 d:FF r:FFD3 m:21 8010 2324A7 121212 0300
2014.02.09 19:40:32.977 1: HMLAN delay:0.0922799110412598
Die ersten delays sind vom Schalten eines Aktors.
Netzwerktechnisch ist der HMLAN problemlos erreichbar. Auf ping antwortet er sogar schneller als die FB. Die Route ist auch direkt. Und FHEM läuft auf einem Core 2 Duo mit 2,5 GHz mit SSD. Eigentlich auch schnell. :o
Grüße
Herr 3x
hi,
19:34:40.525 Parse: HMLAN1 R:E2324A7 stat:0000 m:39 8610 2324A7 000000 0AA0E00F0018
19:34:40.526 delay:0.0931150913238525
19:34:40.693 Send: HMLAN1 S:S17EE0D52 stat: 00 m:0C A112 121212 2324A7
schlechtes timing. Die 93ms sind korrekt gerechnet - aber dein system führt es erst nach 160ms aus
19:37:21.527 Parse: HMLAN1 R:E2324A7 stat:0000 m:3A 8610 2324A7 000000 0AA0DF0F0018
19:37:21.528 delay:0.0942509174346924
19:37:21.686 Send: HMLAN1 S:S17F0823B stat: 00 m:0E A112 121212 2324A7
19:37:48.726 delay:0.0912721157073975
19:39:48.277 Parse: HMLAN1 R:E2324A7 stat:0000 m:3B 8610 2324A7 000000 0AA0DF0F0018
die erste Berechnung ist wieder das gleiche.
Das 2. Delay verstehe ich nicht.
Seltsam, dass der Systemcall so unpräzise ist. Kenne ich so nicht.
Gruss Martin
Hmmm, das zweite delay könnte von einem anderen Gerät stammen, der Zeile fürs Log unterscheidet ja nicht, oder?
Allerdings frage ich mich, was fhem in den 160 ms sonst so macht. Kann man das irgendwie tracen?
Immerhin scheinen andere Geräte keine solche Verzögerung zu haben:
2014.02.09 20:58:02.218 0: HMLAN_Send: HMLAN1 S:+21D6F0,00,01,
2014.02.09 20:58:02.218 0: HMLAN_Send: HMLAN1 S:S183A5F2E stat: 00 t:00000000 d:01 r:183A5F2E m:18 A001 121212 21D6F0 010E
2014.02.09 20:58:02.384 0: HMLAN_Parse: HMLAN1 R:E21D6F0 stat:0000 t:04A436B8 d:FF r:FFC1 m:18 A410 21D6F0 121212 0601C80042
2014.02.09 20:58:02.498 0: HMLAN_Parse: HMLAN1 R:R183A5F2E stat:0001 t:04A436BD d:FF r:FFC1 m:18 A410 21D6F0 121212 0601C80042
2014.02.09 20:58:55.093 0: HMLAN_Send: HMLAN1 S:+21D6F0,00,01,
2014.02.09 20:58:55.093 0: HMLAN_Send: HMLAN1 S:S183B2DB9 stat: 00 t:00000000 d:01 r:183B2DB9 m:1A A011 121212 21D6F0 0201C80000
2014.02.09 20:58:55.252 0: HMLAN_Parse: HMLAN1 R:R183B2DB9 stat:0001 t:04A50550 d:FF r:FFC1 m:1A 8002 21D6F0 121212 0101C80042
Herr 3x
ZitatHmmm, das zweite delay könnte von einem anderen Gerät stammen, der Zeile fürs Log unterscheidet ja nicht, oder?
guter Punkt - wenn du selektiv gefiltert hast.
ZitatAllerdings frage ich mich, was fhem in den 160 ms sonst so macht. Kann man das irgendwie tracen?
FHEM kann nichts machen. Das ist (sehr weit gehens) single-threated. Ein Select ist ein system-call. Es erlaubt Linux andere Prozesse zu bearbeiten.
Zwischen dem Select (warten) und dem naehsten Log ist fast kein Code - es ist also davon auszugehen, das dies in "nullzeit" erledigt wird (oft bestaetigt).
FHEM ist single-threated - linix aber nicht.
Nachdem select eigentlich immer sauber funktiniert (man muss damit rechnen, dass das OS auch einmal etwas anderes tut, ist aber die Ausnahme) stellt sich die Frage, was in deinem System anders laeuft.
a) laeuft der Timer deines Select langsamer?
b) ist ein offset auf der Berechnung
test: setze $dDly = 0.05 in der IF abfrage und pruefe, ob 50ms, 100ms oder 160ms gewartet werden.
Aktuell scheint es ein Problem deines OS zu sein.
Gruss Martin
Hallo Martin,
ich habe mal probehalber die Zeile $dDly = 0.05 angepasst,
if ($modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend}){
my $dDly = $modules{CUL_HM}{defptr}{$dst}{helper}{io}{nextSend} - $tn;
$dDly -= 0.05 if ($typ eq "02");# delay at least 50ms for ACK, but not 100
Log 1,"HMLAN 1 delay:".(($dDly > 0.1)?0.1:$dDly) if ($dDly > 0.01);
select(undef, undef, undef, (($dDly > 0.1)?0.1:$dDly))
if ($dDly = 0.05);
Log 1, "HMLAN 2 delay select";
Die zusätzlichen Logeinträge habe ich zur Zeitmessung eingebaut.
Dann sieht mein Log so aus:
2014.02.11 20:36:17.467 0: HMLAN_Parse: HMLAN1 R:E2324A7 stat:0000 t:0EDD66EE d:FF r:FFD4 m:C4 8610 2324A7 000000 0A90DD0F0018
2014.02.11 20:36:17.468 1: HMLAN 1 delay:0.094271183013916
2014.02.11 20:36:17.561 1: HMLAN 2 delay select
2014.02.11 20:36:17.561 0: HMLAN_Send: HMLAN1 S:S22732E80 stat: 00 t:00000000 d:01 r:22732E80 m:10 A112 121212 2324A7
2014.02.11 20:36:17.562 1: HMLAN delay end syswrite
2014.02.11 20:36:17.718 0: HMLAN_Parse: HMLAN1 R:R22732E80 stat:0001 t:0EDD67EF d:FF r:FFD4 m:10 8002 2324A7 121212 00
2014.02.11 20:36:17.720 1: HMLAN 1 delay:0.099085807800293
2014.02.11 20:36:17.829 1: HMLAN 2 delay select
2014.02.11 20:36:17.829 0: HMLAN_Send: HMLAN1 S:+2324A7,00,01,
2014.02.11 20:36:17.829 0: HMLAN_Send: HMLAN1 S:S22732F7C stat: 00 t:00000000 d:01 r:22732F7C m:11 A011 121212 2324A7 86042A
2014.02.11 20:36:17.830 1: HMLAN delay end syswrite
2014.02.11 20:36:18.121 0: HMLAN_Parse: HMLAN1 R:R22732F7C stat:0001 t:0EDD6982 d:FF r:FFD4 m:11 8002 2324A7 121212 00
2014.02.11 20:36:31.891 1: HMLAN delay end syswrite
Damit wird die Temperatur meistens gesetzt.
Um der Sache etwas tiefer auf den Grund zu gehen würde ich gern verstehen, was das select eigentlich da macht.
Herr 3x
Zitat von: Herr 3x am 11 Februar 2014, 20:49:10
select(undef, undef, undef, (($dDly > 0.1)?0.1:$dDly))
Um der Sache etwas tiefer auf den Grund zu gehen würde ich gern verstehen, was das select eigentlich da macht.
Das select() macht eine Verzögerung von 0,1s falls das ermittelte $dDly größer als 0.1 ist, ansonsten beträgt die Verzögerung $dDly
im letzten Beispiel funktioniert es ja auch... da sind keine 160ms mehr dabei. Hast du etwas geaendert?
Hallo Martin,
mit einem
sleep(0.05);
statt dem select scheint das Timing besser zu klappen.
Meine Vermutung ist, dass das select bei mir eine andere Zeit zählt oder irgendwas anderes verzögert.
Es gibt eine real time, user time und sys time. Mit real = user + sys + x
Wenn ich perl -e 'select(undef,undef,undef,.1)' als script mit "time" aufrufe bekomme ich:
real 0m0.113s
user 0m0.004s
sys 0m0.006s
Mit dem unschönen Workaround klappt es erst mal.
Danke
Herr 3x
hm - select ist ganz allgemein eine Methode um ein paar ms zu warten. Muss etwas an deinem System sein...
Hallo,
heute habe ich meine Gesamtconfig nochmal auf der FB7390 getestet: Da läuft alles. Also muss es an dem Server liegen :-\
Nachdem die GUI sich schnell anfühlt und auch sonst eigentlich alles andere gut funktioniert habe ich mir gedacht, dass ich die Wartezeit mal selbst bestimmt, wenn das OS nicht echtzeitfähig ist.
Herausgekommen ist das (man möge mir mein Amateur-Perl verzeihen):
Log 1,"HMLAN delay:".(($dDly > 0.1)?0.1:$dDly) if ($dDly > 0.01);
# select(undef, undef, undef, (($dDly > 0.1)?0.1:$dDly))
my $t0 = [gettimeofday];
my $elap_time = 0;
my $elap_test = 0;
do{
sleep(0.001);
$elap_time = tv_interval ( $t0,[gettimeofday]);
if ($elap_time > $dDly) {$elap_test = 1};
}while($elap_test == 0);
Log 1,"HMLAN delay end".$elap_time." ".$elap_test;
Im Log sieht das etwa so aus:
2014.02.15 22:02:45.255 0: HMLAN_Send: HMLAN1 S:S375BC6EF stat: 00 t:00000000 d:01 r:375BC6EF m:5F A001 121212 2324A7 0503
2014.02.15 22:02:45.417 0: HMLAN_Parse: HMLAN1 R:R375BC6EF stat:0001 t:23C6C871 d:FF r:FFD5 m:5F 8010 2324A7 121212 0100000000
2014.02.15 22:02:45.418 1: HMLAN delay:0.0990607738494873
2014.02.15 22:02:45.518 1: HMLAN delay end0.099649 1
2014.02.15 22:02:45.518 0: HMLAN_Send: HMLAN1 S:S375BC7EE stat: 00 t:00000000 d:01 r:375BC7EE m:60 A001 121212 2324A7 05040000000001
2014.02.15 22:02:45.823 0: HMLAN_Parse: HMLAN1 R:R375BC7EE stat:0001 t:23C6CA08 d:FF r:FFD5 m:60 8010 2324A7 121212 0208000000
2014.02.15 22:02:45.825 1: HMLAN delay:0.0988819599151611
2014.02.15 22:02:45.925 1: HMLAN delay end0.100192 1
Na ja, elegant ist anders, aber es funktioniert. An der Prozessorlast sehe ich keinen Unterschied gegenüber vorher.
Jetzt funktioniert das setzen der Soll-Temperatur reibungslos (vermutlich bis Montag) ;)
Herr 3x
wie du es magst.
es arbeitet schon mehr. Ein Select lässt andere Processe - ausser FHEM zu wort kommen, sleep kann ich aktuell nicht klar zuordnen, Aber du rufst es in jeden Fall mehrfach auf - das OS muss den prozess immer wieder in den Fordergrund switchen - das kostet in jedem Fall kernal zeit.
Da dein System aber generell eine korrekte Zeit errechnen kann scheint eine Einstellung im Linux falsch zu sein. Mit echtzeitfähigkeit im eigentlichen Sinn hat dies nichts zu tun.... fast alle hier verwendeten Linux sind nicht wirklich echzeit programmiert...
Hallo Martin,
Vermutlich ist das untergegangen, aber ich habe fhem nicht auf Linux laufen sondern auf OSX 10.9.
Vor dem Hintergrund kann das Verhalten schon anders sein. Kann natürlich auch ein Bug sein.
Mir ist bewusst, dass ich mit dem Workaround mehr Prozessorlast erzeuge. Das scheint aber in der Gesamtlast des Systems unter zu gehen, fhem kommt nicht über eine einstellige Prozentzahl der CPU-Last hinaus. Ich werde nach Updates einfach die Stelle patchen und gut is.
Danke für deine Unterstützung
Herr 3x
ok - select sollte trotzdem arbeiten. Ich denke was ist irgendwo einstellbar ist.
Du könntest ausmessen, ob es ein offset, oder eine Lineare Abweichung ist - oder schlimmstenfalls random.
Du kannst natürlich auch die Funktion überschreiben. Wenn du HMLAN_SimpleWrite(@) rauskopierst und in ein eingenes Modul einbaust wird die Orginale mit deiner ersetzt.
Dann kannst du es einfach einauen und musst nicht patchen (oder - es patchet automatisch). Wenn natürlich die Funktion geändert wird musst du nachziehen... der Rest läuft
Gruss Martin
Hallo Zusammen,
ich habe ebenfalls Probleme mit meinem neu erworbenen HM-CC-RT-DN.
Bisher hatte ich lediglich MAX! Komponenten, die über einen MAX-LAN angesteuert werden und einen CUL v3 mit Version 1.55 zum Lesen eines S330TH und FHEM Version 5.5 mit dem neusten update.
Da MAX nicht sehr ausbaubar ist möchte ich mit Homematic Komponenten weitermachen und habe den oben erwähnten HM-CC-RT-DN mit dem CUL (unter rfmode HomeMatic)
erfolgreich gepairt. Es erscheint
PairedTo 0xF11234
wobei F11234 mein CUL ist. Auch kann ich Befehle zum HM-CC-RT-DN senden. Zum Beispiel habe ich das Temperaturprofil gesetzt
und kann auf bestimmte manuelle Temperaturen stellen. Empgfangen geht problemlos, ich bekomme regelmäßig die Temperaturen im Log.
ABER:
Es akkumulieren sich CMDs_pending. Morgens sind es leicht 150. Manachmal werden es weniger, das heiß ein paar scheint er los zu werden und
es heißt auch
Activity alive 2014-02-16 09:12:39
CommandAccepted yes 2014-02-16 12:22:48
Nach Suchen im Forum habe ich folgende Ursachen ausgeschlossen:
1) schlecher Empfang :
rssi_at_CUL_HM avg:-29.42 min:-70.5 max:-27.5 lst:-29 cnt:318
2) HMInfo gab Fehler bei IODev das habe ich durch setzen von
attr CUL_HM hmId F11234
attr Thermo IODev CUL_HM
loswerden können.
An der Sache hat sich aber nichts geändert.
Kann mir jemand raten?
Gruß George
Hallo Georg,
fragen über fragen
- woher kommen die kontinuierlichen Kommandos im stack? warum sendest du so viel?
- wenn sich 150 kommandos ansammeln ist es sicher ein Problem - das gibt delays - und zwar erheblich
=> wenn du es untersucht habe willst, leifere die Details:
- mitschnitt der rohmessages
- list des Device
Gruss Martin
Hallo Martin,
dachte ich mir, daß es da ein Problem gibt. Senden tue ich eigentlich nichts mit Absicht. Ein paar getConfigs, wenn ich mir das so im Log ansehe.
Wie man Rohmitschnitte macht, muß ich erst noch lernen, (für einen einen Hinweis wäre ich dankbar) hier ist eine kleine manuelle Übersicht über die letzte Stunde:
13:18h 54 Cmds_pending
13:19h 56
13:33h 55
13:51h 61
14:18h 66
14:28h 67
Gruß George
Hallo Martin
hier ist das Listing des CUL_HM
fhem> list CUL_HM
Internals:
CMDS BCFiAZEGMRTVWXefmltux
CUL_HM_MSGCNT 543
CUL_HM_TIME 2014-02-16 14:31:21
Clients :CUL_HM:HMS:CUL_IR:
DEF /dev/ttyACM0@38400 1234
DeviceName /dev/ttyACM0@38400
FD 17
FHTID 1234
HM_CMDNR 6
NAME CUL_HM
NR 131
PARTIAL
RAWMSG A1A06A01023B992F1123403012A20093D18030016073000640F05005A
RSSI -29
STATE Initialized
TYPE CUL
VERSION V 1.55 CUL868
initString X21
Ar
Matchlist:
1:CUL_HM ^A....................
8:HMS ^810e04....(1|5|9).a001
D:CUL_IR ^I............
Readings:
2014-02-16 09:12:26 cmds B C F i A Z E G M R T V W X e f m l t u x
2014-02-16 14:31:21 state Initialized
Helper:
23b992:
QUEUE:
Attributes:
hmId F11234
rfmode HomeMatic
hier des Homematic. (Heißt jetzt ThermoBuero1)
Internals:
CUL_HM_MSGCNT 548
CUL_HM_RAWMSG A1A08A01023B992F1123403012A20093D18030016073000640F05005B
CUL_HM_RSSI -28.5
CUL_HM_TIME 2014-02-16 14:33:34
DEF 23B992
IODev CUL_HM
LASTInputDev CUL_HM
MSGCNT 548
NAME ThermoBuero1
NR 133
STATE CMDs_pending
TYPE CUL_HM
channel_01 ThermoBuero1_Weather
channel_02 ThermoBuero1_Climate
channel_03 ThermoBuero1_WindowRec
channel_04 ThermoBuero1_Clima
channel_05 ThermoBuero1_ClimaTeam
channel_06 ThermoBuero1_remote
lastMsg No:08 - t:10 s:23B992 d:F11234 03012A20093D18030016073000640F0500
protCmdPend 67 CMDs pending
protCondBurst off
protLastRcv 2014-02-16 14:33:34
protResnd 95 last_at:2014-02-16 14:33:35
protSnd 575 last_at:2014-02-16 14:33:34
protState CMDs_pending
rssi_at_CUL_HM avg:-29.28 min:-70.5 max:-27.5 lst:-28.5 cnt:548
Readings:
2014-02-16 09:12:39 Activity alive
2014-02-16 14:33:33 CommandAccepted yes
2014-02-15 10:05:53 D-firmware 1.1
2014-02-15 10:05:53 D-serialNr KEQ0721213
2014-02-15 23:19:44 PairedTo 0xF11234
2014-02-15 17:26:01 R-backOnTime 10 s
2014-02-15 23:19:44 R-btnLock unlock
2014-02-15 23:19:44 R-burstRx off
2014-02-15 23:19:44 R-cyclicInfoMsg on
2014-02-15 23:19:44 R-cyclicInfoMsgDis 0
2014-02-15 23:19:44 R-globalBtnLock off
2014-02-15 23:19:44 R-localResDis off
2014-02-15 17:26:01 R-lowBatLimitRT 2.1 V
2014-02-15 23:19:44 R-modusBtnLock off
2014-02-15 23:19:44 R-pairCentral 0xF11234
2014-02-15 23:19:44 RegL_00: 01:00 02:01 09:01 0A:F1 0B:12 0C:34 0E:0A 0F:00 11:00 12:15 16:00 18:00 19:00 1A:00 00:00
2014-02-16 14:33:33 actuator 100 %
2014-02-16 14:33:33 battery ok
2014-02-16 14:33:33 batteryLevel 3.1 V
2014-02-16 14:33:33 desired-temp 21.5
2014-02-16 14:33:33 measured-temp 20.4
2014-02-16 14:33:35 state CMDs_pending
2014-02-15 20:42:53 time-request -
Regl_07::
VAL
cmdStack:
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A011F1123423B9928004
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A011F1123423B99281041C
++A011F1123423B9928004
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
++A001F1123423B99204040000000001
++A001F1123423B99200040000000007
++A001F1123423B9920503
++A001F1123423B99205040000000001
++A001F1123423B9920603
++A001F1123423B99206040000000001
Helper:
cSnd 01F1123423B99200040000000007
mId 0095
rxType 140
Io:
nextSend 1392557614.40117
Prt:
awake 0
bErr 0
sProc 2
wakeup 0
wuReSent 2
Q:
qReqConf
qReqStat
Role:
dev 1
Rpt:
IO CUL_HM
flg A
ts 1392557614.30699
ack:
HASH(0x1b11108)
088002F1123423B99200
Rssi:
At_cul_hm:
avg -29.286496350365
cnt 548
lst -28.5
max -27.5
min -70.5
Shregw:
07 04
Shadowreg:
RegL_07:
Attributes:
IODev CUL_HM
actCycle 000:10
actStatus alive
autoReadReg 5_readMissing
burstAccess 1_auto
expert 2_full
firmware 1.1
icon hc_wht_regler
model HM-CC-RT-DN
peerIDs
room Buero
serialNr KEQ0721213
showtime 1
subType thermostat
webCmd getConfig:clear msgEvents:burstXmit
Jetzt versuche ich mich an den Mitschnitten
George
Hi,
roh-mitschnitt:
http://forum.fhem.de/index.php/topic,16563.0.html
ein list des Device zeigt die Kommandos.
hm - könnte sein, dass es das automatische lesen der Config ist. Wenn es wirklich schlecht funktioniert könnte ich mir vorstellen, dass ein neuer Versuch gestartet wird bevor der vorige abgearbeitet ist. Muss man evtl abfangen...
Dennoch hast du offensichtlich ein Übertragungsproblem - das scheint das promäre Übel
Gruss Martin
p.s. a - sehe, es sind wiederholte getConfig...
@herr3x
wenn du das in die Kommandozeile pastest kannst du das select ausmessen - und die test-werde ändern...
{my @tt = (0.1,0.2);;my %r;;\
foreach my $ti(@tt){foreach(1..10){\
my $s = gettimeofday();;select(undef, undef, undef, $ti);;\
my $c=(gettimeofday()-$s);;\
$r{$ti}{min}=$c if(!$r{$ti}{min}||$r{$ti}{min}>$c);;\
$r{$ti}{max}=$c if(!$r{$ti}{max}||$r{$ti}{min}<$c);;\
}};;my $ret = "";;\
foreach(keys %r){$ret.="$_ - min:".int(($r{$_}{min}-$_)*1000)." max:".int(($r{$_}{min}-$_)*1000)."\n"}\
return $ret;;\
}
Gruss Martin
Oh Gott! da geht ja die Post ab. Ich habe mit
set ThermoBuero1 burstXmit
folgendes mitgeschnitten:
2014.02.16 14:50:54.303 4: CUL_send: CUL_HMAs 09 15 B112 F11234 23B992
2014.02.16 14:51:53.786 4: CUL_Parse: CUL_HM A 0F D1 8610 23B992 000000 0AACCB9064265A -29
2014.02.16 14:51:53.891 4: CUL_send: CUL_HMAs 09 16 A112 F11234 23B992
2014.02.16 14:51:54.044 4: CUL_Parse: CUL_HM A 0A 16 8002 23B992 F11234 005A -29
2014.02.16 14:51:54.147 4: CUL_send: CUL_HMAs 10 17 A001 F11234 23B992 00040000000007
2014.02.16 14:51:54.316 4: CUL_Parse: CUL_HM A 1A 17 A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:51:54.380 4: CUL_send: CUL_HMAs 0A 17 8002 F11234 23B992 00
2014.02.16 14:51:54.574 4: CUL_Parse: CUL_HM A 1A 18 A010 23B992 F11234 03100000098E386C487856B455144120455A -29
2014.02.16 14:51:54.637 4: CUL_send: CUL_HMAs 0A 18 8002 F11234 23B992 00
2014.02.16 14:51:54.832 4: CUL_Parse: CUL_HM A 1A 19 A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:51:54.896 4: CUL_send: CUL_HMAs 0A 19 8002 F11234 23B992 00
2014.02.16 14:51:55.090 4: CUL_Parse: CUL_HM A 1A 1A A010 23B992 F11234 032E386C487856B454FC412045204520455A -29
2014.02.16 14:51:55.153 4: CUL_send: CUL_HMAs 0A 1A 8002 F11234 23B992 00
2014.02.16 14:51:55.347 4: CUL_Parse: CUL_HM A 1A 1B A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:51:55.411 4: CUL_send: CUL_HMAs 0A 1B 8002 F11234 23B992 00
2014.02.16 14:51:55.605 4: CUL_Parse: CUL_HM A 1A 1C A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:51:55.669 4: CUL_send: CUL_HMAs 0A 1C 8002 F11234 23B992 00
2014.02.16 14:51:55.872 4: CUL_Parse: CUL_HM A 1A 1D A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:51:55.936 4: CUL_send: CUL_HMAs 0A 1D 8002 F11234 23B992 00
2014.02.16 14:51:55.966 4: CUL_send: CUL_HMAs 09 18 B112 F11234 23B992
2014.02.16 14:51:56.397 4: CUL_Parse: CUL_HM A 1A 1E A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:51:56.460 4: CUL_send: CUL_HMAs 0A 1E 8002 F11234 23B992 00
2014.02.16 14:51:56.653 4: CUL_Parse: CUL_HM A 1A 1F A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:51:56.716 4: CUL_send: CUL_HMAs 0A 1F 8002 F11234 23B992 00
2014.02.16 14:51:56.895 4: CUL_Parse: CUL_HM A 1A 20 A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:51:56.958 4: CUL_send: CUL_HMAs 0A 20 8002 F11234 23B992 00
2014.02.16 14:51:57.153 4: CUL_Parse: CUL_HM A 1A 21 A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:51:57.216 4: CUL_send: CUL_HMAs 0A 21 8002 F11234 23B992 00
2014.02.16 14:51:57.411 4: CUL_Parse: CUL_HM A 1A 22 A010 23B992 F11234 03A64520452045204520452038664878565A -29
2014.02.16 14:51:57.474 4: CUL_send: CUL_HMAs 0A 22 8002 F11234 23B992 00
2014.02.16 14:51:57.669 4: CUL_Parse: CUL_HM A 1A 23 A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:51:57.732 4: CUL_send: CUL_HMAs 0A 23 8002 F11234 23B992 00
2014.02.16 14:51:57.924 4: CUL_Parse: CUL_HM A 17 24 A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:51:57.987 4: CUL_send: CUL_HMAs 0A 24 8002 F11234 23B992 00
2014.02.16 14:51:58.182 4: CUL_Parse: CUL_HM A 17 24 A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:51:58.245 4: CUL_send: CUL_HMAs 0A 24 8002 F11234 23B992 00
2014.02.16 14:51:58.428 4: CUL_Parse: CUL_HM A 0B 25 8010 23B992 F11234 03005A -29
2014.02.16 14:51:58.531 4: CUL_send: CUL_HMAs 0B 19 A001 F11234 23B992 0503
2014.02.16 14:51:58.688 4: CUL_Parse: CUL_HM A 0E 19 8010 23B992 F11234 01000000005A -29
2014.02.16 14:51:58.791 4: CUL_send: CUL_HMAs 10 1A A001 F11234 23B992 05040000000001
2014.02.16 14:51:59.119 4: CUL_Parse: CUL_HM A 0E 1A 8010 23B992 F11234 02080000005A -29
2014.02.16 14:51:59.222 4: CUL_send: CUL_HMAs 0B 1B A001 F11234 23B992 0603
2014.02.16 14:51:59.379 4: CUL_Parse: CUL_HM A 0E 1B 8010 23B992 F11234 01000000005A -29
2014.02.16 14:51:59.482 4: CUL_send: CUL_HMAs 10 1C A001 F11234 23B992 06040000000001
2014.02.16 14:51:59.640 4: CUL_Parse: CUL_HM A 0E 1C 8010 23B992 F11234 02080000005A -29
2014.02.16 14:51:59.743 4: CUL_send: CUL_HMAs 10 1D A001 F11234 23B992 04040000000001
2014.02.16 14:51:59.901 4: CUL_Parse: CUL_HM A 0E 1D 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:00.004 4: CUL_send: CUL_HMAs 10 1E A001 F11234 23B992 00040000000007
2014.02.16 14:52:00.173 4: CUL_Parse: CUL_HM A 1A 1E A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:52:00.236 4: CUL_send: CUL_HMAs 0A 1E 8002 F11234 23B992 00
2014.02.16 14:52:00.430 4: CUL_Parse: CUL_HM A 1A 1F A010 23B992 F11234 03100000098E386C487856B455144120455A -29
2014.02.16 14:52:00.493 4: CUL_send: CUL_HMAs 0A 1F 8002 F11234 23B992 00
2014.02.16 14:52:00.688 4: CUL_Parse: CUL_HM A 1A 20 A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:52:00.751 4: CUL_send: CUL_HMAs 0A 20 8002 F11234 23B992 00
2014.02.16 14:52:00.946 4: CUL_Parse: CUL_HM A 1A 21 A010 23B992 F11234 032E386C487856B454FC412045204520455A -29
2014.02.16 14:52:01.009 4: CUL_send: CUL_HMAs 0A 21 8002 F11234 23B992 00
2014.02.16 14:52:01.204 4: CUL_Parse: CUL_HM A 1A 22 A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:52:01.267 4: CUL_send: CUL_HMAs 0A 22 8002 F11234 23B992 00
2014.02.16 14:52:01.461 4: CUL_Parse: CUL_HM A 1A 23 A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:52:01.524 4: CUL_send: CUL_HMAs 0A 23 8002 F11234 23B992 00
2014.02.16 14:52:01.719 4: CUL_Parse: CUL_HM A 1A 24 A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:52:01.782 4: CUL_send: CUL_HMAs 0A 24 8002 F11234 23B992 00
2014.02.16 14:52:01.977 4: CUL_Parse: CUL_HM A 1A 25 A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:02.040 4: CUL_send: CUL_HMAs 0A 25 8002 F11234 23B992 00
2014.02.16 14:52:02.234 4: CUL_Parse: CUL_HM A 1A 26 A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:52:02.297 4: CUL_send: CUL_HMAs 0A 26 8002 F11234 23B992 00
2014.02.16 14:52:02.492 4: CUL_Parse: CUL_HM A 1A 27 A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:52:02.555 4: CUL_send: CUL_HMAs 0A 27 8002 F11234 23B992 00
2014.02.16 14:52:02.750 4: CUL_Parse: CUL_HM A 1A 28 A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:52:02.813 4: CUL_send: CUL_HMAs 0A 28 8002 F11234 23B992 00
2014.02.16 14:52:03.007 4: CUL_Parse: CUL_HM A 1A 29 A010 23B992 F11234 03A64520452045204520452038664878565A -29
2014.02.16 14:52:03.071 4: CUL_send: CUL_HMAs 0A 29 8002 F11234 23B992 00
2014.02.16 14:52:03.265 4: CUL_Parse: CUL_HM A 1A 2A A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:03.328 4: CUL_send: CUL_HMAs 0A 2A 8002 F11234 23B992 00
2014.02.16 14:52:03.520 4: CUL_Parse: CUL_HM A 17 2B A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:03.583 4: CUL_send: CUL_HMAs 0A 2B 8002 F11234 23B992 00
2014.02.16 14:52:03.778 4: CUL_Parse: CUL_HM A 17 2B A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:03.841 4: CUL_send: CUL_HMAs 0A 2B 8002 F11234 23B992 00
2014.02.16 14:52:04.024 4: CUL_Parse: CUL_HM A 0B 2C 8010 23B992 F11234 03005A -29
2014.02.16 14:52:04.127 4: CUL_send: CUL_HMAs 0B 1F A001 F11234 23B992 0503
2014.02.16 14:52:04.284 4: CUL_Parse: CUL_HM A 0E 1F 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:04.387 4: CUL_send: CUL_HMAs 10 20 A001 F11234 23B992 05040000000001
2014.02.16 14:52:04.545 4: CUL_Parse: CUL_HM A 0E 20 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:04.649 4: CUL_send: CUL_HMAs 0B 21 A001 F11234 23B992 0603
2014.02.16 14:52:04.806 4: CUL_Parse: CUL_HM A 0E 21 8010 23B992 F11234 01000000005B -28.5
2014.02.16 14:52:04.910 4: CUL_send: CUL_HMAs 10 22 A001 F11234 23B992 06040000000001
2014.02.16 14:52:05.066 4: CUL_Parse: CUL_HM A 0E 22 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:05.170 4: CUL_send: CUL_HMAs 0B 23 A011 F11234 23B992 8004
2014.02.16 14:52:05.323 4: CUL_Parse: CUL_HM A 0A 23 8002 23B992 F11234 005A -29
2014.02.16 14:52:05.426 4: CUL_send: CUL_HMAs 10 24 A001 F11234 23B992 04040000000001
2014.02.16 14:52:05.584 4: CUL_Parse: CUL_HM A 0E 24 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:05.687 4: CUL_send: CUL_HMAs 10 25 A001 F11234 23B992 00040000000007
2014.02.16 14:52:05.855 4: CUL_Parse: CUL_HM A 1A 25 A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:52:05.919 4: CUL_send: CUL_HMAs 0A 25 8002 F11234 23B992 00
2014.02.16 14:52:06.114 4: CUL_Parse: CUL_HM A 1A 26 A010 23B992 F11234 03100000098E386C487856B455144120455A -29
2014.02.16 14:52:06.177 4: CUL_send: CUL_HMAs 0A 26 8002 F11234 23B992 00
2014.02.16 14:52:06.370 4: CUL_Parse: CUL_HM A 1A 27 A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:52:06.435 4: CUL_send: CUL_HMAs 0A 27 8002 F11234 23B992 00
2014.02.16 14:52:06.628 4: CUL_Parse: CUL_HM A 1A 28 A010 23B992 F11234 032E386C487856B454FC412045204520455A -29
2014.02.16 14:52:06.692 4: CUL_send: CUL_HMAs 0A 28 8002 F11234 23B992 00
2014.02.16 14:52:06.886 4: CUL_Parse: CUL_HM A 1A 29 A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:52:06.950 4: CUL_send: CUL_HMAs 0A 29 8002 F11234 23B992 00
2014.02.16 14:52:07.145 4: CUL_Parse: CUL_HM A 1A 2A A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:52:07.208 4: CUL_send: CUL_HMAs 0A 2A 8002 F11234 23B992 00
2014.02.16 14:52:07.402 4: CUL_Parse: CUL_HM A 1A 2B A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:52:07.465 4: CUL_send: CUL_HMAs 0A 2B 8002 F11234 23B992 00
2014.02.16 14:52:07.660 4: CUL_Parse: CUL_HM A 1A 2C A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:07.723 4: CUL_send: CUL_HMAs 0A 2C 8002 F11234 23B992 00
2014.02.16 14:52:07.918 4: CUL_Parse: CUL_HM A 1A 2D A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:52:07.981 4: CUL_send: CUL_HMAs 0A 2D 8002 F11234 23B992 00
2014.02.16 14:52:08.175 4: CUL_Parse: CUL_HM A 1A 2E A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:52:08.238 4: CUL_send: CUL_HMAs 0A 2E 8002 F11234 23B992 00
2014.02.16 14:52:08.433 4: CUL_Parse: CUL_HM A 1A 2F A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:52:08.496 4: CUL_send: CUL_HMAs 0A 2F 8002 F11234 23B992 00
2014.02.16 14:52:08.691 4: CUL_Parse: CUL_HM A 1A 30 A010 23B992 F11234 03A64520452045204520452038664878565A -29
2014.02.16 14:52:08.754 4: CUL_send: CUL_HMAs 0A 30 8002 F11234 23B992 00
2014.02.16 14:52:08.948 4: CUL_Parse: CUL_HM A 1A 31 A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:09.184 4: CUL_send: CUL_HMAs 0A 31 8002 F11234 23B992 00
2014.02.16 14:52:09.464 4: CUL_Parse: CUL_HM A 1A 31 A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:09.527 4: CUL_send: CUL_HMAs 0A 31 8002 F11234 23B992 00
2014.02.16 14:52:09.719 4: CUL_Parse: CUL_HM A 17 32 A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:09.782 4: CUL_send: CUL_HMAs 0A 32 8002 F11234 23B992 00
2014.02.16 14:52:09.965 4: CUL_Parse: CUL_HM A 0B 33 8010 23B992 F11234 03005A -29
2014.02.16 14:52:10.144 4: CUL_send: CUL_HMAs 0B 26 A001 F11234 23B992 0503
2014.02.16 14:52:10.306 4: CUL_Parse: CUL_HM A 0E 26 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:10.410 4: CUL_send: CUL_HMAs 10 27 A001 F11234 23B992 05040000000001
2014.02.16 14:52:10.567 4: CUL_Parse: CUL_HM A 0E 27 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:10.670 4: CUL_send: CUL_HMAs 0B 28 A001 F11234 23B992 0603
2014.02.16 14:52:10.827 4: CUL_Parse: CUL_HM A 0E 28 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:10.930 4: CUL_send: CUL_HMAs 10 29 A001 F11234 23B992 06040000000001
2014.02.16 14:52:11.088 4: CUL_Parse: CUL_HM A 0E 29 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:11.191 4: CUL_send: CUL_HMAs 10 2A A001 F11234 23B992 04040000000001
2014.02.16 14:52:11.348 4: CUL_Parse: CUL_HM A 0E 2A 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:11.451 4: CUL_send: CUL_HMAs 10 2B A001 F11234 23B992 00040000000007
2014.02.16 14:52:11.619 4: CUL_Parse: CUL_HM A 1A 2B A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:52:11.682 4: CUL_send: CUL_HMAs 0A 2B 8002 F11234 23B992 00
2014.02.16 14:52:11.877 4: CUL_Parse: CUL_HM A 1A 2C A010 23B992 F11234 03100000098E386C487856B455144120455A -29
2014.02.16 14:52:11.940 4: CUL_send: CUL_HMAs 0A 2C 8002 F11234 23B992 00
2014.02.16 14:52:12.135 4: CUL_Parse: CUL_HM A 1A 2D A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:52:12.198 4: CUL_send: CUL_HMAs 0A 2D 8002 F11234 23B992 00
2014.02.16 14:52:12.392 4: CUL_Parse: CUL_HM A 1A 2E A010 23B992 F11234 032E386C487856B454FC412045204520455A -29
2014.02.16 14:52:12.455 4: CUL_send: CUL_HMAs 0A 2E 8002 F11234 23B992 00
2014.02.16 14:52:12.650 4: CUL_Parse: CUL_HM A 1A 2F A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:52:12.713 4: CUL_send: CUL_HMAs 0A 2F 8002 F11234 23B992 00
2014.02.16 14:52:12.908 4: CUL_Parse: CUL_HM A 1A 30 A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:52:12.971 4: CUL_send: CUL_HMAs 0A 30 8002 F11234 23B992 00
2014.02.16 14:52:13.165 4: CUL_Parse: CUL_HM A 1A 31 A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:52:13.228 4: CUL_send: CUL_HMAs 0A 31 8002 F11234 23B992 00
2014.02.16 14:52:13.423 4: CUL_Parse: CUL_HM A 1A 32 A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:13.486 4: CUL_send: CUL_HMAs 0A 32 8002 F11234 23B992 00
2014.02.16 14:52:13.681 4: CUL_Parse: CUL_HM A 1A 33 A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:52:13.744 4: CUL_send: CUL_HMAs 0A 33 8002 F11234 23B992 00
2014.02.16 14:52:13.938 4: CUL_Parse: CUL_HM A 1A 34 A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:52:14.001 4: CUL_send: CUL_HMAs 0A 34 8002 F11234 23B992 00
2014.02.16 14:52:14.206 4: CUL_Parse: CUL_HM A 1A 35 A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:52:14.262 4: CUL_send: CUL_HMAs 0A 35 8002 F11234 23B992 00
2014.02.16 14:52:14.455 4: CUL_Parse: CUL_HM A 1A 36 A010 23B992 F11234 03A64520452045204520452038664878565A -29
2014.02.16 14:52:14.519 4: CUL_send: CUL_HMAs 0A 36 8002 F11234 23B992 00
2014.02.16 14:52:14.711 4: CUL_Parse: CUL_HM A 1A 37 A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:14.775 4: CUL_send: CUL_HMAs 0A 37 8002 F11234 23B992 00
2014.02.16 14:52:14.966 4: CUL_Parse: CUL_HM A 17 38 A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:15.036 4: CUL_send: CUL_HMAs 0A 38 8002 F11234 23B992 00
2014.02.16 14:52:15.213 4: CUL_Parse: CUL_HM A 0B 39 8010 23B992 F11234 03005A -29
2014.02.16 14:52:15.325 4: CUL_send: CUL_HMAs 0B 2C A001 F11234 23B992 0503
2014.02.16 14:52:15.483 4: CUL_Parse: CUL_HM A 0E 2C 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:15.586 4: CUL_send: CUL_HMAs 10 2D A001 F11234 23B992 05040000000001
2014.02.16 14:52:15.743 4: CUL_Parse: CUL_HM A 0E 2D 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:15.847 4: CUL_send: CUL_HMAs 0B 2E A001 F11234 23B992 0603
2014.02.16 14:52:16.006 4: CUL_Parse: CUL_HM A 0E 2E 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:16.110 4: CUL_send: CUL_HMAs 10 2F A001 F11234 23B992 06040000000001
2014.02.16 14:52:16.266 4: CUL_Parse: CUL_HM A 0E 2F 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:16.370 4: CUL_send: CUL_HMAs 10 30 A001 F11234 23B992 04040000000001
2014.02.16 14:52:16.526 4: CUL_Parse: CUL_HM A 0E 30 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:16.630 4: CUL_send: CUL_HMAs 10 31 A001 F11234 23B992 00040000000007
2014.02.16 14:52:16.797 4: CUL_Parse: CUL_HM A 1A 31 A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:52:16.861 4: CUL_send: CUL_HMAs 0A 31 8002 F11234 23B992 00
2014.02.16 14:52:17.056 4: CUL_Parse: CUL_HM A 1A 32 A010 23B992 F11234 03100000098E386C487856B455144120455B -28.5
2014.02.16 14:52:17.119 4: CUL_send: CUL_HMAs 0A 32 8002 F11234 23B992 00
2014.02.16 14:52:17.314 4: CUL_Parse: CUL_HM A 1A 33 A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:52:17.377 4: CUL_send: CUL_HMAs 0A 33 8002 F11234 23B992 00
2014.02.16 14:52:17.571 4: CUL_Parse: CUL_HM A 1A 34 A010 23B992 F11234 032E386C487856B454FC412045204520455B -28.5
2014.02.16 14:52:17.634 4: CUL_send: CUL_HMAs 0A 34 8002 F11234 23B992 00
2014.02.16 14:52:17.829 4: CUL_Parse: CUL_HM A 1A 35 A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:52:17.893 4: CUL_send: CUL_HMAs 0A 35 8002 F11234 23B992 00
2014.02.16 14:52:18.087 4: CUL_Parse: CUL_HM A 1A 36 A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:52:18.150 4: CUL_send: CUL_HMAs 0A 36 8002 F11234 23B992 00
2014.02.16 14:52:18.344 4: CUL_Parse: CUL_HM A 1A 37 A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:52:18.408 4: CUL_send: CUL_HMAs 0A 37 8002 F11234 23B992 00
2014.02.16 14:52:18.602 4: CUL_Parse: CUL_HM A 1A 38 A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:18.665 4: CUL_send: CUL_HMAs 0A 38 8002 F11234 23B992 00
2014.02.16 14:52:18.860 4: CUL_Parse: CUL_HM A 1A 39 A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:52:18.923 4: CUL_send: CUL_HMAs 0A 39 8002 F11234 23B992 00
2014.02.16 14:52:19.118 4: CUL_Parse: CUL_HM A 1A 3A A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:52:19.184 4: CUL_send: CUL_HMAs 0A 3A 8002 F11234 23B992 00
2014.02.16 14:52:19.375 4: CUL_Parse: CUL_HM A 1A 3B A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:52:19.438 4: CUL_send: CUL_HMAs 0A 3B 8002 F11234 23B992 00
2014.02.16 14:52:19.633 4: CUL_Parse: CUL_HM A 1A 3C A010 23B992 F11234 03A64520452045204520452038664878565A -29
2014.02.16 14:52:19.696 4: CUL_send: CUL_HMAs 0A 3C 8002 F11234 23B992 00
2014.02.16 14:52:19.891 4: CUL_Parse: CUL_HM A 1A 3D A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:19.954 4: CUL_send: CUL_HMAs 0A 3D 8002 F11234 23B992 00
2014.02.16 14:52:20.145 4: CUL_Parse: CUL_HM A 17 3E A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:20.218 4: CUL_send: CUL_HMAs 0A 3E 8002 F11234 23B992 00
2014.02.16 14:52:20.392 4: CUL_Parse: CUL_HM A 0B 3F 8010 23B992 F11234 03005A -29
2014.02.16 14:52:20.495 4: CUL_send: CUL_HMAs 0B 32 A001 F11234 23B992 0503
2014.02.16 14:52:20.652 4: CUL_Parse: CUL_HM A 0E 32 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:20.756 4: CUL_send: CUL_HMAs 10 33 A001 F11234 23B992 05040000000001
2014.02.16 14:52:20.913 4: CUL_Parse: CUL_HM A 0E 33 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:21.016 4: CUL_send: CUL_HMAs 0B 34 A001 F11234 23B992 0603
2014.02.16 14:52:21.173 4: CUL_Parse: CUL_HM A 0E 34 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:21.276 4: CUL_send: CUL_HMAs 10 35 A001 F11234 23B992 06040000000001
2014.02.16 14:52:21.434 4: CUL_Parse: CUL_HM A 0E 35 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:21.537 4: CUL_send: CUL_HMAs 10 36 A001 F11234 23B992 04040000000001
2014.02.16 14:52:21.695 4: CUL_Parse: CUL_HM A 0E 36 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:21.798 4: CUL_send: CUL_HMAs 10 37 A001 F11234 23B992 00040000000007
2014.02.16 14:52:21.967 4: CUL_Parse: CUL_HM A 1A 37 A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:52:22.030 4: CUL_send: CUL_HMAs 0A 37 8002 F11234 23B992 00
2014.02.16 14:52:22.224 4: CUL_Parse: CUL_HM A 1A 38 A010 23B992 F11234 03100000098E386C487856B455144120455A -29
2014.02.16 14:52:22.287 4: CUL_send: CUL_HMAs 0A 38 8002 F11234 23B992 00
2014.02.16 14:52:22.482 4: CUL_Parse: CUL_HM A 1A 39 A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:52:22.546 4: CUL_send: CUL_HMAs 0A 39 8002 F11234 23B992 00
2014.02.16 14:52:22.740 4: CUL_Parse: CUL_HM A 1A 3A A010 23B992 F11234 032E386C487856B454FC412045204520455A -29
2014.02.16 14:52:22.803 4: CUL_send: CUL_HMAs 0A 3A 8002 F11234 23B992 00
2014.02.16 14:52:22.997 4: CUL_Parse: CUL_HM A 1A 3B A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:52:23.060 4: CUL_send: CUL_HMAs 0A 3B 8002 F11234 23B992 00
2014.02.16 14:52:23.255 4: CUL_Parse: CUL_HM A 1A 3C A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:52:23.318 4: CUL_send: CUL_HMAs 0A 3C 8002 F11234 23B992 00
2014.02.16 14:52:23.513 4: CUL_Parse: CUL_HM A 1A 3D A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:52:23.576 4: CUL_send: CUL_HMAs 0A 3D 8002 F11234 23B992 00
2014.02.16 14:52:23.770 4: CUL_Parse: CUL_HM A 1A 3E A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:23.833 4: CUL_send: CUL_HMAs 0A 3E 8002 F11234 23B992 00
2014.02.16 14:52:24.028 4: CUL_Parse: CUL_HM A 1A 3F A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:52:24.091 4: CUL_send: CUL_HMAs 0A 3F 8002 F11234 23B992 00
2014.02.16 14:52:24.286 4: CUL_Parse: CUL_HM A 1A 40 A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:52:24.353 4: CUL_send: CUL_HMAs 0A 40 8002 F11234 23B992 00
2014.02.16 14:52:24.543 4: CUL_Parse: CUL_HM A 1A 41 A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:52:24.606 4: CUL_send: CUL_HMAs 0A 41 8002 F11234 23B992 00
2014.02.16 14:52:24.801 4: CUL_Parse: CUL_HM A 1A 42 A010 23B992 F11234 03A64520452045204520452038664878565A -29
2014.02.16 14:52:24.864 4: CUL_send: CUL_HMAs 0A 42 8002 F11234 23B992 00
2014.02.16 14:52:25.059 4: CUL_Parse: CUL_HM A 1A 43 A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:25.123 4: CUL_send: CUL_HMAs 0A 43 8002 F11234 23B992 00
2014.02.16 14:52:25.314 4: CUL_Parse: CUL_HM A 17 44 A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:25.377 4: CUL_send: CUL_HMAs 0A 44 8002 F11234 23B992 00
2014.02.16 14:52:25.572 4: CUL_Parse: CUL_HM A 17 44 A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:25.636 4: CUL_send: CUL_HMAs 0A 44 8002 F11234 23B992 00
2014.02.16 14:52:25.818 4: CUL_Parse: CUL_HM A 0B 45 8010 23B992 F11234 03005A -29
2014.02.16 14:52:25.911 4: CUL_send: CUL_HMAs 0B 38 A001 F11234 23B992 0503
2014.02.16 14:52:26.069 4: CUL_Parse: CUL_HM A 0E 38 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:26.230 4: CUL_send: CUL_HMAs 10 39 A001 F11234 23B992 05040000000001
2014.02.16 14:52:26.387 4: CUL_Parse: CUL_HM A 0E 39 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:26.491 4: CUL_send: CUL_HMAs 0B 3A A001 F11234 23B992 0603
2014.02.16 14:52:26.647 4: CUL_Parse: CUL_HM A 0E 3A 8010 23B992 F11234 01000000005A -29
2014.02.16 14:52:26.751 4: CUL_send: CUL_HMAs 10 3B A001 F11234 23B992 06040000000001
2014.02.16 14:52:26.908 4: CUL_Parse: CUL_HM A 0E 3B 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:27.012 4: CUL_send: CUL_HMAs 10 3C A001 F11234 23B992 04040000000001
2014.02.16 14:52:27.168 4: CUL_Parse: CUL_HM A 0E 3C 8010 23B992 F11234 02080000005A -29
2014.02.16 14:52:27.272 4: CUL_send: CUL_HMAs 10 3D A001 F11234 23B992 00040000000007
2014.02.16 14:52:27.441 4: CUL_Parse: CUL_HM A 1A 3D A010 23B992 F11234 03012A20093D18030016073000640F05005A -29
2014.02.16 14:52:27.504 4: CUL_send: CUL_HMAs 0A 3D 8002 F11234 23B992 00
2014.02.16 14:52:27.698 4: CUL_Parse: CUL_HM A 1A 3E A010 23B992 F11234 03100000098E386C487856B455144120455A -29
2014.02.16 14:52:27.761 4: CUL_send: CUL_HMAs 0A 3E 8002 F11234 23B992 00
2014.02.16 14:52:27.956 4: CUL_Parse: CUL_HM A 1A 3F A010 23B992 F11234 031F2045204520452045204520452045205A -29
2014.02.16 14:52:28.019 4: CUL_send: CUL_HMAs 0A 3F 8002 F11234 23B992 00
2014.02.16 14:52:28.214 4: CUL_Parse: CUL_HM A 1A 40 A010 23B992 F11234 032E386C487856B454FC412045204520455B -28.5
2014.02.16 14:52:28.277 4: CUL_send: CUL_HMAs 0A 40 8002 F11234 23B992 00
2014.02.16 14:52:28.471 4: CUL_Parse: CUL_HM A 1A 41 A010 23B992 F11234 033D2045204520452045204520386048785A -29
2014.02.16 14:52:28.534 4: CUL_send: CUL_HMAs 0A 41 8002 F11234 23B992 00
2014.02.16 14:52:28.729 4: CUL_Parse: CUL_HM A 1A 42 A010 23B992 F11234 034C56C0550E41204520452045204520455A -29
2014.02.16 14:52:28.792 4: CUL_send: CUL_HMAs 0A 42 8002 F11234 23B992 00
2014.02.16 14:52:28.987 4: CUL_Parse: CUL_HM A 1A 43 A010 23B992 F11234 035B204520452045203866487856C0550E5A -29
2014.02.16 14:52:29.050 4: CUL_send: CUL_HMAs 0A 43 8002 F11234 23B992 00
2014.02.16 14:52:29.244 4: CUL_Parse: CUL_HM A 1A 44 A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:29.307 4: CUL_send: CUL_HMAs 0A 44 8002 F11234 23B992 00
2014.02.16 14:52:29.760 4: CUL_Parse: CUL_HM A 1A 44 A010 23B992 F11234 036A4120452045204520452045204520455A -29
2014.02.16 14:52:29.823 4: CUL_send: CUL_HMAs 0A 44 8002 F11234 23B992 00
2014.02.16 14:52:30.017 4: CUL_Parse: CUL_HM A 1A 45 A010 23B992 F11234 03792045203866487856C0550E412045205A -29
2014.02.16 14:52:30.080 4: CUL_send: CUL_HMAs 0A 45 8002 F11234 23B992 00
2014.02.16 14:52:30.275 4: CUL_Parse: CUL_HM A 1A 46 A010 23B992 F11234 03884520452045204520452045204520385A -29
2014.02.16 14:52:30.338 4: CUL_send: CUL_HMAs 0A 46 8002 F11234 23B992 00
2014.02.16 14:52:30.533 4: CUL_Parse: CUL_HM A 1A 47 A010 23B992 F11234 039766487856C0550E41204520452045205A -29
2014.02.16 14:52:30.596 4: CUL_send: CUL_HMAs 0A 47 8002 F11234 23B992 00
2014.02.16 14:52:30.790 4: CUL_Parse: CUL_HM A 1A 48 A010 23B992 F11234 03A64520452045204520452038664878565B -28.5
2014.02.16 14:52:30.853 4: CUL_send: CUL_HMAs 0A 48 8002 F11234 23B992 00
2014.02.16 14:52:31.048 4: CUL_Parse: CUL_HM A 1A 49 A010 23B992 F11234 03B5B4550E4120452045204520452045205A -29
2014.02.16 14:52:31.111 4: CUL_send: CUL_HMAs 0A 49 8002 F11234 23B992 00
2014.02.16 14:52:31.303 4: CUL_Parse: CUL_HM A 17 4A A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:31.366 4: CUL_send: CUL_HMAs 0A 4A 8002 F11234 23B992 00
2014.02.16 14:52:31.561 4: CUL_Parse: CUL_HM A 17 4A A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:31.624 4: CUL_send: CUL_HMAs 0A 4A 8002 F11234 23B992 00
2014.02.16 14:52:31.818 4: CUL_Parse: CUL_HM A 17 4A A010 23B992 F11234 03C445204520452012212A0F1E1E5A -29
2014.02.16 14:52:31.881 4: CUL_send: CUL_HMAs 0A 4A 8002 F11234 23B992 00
Sorry für die Länge
George
Hi,
a) lange daten kann man als file hier einhängen
b) die Verarbeitung sieht erst einmal gut aus - ich kann nicht erkennen, dass es zu Problemen gekommen ist
c) du kannst ein das erst einmal reseten mit
set ThermoBuero1 clear mshEvent
d) mit der neuen Version (ab morgen im Update) sollte es zumindest besser werden, was das automatische Abfragen betrifft.
nach einem reset (clear) kannst du es noch einmal langsam beobachten - ob es sich noch einmal "aufbaut"
Gruss Martin
Danke Martin,
ich habe jetzt erst mal mit "clear" geputzt:
STATE Info_Cleared
und fasse bis zum Update 'mal nichts an.
George
Zitat von: martinp876 am 16 Februar 2014, 14:43:35
@herr3x
wenn du das in die Kommandozeile pastest kannst du das select ausmessen - und die test-werde ändern...
Äh, was messe ich denn da? :o
1x aufgerufen:
0.2 - min:104 max:104
0.1 - min:92 max:922x
0.2 - min:37 max:37
0.1 - min:68 max:683x
0.2 - min:70 max:70
0.1 - min:93 max:93Da werde ich nicht draus schlau.
Herr 3x
sorry, dachte du kannst es lesen.
Zitat{my @tt = (0.1,0.2);;my %r;;\
test for 0.1 und 0.2 sec (also 100 und 200 ms). Die Liste kannst du verlängern.
Zitatforeach my $ti(@tt){foreach(1..10){\
10 wiederholungen
0.2 - min:104 max:104
bei den 10 versuchen mit 200ms hatte dein System eine Abweichung von (min = max) 104ms
0.1 - min:92 max:92
bei den 10 versuchen mit 100ms hatte dein System eine Abweichung von (min = max) 92ms
bei weiteren versuchen sieht man, dass es nicht stabil ist.
Bei mir sieht es so aus: keine Abweichung.
0.2 - min:0 max:0
0.1 - min:0 max:0
Dein system scheint nicht hier stabil zu rechnen
Gruss Martin
Hallo,
nach dem "clear" gab es keine neu Abgesetzten Kommandos mehr. Heute Morgen habe ich probehalber
ein "tempListMon" abgesetzt und der Spuck geht neu los. Das Kommando wurde aber umgesetzt.
Gruß, George
kannst du rohmessage sammeln?
Hey! es hat sich was getan. Kurz vo Deiner email habe ich ein update gemacht und CUL_HM war auch dabei. Jetzt arbeitet es den Comandstack ab!
Anbei ist das Logfile
nicht ganz klar was passiert - und ob es ein Fehler ist.
Es wurde 2mal getConfig gemacht - das koennte schon einmal vorkommen, je nachdem wie sich das timing ueberschneidet.
Bei der ersten Sequenz ist ein Fehler aufgetreten - deine CUL hat zu langsam geantwortet. Das getConfig wurde sauber bgebrochen und beim naechsten Aufwachen kompletiert wie geplant.
Beim 2. getConfig gab es keine Probleme.
Aktuell kann ich nicht sehen, dass sich diese autoReads aufschaukeln koennen - insbesondere nach der Aenderung von gestern.
Es gibt jeweils einen slow-check nach 30min - wenn ein getConfig nach dieser Zeit nicht abgearbeitet wurde, haette es evtl zu dem beschreibenen Problem kommen koennen... jetzt eigentich nicht mehr.
Beobachte noch einmal - wenn der cmdStack waechst noch einmal melden.
Zur Uberwachung des Systems empfiehlt sich HMInfo protEvents short - da steht auch die stackgroesse drin
Gruss Martin
Hallo Martin,
das Problem ist immer noch da von 9:36h bis 11:45h waren es 252 Meldungen im CUL_HM_MSGCNT, allerdings
nur
protCmdPend 7 CMDs_pending
der Reihen nach waren das mehrere getConfigs auf die subdevices.
Ich habe jetzt nochmal geputzt und ein einzelnes sysTime, ohne burstXmit
abgesetzt.
Hier ist das Resultat:
2014.02.17 12:16:55 2: CUL_HM set ThermoBuero1 clear msgEvents
2014.02.17 12:22:23.000 4: CUL_Parse: CUL_HM A 0F F6 8610 23B992 000000 0AAC9D1064185B -28.5
2014.02.17 12:24:26.501 4: CUL_Parse: CUL_HM A 0F F7 8610 23B992 000000 0AAC9E1064185B -28.5
2014.02.17 12:24:26.604 4: CUL_send: CUL_HMAs 09 81 A112 F11234 23B992
2014.02.17 12:24:26.757 4: CUL_Parse: CUL_HM A 0A 81 8002 23B992 F11234 005B -28.5
2014.02.17 12:24:26.860 4: CUL_send: CUL_HMAs 0F 82 803F F11234 23B992 02041A949F68
2014.02.17 12:27:19.754 4: CUL_Parse: CUL_HM A 0F F8 8610 23B992 000000 0AACA11064185B -28.5
der MSGCNT ist um drei hoch, und STATE CMDs_done
danach einn getConfig ThermoBuero1_remote, denn der war der letzte im Log
2014.02.17 12:34:32.512 4: CUL_Parse: CUL_HM A 0F FB 8610 23B992 000000 0AACA41064185B -28.5
2014.02.17 12:34:32.615 4: CUL_send: CUL_HMAs 09 83 A112 F11234 23B992
2014.02.17 12:34:32.769 4: CUL_Parse: CUL_HM A 0A 83 8002 23B992 F11234 005B -28.5
2014.02.17 12:34:32.872 4: CUL_send: CUL_HMAs 0B 84 A001 F11234 23B992 0603
2014.02.17 12:34:33.029 4: CUL_Parse: CUL_HM A 0E 84 8010 23B992 F11234 01000000005B -28.5
2014.02.17 12:34:33.132 4: CUL_send: CUL_HMAs 10 85 A001 F11234 23B992 06040000000001
2014.02.17 12:34:33.290 4: CUL_Parse: CUL_HM A 0E 85 8010 23B992 F11234 02080000005B -28.5
2014.02.17 12:37:32.015 4: CUL_Parse: CUL_HM A 0F FC 8610 23B992 000000 0AACA41064185A -29
Wurde auch abgearbeitet.
Jetze ich noch ein getConfig auf den ganzen device abgeschickt. Das Resultat ist wieder sehr länglich, ABER :
CMDs_done
Das Logfile habe ich angehängt.
Gruß, George
ist alles sauber bearbeitet.
was sagt ein HMInfo checkConfig? Glaubt es, das hier etwas fehlt?
fhem> set hm configCheck
configCheck done:
Gestern (vor dem Update) kamen Meldungen. Genau erinnere ich mich nicht, aber Register 07 hatte ein Problem
wenn ein register nicht komplett vorhanden ist wird es noch einmal probiert. So die Idee, wenn der User dies nicht abstellt (autoRegRead).
Danke Martin,
das klingt jetzt doch alles einmal gut. Dann kann ich mich ja daran wagen mehrere Endgeräte zu kaufen.
Gibt es gute Gründe einen HMLAN zu kaufen statt beim CUL zu bleiben (Geld habe ich nicht zu verschenken,
das ganze soll ja unter anderem eine Sparmaßnahme sein.)
Gruß, George
HMLAN ist besser geeignet fuer HM, CUL ist universeller.
HMLAN/USB bieten
- besseres Timing dank timestamps
- besseres ACK-timing, da im device selbst erledigt
- AES support
Fraglich:
HMLAN hat ein keepalive - sehe ich als feature, manche sehen es als Problem
CUL bietet
- komplett transparenten durchgriff (leichte Vorteile bei virtuellen Devices)
- Universell einsetzbar fuer andere Systeme (nicht HM relevant)
- kein drosseln der message load (zwar nicht standardkonform... )
Ich wuerde ein HMLAN/usb nehmen. Wenn schon eine CUL da ist und keine Probleme macht ist es ausreichend.
Gruss Martin
Dann rüste ich erst einmal mit Heizkörperthermostaten auf und falls ich nicht zufrieden bin kaufe ich einen HMLAN nach.
Danke.
p.s. die Info wäre eine gute Entscheidungshilfe für Anfänger im Wiki
Hallo Martin,
ich habe das Register dayTemp verändern wollen.
Jetzt hängen wieder einige Komados. insbesondere zeigt aber jetzt HMinfo folgendes:
configCheck done:
missing register list
ThermoBuero1_Clima: RegL_01:
ThermoBuero1_ClimaTeam: RegL_01:
ThermoBuero1_remote: RegL_01:
incomplete register list
ThermoBuero1_Clima: RegL_07:
Register changes pending
ThermoBuero1_Clima
peer list not read
empty: ThermoBuero1_ClimaTeam
empty: ThermoBuero1_remote
Martin,
es hat dann doch geklappt, allerdings mit 130 Messages und einem Pegel von 9 Cmds in der Schlange für ca. 15 Minuten.
Die letzten habe ich mitgeschnitten, weil ich mir dachte, daß Du danach fragst ;)
zu wissen:
wenn man den RT register aendert braucht er meist eine pause. Man kann dann erst wieder in 2,5min mit ihm reden. Bei default-einstellungen wird danach ein getConfig ausgefuehrt.
So du mehrere Register aenderst mit 'exec' wird nach jedem schreiben ein Pause gemacht. Das kann dann schon einmal dauern.
wird in unterschiedliche Listen geschrieben kommt auch eine Pause.
genau das passiert in dem kurzen Ausschnitt: schreiben - leseversuch(fehler) - pause - lesen korrekt
besser geht es nicht