[Gelöst] CUL + HMLAN / Unzuverlässiges Verhalten von HM-CC-VD mit virtuellem TC

[frank]

Ich verstehe es so: Zeile 1 und 2, HMLAN sendet an VD, Antwort vom VD.

vielleicht.

2015.09.29 22:49:20.430 0: HMLAN_Send:  HMLAN1 S:S1ADDE37D stat:  00 t:00000000 d:01 r:1ADDE37D m:AF A258 22CE02 204323 0033

1. vtc => vd. braun: message nr, rot: command type, blau: sender, grün: empfänger, rest: payload.

2015.09.29 22:49:20.961 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2656D88D d:FF r:FFC9     m:AF 8202 204323 22CE02 0101280030

2. antwort vd => vtc

2015.09.29 22:49:20.966 0: HMLAN_Parse: HMLAN1 R:R1ADDE37D stat:0008 t:00000000 d:FF r:7FFF     m:AF A258 22CE02 204323 0033

3. vermutlich das ack des hmlan an fhem (sendebestätigung). ist im prinzip die sendemsg.

2015.09.29 22:49:20.966 0: HMLAN_Parse: HMLAN1 no ACK from 204323

4. kommt immer, da hmlan unter falscher hmid (vtc) sendet und es dafür natürlich kein ack an die zentrale gab.

ich habe 5 vd erfolgreich am laufen mit hmlan und fritzbox. pro vd habe ich eventuell 5 einzelne miss in 24 stunden. also eine msg wird nicht beantwortet, aber die folgenden sind wieder ok. lost gibt es eigentlich gar nicht. nur wenn ich viel mit fhem arbeite.

es gibt einen thread tc emulieren. da sollte alles drinstehen.

Wenn, wie Du beschreibst, bei mir alles durcheinander sendet, wäre es eine Möglichkeit die vTCs ganz zu löschen, fhem neu zu starten und die dann wieder anzulegen?

dann muss fhem noch mehr infos ordern. eher schlecht. aber probieren geht über studieren. einen musst du doch eh neu pairen, dachte icht.

[franz27]

Hallo frank,

danke für Deine Hinweise, damit komme ich jetzt zumindest mit der Analyse besser klar. Richtig laufen tut es immer noch nicht.
Die Message-Nr. ist wohl eher als "Gesprächs"-Nr. zu verstehen und kennzeichnet zusammenhgehörige Nachrichten.

Das hier ist ein erstes ok nach einem längern Zeitraum mit miss. Das sieht auch immer ähnlich aus, mit 5 verschiendenen Message-Nr., hier F5, D9, F6, F7, F8.
Dabei ist die zweite Message-Nr. immer einiges kleiner als die erste, der Rest ist fortlaufend, wobei die D9, F6 und F8-Messages vom HMLAN kommen, F5 und F7 vom vTC.
Der Payload ist auch jeweils immer gleich.

2015.10.01 10:14:49.753 0: HMLAN_Send:  HMLAN1 S:S2277D4E9 stat:  00 t:00000000 d:01 r:2277D4E9 m:F5 A258 22CE02 204323 00CC
2015.10.01 10:14:49.758 0: HMLAN_Send:  HMLAN1 I:+204323,02,00,00
2015.10.01 10:14:50.015 0: HMLAN_Send:  HMLAN1 S:S2277D590 stat:  00 t:00000000 d:01 r:2277D590 m:D9 A112 24CE23 204323
2015.10.01 10:14:50.214 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF11DEA d:FF r:FFC9     m:F5 8202 204323 22CE02 0101B40030
2015.10.01 10:14:50.498 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF11EE3 d:FF r:FFC9     m:F5 8002 204323 24CE23 00
2015.10.01 10:14:50.513 0: HMLAN_Parse: HMLAN1 R:R226C967E stat:0008 t:00000000 d:FF r:7FFF     m:F5 A112 24CE23 204323
2015.10.01 10:14:50.514 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:14:50.712 0: HMLAN_Send:  HMLAN1 S:S2277D849 stat:  00 t:00000000 d:01 r:2277D849 m:F6 A112 24CE23 204323
2015.10.01 10:14:50.908 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF120A3 d:FF r:FFC9     m:F5 8202 204323 22CE02 0101B40030
2015.10.01 10:14:51.202 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF1219C d:FF r:FFCA     m:F5 8002 204323 24CE23 00
2015.10.01 10:14:51.223 0: HMLAN_Parse: HMLAN1 R:R226C967E stat:0008 t:00000000 d:FF r:7FFF     m:F5 A112 24CE23 204323
2015.10.01 10:14:51.224 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:14:51.507 0: HMLAN_Send:  HMLAN1 S:S2277DBC3 stat:  00 t:00000000 d:01 r:2277DBC3 m:F6 A112 24CE23 204323
2015.10.01 10:14:51.510 0: HMLAN_Parse: HMLAN1 R:R2277D4E9 stat:0008 t:00000000 d:FF r:7FFF     m:F5 A258 22CE02 204323 00CC
2015.10.01 10:14:51.511 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:14:51.511 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF1235D d:FF r:FFC9     m:F5 8202 204323 22CE02 0101B40030
2015.10.01 10:14:51.857 0: HMLAN_Parse: HMLAN1 R:R1425C324 stat:0081 t:2DF1245A d:FF r:FFC9     m:F5 8002 204323 24CE23 00
2015.10.01 10:14:51.968 0: HMLAN_Parse: HMLAN1 R:R2277D590 stat:0001 t:2DF125EC d:FF r:FFC9     m:D9 8002 204323 24CE23 00
2015.10.01 10:14:52.463 0: HMLAN_Send:  HMLAN1 S:S2277DF21 stat:  00 t:00000000 d:01 r:2277DF21 m:F7 A258 22CE02 204323 00CC
2015.10.01 10:14:52.469 0: HMLAN_Parse: HMLAN1 R:R2277D849 stat:0001 t:2DF12781 d:FF r:FFC9     m:F6 8002 204323 24CE23 00
2015.10.01 10:14:52.870 0: HMLAN_Send:  HMLAN1 S:S2277E0B7 stat:  00 t:00000000 d:01 r:2277E0B7 m:F8 A112 24CE23 204323
2015.10.01 10:14:53.066 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF12912 d:FF r:FFC9     m:F7 8202 204323 22CE02 0101B40030
2015.10.01 10:14:53.070 0: HMLAN_Parse: HMLAN1 R:R2277DBC3 stat:0008 t:00000000 d:FF r:7FFF     m:F6 A112 24CE23 204323
2015.10.01 10:14:53.070 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:14:53.191 0: HMLAN_Parse: HMLAN1 R:R226C967E stat:0081 t:2DF12A10 d:FF r:FFC9     m:F7 8002 204323 24CE23 00
2015.10.01 10:14:53.526 0: HMLAN_Send:  HMLAN1 S:S2277E347 stat:  00 t:00000000 d:01 r:2277E347 m:F8 A112 24CE23 204323
2015.10.01 10:14:53.721 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF12BA2 d:FF r:FFC9     m:F7 8202 204323 22CE02 0101B40030
2015.10.01 10:14:53.976 0: HMLAN_Parse: HMLAN1 R:R226C967E stat:0081 t:2DF12CA0 d:FF r:FFC9     m:F7 8002 204323 24CE23 00
2015.10.01 10:14:54.183 0: HMLAN_Send:  HMLAN1 S:S2277E5D7 stat:  00 t:00000000 d:01 r:2277E5D7 m:F8 A112 24CE23 204323
2015.10.01 10:14:54.376 0: HMLAN_Parse: HMLAN1 R:R2277DF21 stat:0008 t:00000000 d:FF r:7FFF     m:F7 A258 22CE02 204323 00CC
2015.10.01 10:14:54.376 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:14:54.377 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF12E33 d:FF r:FFC9     m:F7 8202 204323 22CE02 0101B40030
2015.10.01 10:14:54.485 0: HMLAN_Parse: HMLAN1 R:R1425C324 stat:0081 t:2DF12F31 d:FF r:FFC9     m:F7 8002 204323 24CE23 00
2015.10.01 10:14:54.739 0: HMLAN_Send:  HMLAN1 I:+204323,00,00,00
2015.10.01 10:14:54.744 0: HMLAN_Parse: HMLAN1 R:R2277E347 stat:0001 t:2DF130C2 d:FF r:FFC9     m:F8  8 8002 204323 24CE23 00
2015.10.01 10:14:54.806 0: HMLAN_Parse: HMLAN1 R:R2277E0B7 stat:0008 t:00000000 d:FF r:7FFF     m:F8 A112 24CE23 204323
2015.10.01 10:14:54.806 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:14:55.210 0: HMLAN_Parse: HMLAN1 R:R2277E5D7 stat:0001 t:2DF13298 d:FF r:FFC9     m:F8 8002 204323 24CE23 00

Hier ein zweites ok

2015.10.01 10:16:56.754 0: HMLAN_Send:  HMLAN1 S:S2279C501 stat:  00 t:00000000 d:01 r:2279C501 m:F6 A258 22CE02 204323 00CC
2015.10.01 10:16:57.285 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF30E16 d:FF r:FFC9     m:F6 8202 204323 22CE02 0101A00030
2015.10.01 10:16:57.289 0: HMLAN_Parse: HMLAN1 R:R2279C501 stat:0008 t:00000000 d:FF r:7FFF     m:F6 A258 22CE02 204323 00CC
2015.10.01 10:16:57.290 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:16:57.304 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF30F80 d:FF r:FFC9     m:F6 8202 204323 22CE02 0101A00030

und ein drittes

2015.10.01 10:19:53.256 0: HMLAN_Send:  HMLAN1 S:S227C7678 stat:  00 t:00000000 d:01 r:227C7678 m:F7 A258 22CE02 204323 00CC
2015.10.01 10:19:53.842 0: HMLAN_Parse: HMLAN1 R:R227C7678 stat:0008 t:00000000 d:FF r:7FFF     m:F7 A258 22CE02 204323 00CC
2015.10.01 10:19:53.842 0: HMLAN_Parse: HMLAN1 no ACK from 204323
2015.10.01 10:19:53.843 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF5C145 d:FF r:FFC9     m:F7 8202 204323 22CE02 0101A00030

dann das folgende miss

2015.10.01 10:22:35.258 0: HMLAN_Send:  HMLAN1 S:S227EEF49 stat:  00 t:00000000 d:01 r:227EEF49 m:F8 A258 22CE02 204323 00CC
2015.10.01 10:22:35.864 0: HMLAN_Parse: HMLAN1 R:R227EEF49 stat:0008 t:00000000 d:FF r:7FFF     m:F8 A258 22CE02 204323 00CC
2015.10.01 10:22:35.865 0: HMLAN_Parse: HMLAN1 no ACK from 204323

Ich habe mir auch den Quellcode zu in den FHEM Dateien angeschaut, A258 ist das Wakeup von Fhem/vTC an den VD, 8202 die "Jo, bin wach"-Antwort vom VD, 8002 (in diesem Fall mit 00) ein ACK vom VD.

Was mich sehr irritiert ist das beim ersten OK alles 4 mal gesendet wird, bei den zweien (F5 und F7) kommt ein ACK vom VD - kein Wunder, daß danach die Timer nicht stimmen, welcher von den beiden soll denn als Basis für die weitere Kommunikation genommen werden. Meistens kommt danach auch direkt wieder ein miss und es dauert eine Stunde bis zum nächsten OK.

Kann ich auch irgendwie die Timings protokollieren, also wann Fhem/vTC meinen die nächste Botschaft senden zu müssen und ob der Sendezeitpunkt dann auch passt?

[frank]

Was mich sehr irritiert ist das beim ersten OK alles 4 mal gesendet wird, bei den zweien (F5 und F7) kommt ein ACK vom VD - kein Wunder, daß danach die Timer nicht stimmen, welcher von den beiden soll denn als Basis für die weitere Kommunikation genommen werden. Meistens kommt danach auch direkt wieder ein miss und es dauert eine Stunde bis zum nächsten OK.

das meinte ich mit "durcheinanderquatschen".
vtc und die zentrale (fhem) wollen infos.

Code Auswählen Erweitern

2015.10.01 10:14:50.015 0: HMLAN_Send:  HMLAN1 S:S2277D590 stat:  00 t:00000000 d:01 r:2277D590 m:D9 A112 24CE23 204323
zentrale sendet hallo wach, ich habe was zu melden

Code Auswählen Erweitern

2015.10.01 10:14:50.498 0: HMLAN_Parse: HMLAN1 R:E204323   stat:0000 t:2DF11EE3 d:FF r:FFC9     m:F5 8002 204323 24CE23 00
es kommt auch eine antwort (ack) an die zentrale. aber die msgnummer stimmt nicht. normalerweise wird mit der selben nummer geantwortet. somit scheint die antwort in fhem nicht richtig zugeordnet zu werden, denn es wird weiterhin das hallo wach wiederholt anstatt mit der eigentlichen message zu starten. wahrscheinlich getconfig.

ich sehe gerade dass ich bei mir im vd das attr msgRepeat=0 gesetzt habe. das solltest du auch tun.
trotzdem bleibt die frage, was will die zentrale? hast du erfolgreich manuell getconfig durchgeführt? in hminfo gibt es keine auffälligkeiten?

trotz der langwierigen kommunikation, wurde aber das nächste kommunikationsfenster erfolgreich berechnet und gefunden. unter anderem wird die aktuelle msgnummer einer erfolgreichen kommunikation zur berechnung des nächsten fensters benutzt. ist der vd nach dem miss dann eingeschlafen? wenn der vd einschläft, obwohl es kein durcheinanderquatschen gab, müsste eigentlich fhem einen erheblichen freeze gehabt haben. lass doch mal das modul perfmon laufen.

Kann ich auch irgendwie die Timings protokollieren, also wann Fhem/vTC meinen die nächste Botschaft senden zu müssen und ob der Sendezeitpunkt dann auch passt?

verbose=5 beim vtc_chn

du könntest ja mal meine versionen probieren. vielleicht hat sich ein fehler eingeschlichen.

# $Id: 10_CUL_HM.pm 9173 2015-08-30 16:11:11Z martinp876 $
# $Id: 00_HMLAN.pm 9103 2015-08-22 05:23:08Z martinp876 $
# $Id: HMConfig.pm 9172 2015-08-30 15:54:59Z martinp876 $

[franz27]

ich sehe gerade dass ich bei mir im vd das attr msgRepeat=0 gesetzt habe. das solltest du auch tun.

Das scheints gewesen zu sein, läuft seit ca. 45 min stabil. 1x bei einem vTC miss_1 dann direkt wieder ok. Ist noch zu früh für eine definitive Aussage, aber sieht sehr gut aus.

Vielen Dank Frank - bekommst ein Bier von mir, falls wir uns mal über den Weg laufen sollten

[frank]

Das scheints gewesen zu sein

na dann, prost!

sind die A112 messages dadurch jetzt komplett verschwunden, oder sind die nur auswirkungen "moderater"?

[franz27]

sind die A112 messages dadurch jetzt komplett verschwunden, oder sind die nur auswirkungen "moderater"?

Die sind weg, die letzte war von 12:37.

Ich habe noch was zum Thema msgRepeat gelesen um das Verhalten zu verstehen:
Der HMLAN macht von sich aus bei Kommunikationsproblemen bis zu 3 Wiederholungen, wenn bei dem VD msgRepeat=0 nicht gesetzt ist, sendet Fhem selbst auch Wiederholungen und dann haben wir einen großen Haufen Wiederholungen die alle völlig unabhänig durcheinander funken.
Richtig?

[frank]

Richtig?

fast.

das attr msgRepeat sollte die anzahl der wiederholungen der befehle auf dieses device sein.
also zb bei einem statusrequest auf einen aktor erhält fhem keine antwort, dann wird msgrepeat mal wiederholt, bis eine antwort kommt. wenn das attribut fehlt, ist msgrepeat=3. hier ist es natürlich entsprechend komplizierter. eigentlich dürfte sich die zentrale hier gar nicht einmischen, wenn die zentrale gar nichts will.

ich denke, dass die "no ack" meldungen, die auch bei erfolgreicher kommunikation zwischen vtc und vd auftauchen, wahrscheinlich dann die ursache für die A112 messages sind. diese "falschen" no-ack infos, die der hmlan an fhem sendet, müssten in fhem wahrscheinlich "umgebucht" werden. fhem weiss ja, dass es eine antwort vom vd an den vtc gab, obwohl der hmlan anderer meinung ist.

wäre gut, wenn martin dazu noch etwas einfallen könnte.

[martinp876]

msgRepeat sind die Wiederholungen von FHEM. HMLAN macht - wie korrekt gesehen, selbst 3. Wenn man also msgRepeat 5 einstellt werden 3*5 messages gesendet.
Die Wiederholungen von FHEM sind deutlich Langsamer und deken einen anderen Level ab. Bei Schaltern hat sich das positiv ausgewirkt. Beides ergänzt sich.

Bei Burst devices und den wakeups sollte man es auf 0 setzen.

Die HMLAN Wiederholungen kann ich nicht ändern.

[frank]

hallo martin,

msgRepeat sind die Wiederholungen von FHEM. HMLAN macht - wie korrekt gesehen, selbst 3. Wenn man also msgRepeat 5 einstellt werden 3*5 messages gesendet.
Die Wiederholungen von FHEM sind deutlich Langsamer und deken einen anderen Level ab. Bei Schaltern hat sich das positiv ausgewirkt. Beides ergänzt sich.

es geht definitiv um fhem wiederholungen. hier ein log mit msgrepeat=0 beim vd und vtc. nach einem miss ist die nächste kommunikation ganz normal.

Code Auswählen Erweitern

2015.10.04 12:58:53.132 0: HMLAN_Send:  hmlan1 S:S32811B9A stat:  00 t:00000000 d:01 r:32811B9A m:85 A258 B2B2B2 1DFC2F 00FD
2015.10.04 12:58:53.174 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:00316966 d:FF r:FFDD     m:85 A258 B2B2B2 1DFC2F 00FD
2015.10.04 12:58:53.398 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:00316A2E d:FF r:FFDD     m:85 A258 B2B2B2 1DFC2F 00FD
2015.10.04 12:58:53.590 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:00316AF6 d:FF r:FFDD     m:85 A258 B2B2B2 1DFC2F 00FD
2015.10.04 12:58:53.702 0: HMLAN_Parse: hmlan1 R:R32811B9A stat:0008 t:00000000 d:FF r:7FFF     m:85 A258 B2B2B2 1DFC2F 00FD
2015.10.04 12:58:53.704 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 12:58:53.709 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:17C2FB7C d:FF r:FFD1     m:85 8202 1DFC2F B2B2B2 0101C6002D
2015.10.04 12:58:53.750 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:00316B78 d:FF r:FFCB     m:85 8202 1DFC2F B2B2B2 0101C6002D
2015.10.04 12:59:22.181 1: Perfmon: possible freeze starting at 12:59:21, delay is 1.181
2015.10.04 13:01:16.149 1: Perfmon: possible freeze starting at 13:01:15, delay is 1.148
2015.10.04 13:01:16.156 0: HMLAN_Send:  hmlan1 S:S32834A4A stat:  00 t:00000000 d:01 r:32834A4A m:86 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:01:17.218 0: HMLAN_Parse: hmlan1 R:R32834A4A stat:0008 t:00000000 d:FF r:7FFF     m:86 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:01:17.221 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 13:01:17.239 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:00339813 d:FF r:FFDD     m:86 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:01:17.258 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:003398DB d:FF r:FFDD     m:86 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:01:17.272 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:003399A3 d:FF r:FFDD     m:86 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:02:11.297 1: Perfmon: possible freeze starting at 13:02:10, delay is 1.297
2015.10.04 13:02:24.095 1: Perfmon: possible freeze starting at 13:02:23, delay is 1.095
2015.10.04 13:03:22.886 0: HMLAN_Send:  hmlan1 S:S32853954 stat:  00 t:00000000 d:01 r:32853954 m:87 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:03:22.929 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:0035871C d:FF r:FFDD     m:87 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:03:23.153 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:003587E4 d:FF r:FFDD     m:87 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:03:23.277 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:17C71891 d:FF r:FFD1     m:87 8202 1DFC2F B2B2B2 0101C6002D
2015.10.04 13:03:23.451 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:00358868 d:FF r:FFCB     m:87 8202 1DFC2F B2B2B2 0101C6002D
2015.10.04 13:03:23.468 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:00358885 d:FF r:FFDD     m:87 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:03:23.483 0: HMLAN_Parse: hmlan1 R:R32853954 stat:0008 t:00000000 d:FF r:7FFF     m:87 A258 B2B2B2 1DFC2F 00FD
2015.10.04 13:03:23.485 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F


wenn ich aber msgrepeat=3 beim vd einschalte (msgrepeat=0 beim vtc), funkt hier ständig die zentrale mit A112 dazwischen, obwohl eigentlich nichts zu tun wäre. auch die A258 messages des vtc werden wiederholt.

warum erscheinen die A112 messages, wenn msgrepeat gesetzt ist?

Code Auswählen Erweitern

2015.10.04 10:52:40.162 0: HMLAN_Send:  hmlan1 S:S320D8DB0 stat:  00 t:00000000 d:01 r:320D8DB0 m:53 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:52:40.214 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04D58804 d:FF r:FFDD     m:53 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:52:40.343 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04D58886 d:FF r:FFCA     m:53 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:52:40.385 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:174F67FD d:FF r:FFD0     m:53 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:52:40.402 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04D588A4 d:FF r:FFDD     m:53 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:52:40.566 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04D5896B d:FF r:FFDD     m:53 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:52:40.732 0: HMLAN_Parse: hmlan1 R:R320D8DB0 stat:0008 t:00000000 d:FF r:7FFF     m:53 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:52:40.734 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 10:55:19.418 0: HMLAN_Send:  hmlan1 S:S320FFBC9 stat:  00 t:00000000 d:01 r:320FFBC9 m:54 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:55:19.475 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04D7F619 d:FF r:FFDD     m:54 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:55:19.667 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04D7F6E1 d:FF r:FFDD     m:54 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:55:19.859 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04D7F7A9 d:FF r:FFDD     m:54 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:55:20.028 0: HMLAN_Parse: hmlan1 R:R320FFBC9 stat:0008 t:00000000 d:FF r:7FFF     m:54 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:55:20.030 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 10:55:55.623 1: ----- SYNC-ITR1500 ----- FB_IT02_chn01:off => IT07:off
2015.10.04 10:57:44.169 0: HMLAN_Send:  hmlan1 S:S32123136 stat:  00 t:00000000 d:01 r:32123136 m:55 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:44.209 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DA2B86 d:FF r:FFDD     m:55 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:44.433 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DA2C4F d:FF r:FFDD     m:55 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:44.539 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:17540C76 d:FF r:FFD0     m:55 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:44.634 0: HMLAN_Send:  hmlan1 S:S321232B9 stat:  00 t:00000000 d:01 r:321232B9 m:54 A112 1ACE1F 1DFC2F
2015.10.04 10:57:44.664 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA2CD2 d:FF r:FFCA     m:55 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:44.689 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA2D49 d:FF r:FFDD     m:55 A112 1ACE1F 1DFC2F
2015.10.04 10:57:44.785 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:17540D6C d:FF r:FFD0     m:55 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:44.817 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA2DC8 d:FF r:FFCA     m:55 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:45.075 0: HMLAN_Parse: hmlan1 R:R31BBC64D stat:0008 t:00000000 d:FF r:7FFF     m:55 A112 1ACE1F 1DFC2F
2015.10.04 10:57:45.078 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 10:57:45.137 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DA2F09 d:FF r:FFDD     m:55 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:45.237 0: HMLAN_Parse: hmlan1 R:R32123136 stat:0008 t:00000000 d:FF r:7FFF     m:55 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:45.240 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 10:57:45.245 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:17540F31 d:FF r:FFD0     m:55 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:45.332 0: HMLAN_Send:  hmlan1 S:S3212357B stat:  00 t:00000000 d:01 r:3212357B m:56 A112 1ACE1F 1DFC2F
2015.10.04 10:57:45.362 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA2F8D d:FF r:FFCA     m:55 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:45.378 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3004 d:FF r:FFDD     m:55 A112 1ACE1F 1DFC2F
2015.10.04 10:57:45.484 0: HMLAN_Parse: hmlan1 R:R00000000 stat:0081 t:1754102D d:FF r:FFD0     m:55 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:45.511 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA3083 d:FF r:FFCA     m:55 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:45.777 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3194 d:FF r:FFDD     m:54 A112 1ACE1F 1DFC2F
2015.10.04 10:57:45.884 0: HMLAN_Parse: hmlan1 R:R321232B9 stat:0001 t:175411BD d:FF r:FFD0     m:54 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:45.910 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA3213 d:FF r:FFCA     m:54 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:46.161 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3327 d:FF r:FFDD     m:56 A112 1ACE1F 1DFC2F
2015.10.04 10:57:46.287 0: HMLAN_Parse: hmlan1 R:R3212357B stat:0001 t:1754134F d:FF r:FFD0     m:56 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:46.385 0: HMLAN_Send:  hmlan1 S:S32123991 stat:  00 t:00000000 d:01 r:32123991 m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:46.412 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA33A6 d:FF r:FFCB     m:56 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:46.577 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DA34BA d:FF r:FFDD     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:46.695 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:175414E2 d:FF r:FFD0     m:57 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:46.801 0: HMLAN_Send:  hmlan1 S:S32123B27 stat:  00 t:00000000 d:01 r:32123B27 m:58 A112 1ACE1F 1DFC2F
2015.10.04 10:57:46.830 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA353D d:FF r:FFCA     m:57 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:46.849 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA35B5 d:FF r:FFDD     m:57 A112 1ACE1F 1DFC2F
2015.10.04 10:57:46.940 0: HMLAN_Parse: hmlan1 R:R31BBC64D stat:0081 t:175415DD d:FF r:FFD0     m:57 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:46.966 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA3633 d:FF r:FFCA     m:57 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:47.217 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DA3745 d:FF r:FFDD     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:47.346 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:1754176D d:FF r:FFD0     m:57 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:47.451 0: HMLAN_Send:  hmlan1 S:S32123DB0 stat:  00 t:00000000 d:01 r:32123DB0 m:58 A112 1ACE1F 1DFC2F
2015.10.04 10:57:47.494 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA37C8 d:FF r:FFCA     m:57 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:47.512 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3840 d:FF r:FFDD     m:57 A112 1ACE1F 1DFC2F
2015.10.04 10:57:47.591 0: HMLAN_Parse: hmlan1 R:R31BBC64D stat:0081 t:17541868 d:FF r:FFD0     m:57 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:47.619 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA38BE d:FF r:FFCA     m:57 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:47.889 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DA39D0 d:FF r:FFDD     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:47.921 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA39F9 d:FF r:FFDD     m:58 A112 1ACE1F 1DFC2F
2015.10.04 10:57:48.004 0: HMLAN_Parse: hmlan1 R:R32123991 stat:0008 t:00000000 d:FF r:7FFF     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:57:48.006 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 10:57:48.019 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA3A53 d:FF r:FFCA     m:57 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:48.057 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:175419F8 d:FF r:FFD0     m:57 8202 1DFC2F B2B2B2 010100002D
2015.10.04 10:57:48.273 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3B65 d:FF r:FFDD     m:58 A112 1ACE1F 1DFC2F
2015.10.04 10:57:48.305 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3B7A d:FF r:FFDD     m:58 A112 1ACE1F 1DFC2F
2015.10.04 10:57:48.399 0: HMLAN_Parse: hmlan1 R:R32123B27 stat:0001 t:17541B8F d:FF r:FFD0     m:58 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:48.423 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA3BE5 d:FF r:FFCA     m:58 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:48.689 0: HMLAN_Parse: hmusb1 R:E1ACE1F   stat:0000 t:04DA3CF8 d:FF r:FFDD     m:58 A112 1ACE1F 1DFC2F
2015.10.04 10:57:48.801 0: HMLAN_Parse: hmlan1 R:R32123DB0 stat:0001 t:17541D22 d:FF r:FFD0     m:58 8002 1DFC2F 1ACE1F 00
2015.10.04 10:57:48.820 0: HMLAN_Parse: hmusb1 R:E1DFC2F   stat:0000 t:04DA3D77 d:FF r:FFCA     m:58 8002 1DFC2F 1ACE1F 00
2015.10.04 10:59:54.674 0: HMLAN_Send:  hmlan1 S:S32142F01 stat:  00 t:00000000 d:01 r:32142F01 m:56 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:59:54.734 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DC294C d:FF r:FFDD     m:56 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:59:54.926 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DC2A15 d:FF r:FFDD     m:56 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:59:55.125 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DC2ADE d:FF r:FFDD     m:56 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:59:55.284 0: HMLAN_Parse: hmlan1 R:R32142F01 stat:0008 t:00000000 d:FF r:7FFF     m:56 A258 B2B2B2 1DFC2F 0000
2015.10.04 10:59:55.286 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 11:00:35.249 1: Perfmon: possible freeze starting at 11:00:29, delay is 6.247
2015.10.04 11:02:54.674 0: HMLAN_Send:  hmlan1 S:S3216EE20 stat:  00 t:00000000 d:01 r:3216EE20 m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 11:02:54.731 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DEE86D d:FF r:FFDD     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 11:02:55.319 0: HMLAN_Parse: hmlan1 R:R3216EE20 stat:0008 t:00000000 d:FF r:7FFF     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 11:02:55.321 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F
2015.10.04 11:02:55.324 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DEE935 d:FF r:FFDD     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 11:02:55.337 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04DEE9FD d:FF r:FFDD     m:57 A258 B2B2B2 1DFC2F 0000
2015.10.04 11:05:40.182 0: HMLAN_Send:  hmlan1 S:+1DFC2F,02,00,00
2015.10.04 11:05:40.187 0: HMLAN_Send:  hmlan1 S:S321974A2 stat:  00 t:00000000 d:01 r:321974A2 m:58 A258 B2B2B2 1DFC2F 0300
2015.10.04 11:05:40.234 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04E16EF1 d:FF r:FFDD     m:58 A258 B2B2B2 1DFC2F 0300
2015.10.04 11:05:40.457 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04E16FB9 d:FF r:FFDD     m:58 A258 B2B2B2 1DFC2F 0300
2015.10.04 11:05:40.649 0: HMLAN_Parse: hmusb1 R:EB2B2B2   stat:0000 t:04E17081 d:FF r:FFDD     m:58 A258 B2B2B2 1DFC2F 0300
2015.10.04 11:05:40.796 0: HMLAN_Parse: hmlan1 R:R321974A2 stat:0008 t:00000000 d:FF r:7FFF     m:58 A258 B2B2B2 1DFC2F 0300
2015.10.04 11:05:40.798 0: HMLAN_Parse: hmlan1 no ACK from 1DFC2F