[gelöst] patch: keepAlive mechanismus ursache für gelegentliche hmlan reboots

Begonnen von frank, 22 April 2021, 14:38:50

Vorheriges Thema - Nächstes Thema

frank

hallo martin,

seit kurzem beobachte ich gelegentlich disconnects/reboots beim hmlan, wenn ich getconfigs an einen HM-SW1-DIM1TPBU-FM über diesen hmlan sende.
meine grobe schätzung: bei jedem 10. getconfig gibt es ein disconnect, etwa 10%.
alle 4 bisher beobachteten disconnects wurden sogar durch einen reboot des hmlan verursacht, er ist also "abgestürzt".
diese beobachtungen haben sich aus diesem thread ergeben: https://forum.fhem.de/index.php/topic,119122.msg1149917.html#msg1149917


ich habe jetzt mal meine verfügbaren logs nach disconnects vom hmlan durchsucht.
seit august letzten jahres gibt es etwa 1 disconnect alle 2-3 monate, also eigentlich nichts nennenswertes.
etwa die hälfte finden in den ersten minuten nach mitternacht statt und lassen sich dadurch auf ein altes problem mit timerequests durch hm-cc-tc zurückführen. https://forum.fhem.de/index.php/topic,22509.msg159186.html#msg159186.
die ursache der restlichen bleibt unbekannt. einige könnten mit netzwerkproblemen zu tun haben, die zeitgleich von anderen modulen gemeldet wurden.



aktuell vermute ich hier eine verkettung "unglücklicher" umstände beim keepalive mechanismus des hmlan.

1. ein "normales" keepalive, wenn der hmlan im leerlauf ist, dauert regelmässig 4-5ms.
2021.04.18 10:34:01.037 0: HMLAN_Send:  hmlan1 I:K
2021.04.18 10:34:01.041 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:007833CE IDcnt:0011 L:12 %



2. keepalive während eines getconfig
ein fehlerfreies getconfig mit hmlan beim dimmer dauert regelmässig etwa 18 sek.
das interval der keepalive erfolgt im abstand von 25 sek.
dadurch ist wahrscheinlichkeit gross, dass ein keepalive während des getconfig "reingrätscht".

diese keepalive werden meistens quasi "gleichzeitig" mit einem cmd des getconfig an den hmlan gesendet.
immer zuerst das cmd und 1ms danach das keepalive:
2021.04.21 16:29:40.519 0: HMLAN_Send:  hmlan1 S:SF4D62F44 stat:  00 t:00000000 d:01 r:F4D62F44 m:08 A001 1ACE1F 266A86 01040000000001
2021.04.21 16:29:40.520 0: HMLAN_Send:  hmlan1 I:K


grundsätzlich führen diese gleichzeitigen keepalive sicherlich nicht zum reboot des hmlan, denn ich kann sie häufig beobachten, auch ohne reboot des hmlan.
wenn es zum reboot kommt, findet dieser auch nicht unmittelbar nach dem keepalive, sondern erst später nach zunächst erfolgreicher kommunikation mit dem dimmer statt. siehe sniff weiter unten.


3. übertragungsprobleme durch "gleichzeitiges" senden mehrerer cmds an den hmlan
eine untersuchung mit wireshark zeigt zumindestens, dass dieses gleichzeitige senden von 2 cmds an den hmlan grundsätzlich probleme verursacht.
dazu habe ich mal die fhem.log einträge mit den entsprechenden wireshark messages kombiniert.
leider bietet der wireshark mitschnitt in der fritzbox nur noch die ausgehenden pakete in richtung hmlan an. für HMLAN_Send passt es also, aber bei HMLAN_Parse ist es nur die rückbestätigung an den hmlan.
auf alle fälle wird sichtbar, dass der hmlan bei "gleichzeitigem" senden von mehreren cmds, diese nicht sofort bearbeiten kann, wodurch immer fehler bei der übermittlung auftauchen.

###     2021.04.21 16:29:40.519 0: HMLAN_Send:  hmlan1 S:SF4D62F44 stat:  00 t:00000000 d:01 r:F4D62F44 m:08 A001 1ACE1F 266A86 01040000000001
72 2021-04-21 16:29:41.111191 192.168.1.25 192.168.1.9 TCP 122 42692 → 1000 [PSH, ACK] Seq=72 Ack=334 Win=63959 Len=68
###     2021.04.21 16:29:40.520 0: HMLAN_Send:  hmlan1 I:K
73 2021-04-21 16:29:41.129753 192.168.1.25 192.168.1.9 TCP 60 42692 → 1000 [PSH, ACK] Seq=140 Ack=334 Win=63959 Len=3

###     => fehler
78 2021-04-21 16:29:41.347306 192.168.1.25 192.168.1.9 TCP 60 [TCP Retransmission] 42692 → 1000 [PSH, ACK] Seq=140 Ack=334 Win=63959 Len=3

###     2021.04.21 16:29:40.563 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05795A4F d:FF r:FFD2     m:06 A010 266A86 1ACE1F 030000
79 2021-04-21 16:29:41.401898 192.168.1.25 192.168.1.9 TCP 60 42692 → 1000 [ACK] Seq=143 Ack=393 Win=63959 Len=0

###     => fehler
80 2021-04-21 16:29:41.402280 192.168.1.25 192.168.1.9 TCP 60 [TCP Dup ACK 79#1] 42692 → 1000 [ACK] Seq=143 Ack=393 Win=63959 Len=0



4. kompletter sniff eines getconfig mit hmlan reboot
2021.04.21 16:29:39.195 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:39.201 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:0579558E IDcnt:0011 L:4 %
2021.04.21 16:29:39.517 3: CUL_HM set DimPBU01 getConfig noArg
2021.04.21 16:29:40.013 0: HMLAN_Send:  hmlan1 S:SF4D62DA4 stat:  00 t:00000000 d:01 r:F4D62DA4 m:05 A001 1ACE1F 266A86 00040000000000
2021.04.21 16:29:40.048 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 05 A0 01 1ACE1F 266A86 00040000000000
2021.04.21 16:29:40.054 4: CUL_Parse: cul868 A 10 05 A001 1ACE1F 266A86 0004000000000041 -41.5
2021.04.21 16:29:40.175 4: CUL_Parse: cul868 A 18 05 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18007F3F15 -63.5
2021.04.21 16:29:40.211 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 05 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18007F3F
2021.04.21 16:29:40.214 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579595B d:FF r:FFD2     m:05 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18007F3F
2021.04.21 16:29:40.290 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:40.296 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 05 80 02 1ACE1F 266A86 00
2021.04.21 16:29:40.299 0: HMLAN_Parse: hmlan1 R:RF4D62DA4 stat:0001 t:05795960 d:FF r:FFD2     m:05 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18007F3F
2021.04.21 16:29:40.417 4: CUL_Parse: cul868 A 0C 06 A010 266A86 1ACE1F 0300001D -59.5
2021.04.21 16:29:40.519 0: HMLAN_Send:  hmlan1 S:SF4D62F44 stat:  00 t:00000000 d:01 r:F4D62F44 m:08 A001 1ACE1F 266A86 01040000000001
2021.04.21 16:29:40.520 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:40.552 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 06 A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:40.555 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 06 80 02 1ACE1F 266A86 00
2021.04.21 16:29:40.559 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:40.563 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05795A4F d:FF r:FFD2     m:06 A010 266A86 1ACE1F 030000
2021.04.21 16:29:40.628 0: HMUARTLGW hmuart1 send: 00 08
2021.04.21 16:29:40.637 0: HMUARTLGW hmuart1 recv: 00 040207, state 98
2021.04.21 16:29:40.639 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.21 16:29:40.640 0: HMUARTLGW hmuart1 roundtrip delay: 0.0056
2021.04.21 16:29:40.812 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:05795BC1 IDcnt:0011 L:4 %
2021.04.21 16:29:40.814 4: CUL_Parse: cul868 A 10 08 A001 1ACE1F 266A86 0104000000000140 -42
2021.04.21 16:29:40.825 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 08 A0 01 1ACE1F 266A86 01040000000001
2021.04.21 16:29:40.945 4: CUL_Parse: cul868 A 1A 08 A010 266A86 1ACE1F 02300632503364344B35505600572459011D -59.5
2021.04.21 16:29:41.009 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05795C5D d:FF r:FFD2     m:08 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.21 16:29:41.013 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 08 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.21 16:29:41.060 4: CUL_Parse: cul868 A 0A 08 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:41.065 0: HMLAN_Parse: hmlan1 R:RF4D62F44 stat:0001 t:05795C62 d:FF r:FFD2     m:08 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.21 16:29:41.070 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 08 80 02 1ACE1F 266A86 00
2021.04.21 16:29:41.186 4: CUL_Parse: cul868 A 0C 09 A010 266A86 1ACE1F 02080016 -63
2021.04.21 16:29:41.228 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05795D50 d:FF r:FFD2     m:09 A010 266A86 1ACE1F 020800
2021.04.21 16:29:41.232 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 09 A0 10 266A86 1ACE1F 020800
2021.04.21 16:29:41.309 4: CUL_Parse: cul868 A 0A 09 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:41.314 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 09 80 02 1ACE1F 266A86 00
2021.04.21 16:29:41.435 4: CUL_Parse: cul868 A 0C 0A A010 266A86 1ACE1F 0300001D -59.5
2021.04.21 16:29:41.537 0: HMLAN_Send:  hmlan1 S:SF4D6333D stat:  00 t:00000000 d:01 r:F4D6333D m:0C A001 1ACE1F 266A86 0103
2021.04.21 16:29:41.569 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05795E49 d:FF r:FFD2     m:0A A010 266A86 1ACE1F 030000
2021.04.21 16:29:41.573 4: CUL_Parse: cul868 A 0A 0A 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:41.578 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 0A A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:41.580 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 0A 80 02 1ACE1F 266A86 00
2021.04.21 16:29:41.824 4: CUL_Parse: cul868 A 0B 0C A001 1ACE1F 266A86 010341 -41.5
2021.04.21 16:29:41.829 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 0C A0 01 1ACE1F 266A86 0103
2021.04.21 16:29:41.960 4: CUL_Parse: cul868 A 16 0C A010 266A86 1ACE1F 01266A8601266A8602000000001D -59.5
2021.04.21 16:29:42.054 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796053 d:FF r:FFD2     m:0C A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:42.059 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0C A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:42.077 4: CUL_Parse: cul868 A 0A 0C 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:42.084 0: HMLAN_Parse: hmlan1 R:RF4D6333D stat:0001 t:05796058 d:FF r:FFD2     m:0C A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:42.189 0: HMLAN_Send:  hmlan1 S:SF4D635C0 stat:  00 t:00000000 d:01 r:F4D635C0 m:0E A001 1ACE1F 266A86 02040000000001
2021.04.21 16:29:42.193 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 0C 80 02 1ACE1F 266A86 00
2021.04.21 16:29:42.347 4: CUL_Parse: cul868 A 10 0E A001 1ACE1F 266A86 0204000000000141 -41.5
2021.04.21 16:29:42.352 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 0E A0 01 1ACE1F 266A86 02040000000001
2021.04.21 16:29:42.481 4: CUL_Parse: cul868 A 1A 0E A010 266A86 1ACE1F 02300632503364344B35505600572459001D -59.5
2021.04.21 16:29:42.523 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579625D d:FF r:FFD2     m:0E A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.21 16:29:42.527 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 0E A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.21 16:29:42.596 4: CUL_Parse: cul868 A 0A 0E 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:42.601 0: HMLAN_Parse: hmlan1 R:RF4D635C0 stat:0001 t:05796262 d:FF r:FFD2     m:0E A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.21 16:29:42.605 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 0E 80 02 1ACE1F 266A86 00
2021.04.21 16:29:42.722 4: CUL_Parse: cul868 A 0C 0F A010 266A86 1ACE1F 02080016 -63
2021.04.21 16:29:42.759 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796350 d:FF r:FFD2     m:0F A010 266A86 1ACE1F 020800
2021.04.21 16:29:42.766 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 0F A0 10 266A86 1ACE1F 020800
2021.04.21 16:29:42.845 4: CUL_Parse: cul868 A 0A 0F 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:42.851 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 0F 80 02 1ACE1F 266A86 00
2021.04.21 16:29:42.972 4: CUL_Parse: cul868 A 0C 10 A010 266A86 1ACE1F 0300001C -60
2021.04.21 16:29:43.073 0: HMLAN_Send:  hmlan1 S:SF4D6393D stat:  00 t:00000000 d:01 r:F4D6393D m:12 A001 1ACE1F 266A86 0203
2021.04.21 16:29:43.104 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796449 d:FF r:FFD2     m:10 A010 266A86 1ACE1F 030000
2021.04.21 16:29:43.108 4: CUL_Parse: cul868 A 0A 10 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:43.113 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 10 A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:43.115 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 10 80 02 1ACE1F 266A86 00
2021.04.21 16:29:43.359 4: CUL_Parse: cul868 A 0B 12 A001 1ACE1F 266A86 020341 -41.5
2021.04.21 16:29:43.364 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 12 A0 01 1ACE1F 266A86 0203
2021.04.21 16:29:43.493 4: CUL_Parse: cul868 A 16 12 A010 266A86 1ACE1F 01266A8601266A86020000000016 -63
2021.04.21 16:29:43.540 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796653 d:FF r:FFD2     m:12 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:43.544 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 12 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:43.613 4: CUL_Parse: cul868 A 0A 12 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:43.618 0: HMLAN_Parse: hmlan1 R:RF4D6393D stat:0001 t:05796658 d:FF r:FFD2     m:12 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:43.722 0: HMLAN_Send:  hmlan1 S:SF4D63BBD stat:  00 t:00000000 d:01 r:F4D63BBD m:14 A001 1ACE1F 266A86 03040000000001
2021.04.21 16:29:43.726 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 12 80 02 1ACE1F 266A86 00
2021.04.21 16:29:43.883 4: CUL_Parse: cul868 A 10 14 A001 1ACE1F 266A86 0304000000000141 -41.5
2021.04.21 16:29:43.889 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 14 A0 01 1ACE1F 266A86 03040000000001
2021.04.21 16:29:44.016 4: CUL_Parse: cul868 A 1A 14 A010 266A86 1ACE1F 02300632503364344B355056005724590016 -63
2021.04.21 16:29:44.050 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579685D d:FF r:FFD2     m:14 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.21 16:29:44.054 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 14 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.21 16:29:44.131 4: CUL_Parse: cul868 A 0A 14 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:44.136 0: HMLAN_Parse: hmlan1 R:RF4D63BBD stat:0001 t:05796862 d:FF r:FFD2     m:14 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.21 16:29:44.140 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 14 80 02 1ACE1F 266A86 00
2021.04.21 16:29:44.257 4: CUL_Parse: cul868 A 0C 15 A010 266A86 1ACE1F 02080017 -62.5
2021.04.21 16:29:44.291 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796950 d:FF r:FFD2     m:15 A010 266A86 1ACE1F 020800
2021.04.21 16:29:44.295 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 15 A0 10 266A86 1ACE1F 020800
2021.04.21 16:29:44.380 4: CUL_Parse: cul868 A 0A 15 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:44.385 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 15 80 02 1ACE1F 266A86 00
2021.04.21 16:29:44.506 4: CUL_Parse: cul868 A 0C 16 A010 266A86 1ACE1F 0300001D -59.5
2021.04.21 16:29:44.608 0: HMLAN_Send:  hmlan1 S:SF4D63F3B stat:  00 t:00000000 d:01 r:F4D63F3B m:18 A001 1ACE1F 266A86 0303
2021.04.21 16:29:44.638 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796A49 d:FF r:FFD2     m:16 A010 266A86 1ACE1F 030000
2021.04.21 16:29:44.642 4: CUL_Parse: cul868 A 0A 16 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:44.647 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 16 A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:44.650 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 16 80 02 1ACE1F 266A86 00
2021.04.21 16:29:44.897 4: CUL_Parse: cul868 A 0B 18 A001 1ACE1F 266A86 030341 -41.5
2021.04.21 16:29:44.902 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 18 A0 01 1ACE1F 266A86 0303
2021.04.21 16:29:45.030 4: CUL_Parse: cul868 A 16 18 A010 266A86 1ACE1F 01266A8601266A86020000000015 -63.5
2021.04.21 16:29:45.078 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796C54 d:FF r:FFD2     m:18 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:45.082 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 18 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:45.149 4: CUL_Parse: cul868 A 0A 18 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:45.154 0: HMLAN_Parse: hmlan1 R:RF4D63F3B stat:0001 t:05796C59 d:FF r:FFD2     m:18 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.21 16:29:45.258 0: HMLAN_Send:  hmlan1 S:SF4D641BD stat:  00 t:00000000 d:01 r:F4D641BD m:1A A001 1ACE1F 266A86 0104266A860103
2021.04.21 16:29:45.262 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 18 80 02 1ACE1F 266A86 00
2021.04.21 16:29:45.421 4: CUL_Parse: cul868 A 10 1A A001 1ACE1F 266A86 0104266A86010340 -42
2021.04.21 16:29:45.426 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 1A A0 01 1ACE1F 266A86 0104266A860103
2021.04.21 16:29:45.553 4: CUL_Parse: cul868 A 1A 1A A010 266A86 1ACE1F 0301000000326400FF00FF01445464200016 -63
2021.04.21 16:29:45.587 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796E5F d:FF r:FFD2     m:1A A010 266A86 1ACE1F 0301000000326400FF00FF014454642000
2021.04.21 16:29:45.591 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 1A A0 10 266A86 1ACE1F 0301000000326400FF00FF014454642000
2021.04.21 16:29:45.670 4: CUL_Parse: cul868 A 0A 1A 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:45.675 0: HMLAN_Parse: hmlan1 R:RF4D641BD stat:0001 t:05796E64 d:FF r:FFD2     m:1A A010 266A86 1ACE1F 0301000000326400FF00FF014454642000
2021.04.21 16:29:45.678 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 1A 80 02 1ACE1F 266A86 00
2021.04.21 16:29:45.805 4: CUL_Parse: cul868 A 16 1B A010 266A86 1ACE1F 031014C80A050500C80A0A040416 -63
2021.04.21 16:29:45.838 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05796F5A d:FF r:FFD2     m:1B A010 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:29:45.843 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 1B A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:29:45.919 4: CUL_Parse: cul868 A 0A 1B 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:45.925 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 1B 80 02 1ACE1F 266A86 00
2021.04.21 16:29:46.048 4: CUL_Parse: cul868 A 0F 1C A010 266A86 1ACE1F 03260014526316 -63
2021.04.21 16:29:46.079 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579704D d:FF r:FFD2     m:1C A010 266A86 1ACE1F 032600145263
2021.04.21 16:29:46.082 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 1C A0 10 266A86 1ACE1F 032600145263
2021.04.21 16:29:46.168 4: CUL_Parse: cul868 A 0A 1C 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:46.173 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 1C 80 02 1ACE1F 266A86 00
2021.04.21 16:29:46.305 4: CUL_Parse: cul868 A 1A 1D A010 266A86 1ACE1F 03810000003264000A00FFA544546420001D -59.5
2021.04.21 16:29:46.336 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579714F d:FF r:FFD2     m:1D A010 266A86 1ACE1F 03810000003264000A00FFA54454642000
2021.04.21 16:29:46.340 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 1D A0 10 266A86 1ACE1F 03810000003264000A00FFA54454642000
2021.04.21 16:29:46.417 4: CUL_Parse: cul868 A 0A 1D 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:46.422 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 1D 80 02 1ACE1F 266A86 00
2021.04.21 16:29:46.551 4: CUL_Parse: cul868 A 16 1E A010 266A86 1ACE1F 039014C80A050500C80A0A040415 -63.5
2021.04.21 16:29:46.581 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797245 d:FF r:FFD2     m:1E A010 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:29:46.585 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 1E A0 10 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:29:46.664 4: CUL_Parse: cul868 A 0A 1E 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:46.670 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 1E 80 02 1ACE1F 266A86 00
2021.04.21 16:29:46.795 4: CUL_Parse: cul868 A 0F 1F A010 266A86 1ACE1F 03A6201452631C -60
2021.04.21 16:29:46.825 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797338 d:FF r:FFD2     m:1F A010 266A86 1ACE1F 03A620145263
2021.04.21 16:29:46.829 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 1F A0 10 266A86 1ACE1F 03A620145263
2021.04.21 16:29:46.915 4: CUL_Parse: cul868 A 0A 1F 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:46.920 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 1F 80 02 1ACE1F 266A86 00
2021.04.21 16:29:47.040 4: CUL_Parse: cul868 A 0C 20 A010 266A86 1ACE1F 03000015 -63.5
2021.04.21 16:29:47.141 0: HMLAN_Send:  hmlan1 S:SF4D6492C stat:  00 t:00000000 d:01 r:F4D6492C m:22 A001 1ACE1F 266A86 0104266A860203
2021.04.21 16:29:47.169 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579742F d:FF r:FFD2     m:20 A010 266A86 1ACE1F 030000
2021.04.21 16:29:47.172 4: CUL_Parse: cul868 A 0A 20 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:47.177 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 20 A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:47.180 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 20 80 02 1ACE1F 266A86 00
2021.04.21 16:29:47.434 4: CUL_Parse: cul868 A 10 22 A001 1ACE1F 266A86 0104266A86020340 -42
2021.04.21 16:29:47.439 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 22 A0 01 1ACE1F 266A86 0104266A860203
2021.04.21 16:29:47.568 4: CUL_Parse: cul868 A 1A 22 A010 266A86 1ACE1F 0301000000326400FF00FF01122223200017 -62.5
2021.04.21 16:29:47.598 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579763D d:FF r:FFD2     m:22 A010 266A86 1ACE1F 0301000000326400FF00FF011222232000
2021.04.21 16:29:47.602 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 22 A0 10 266A86 1ACE1F 0301000000326400FF00FF011222232000
2021.04.21 16:29:47.683 4: CUL_Parse: cul868 A 0A 22 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:47.688 0: HMLAN_Parse: hmlan1 R:RF4D6492C stat:0001 t:05797642 d:FF r:FFD2     m:22 A010 266A86 1ACE1F 0301000000326400FF00FF011222232000
2021.04.21 16:29:47.691 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 22 80 02 1ACE1F 266A86 00
2021.04.21 16:29:47.818 4: CUL_Parse: cul868 A 16 23 A010 266A86 1ACE1F 031014C80A050500C80A0A04041C -60
2021.04.21 16:29:47.854 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797738 d:FF r:FFD2     m:23 A010 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:29:47.858 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 23 A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:29:47.932 4: CUL_Parse: cul868 A 0A 23 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:47.937 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 23 80 02 1ACE1F 266A86 00
2021.04.21 16:29:48.061 4: CUL_Parse: cul868 A 0F 24 A010 266A86 1ACE1F 03260014526316 -63
2021.04.21 16:29:48.091 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579782B d:FF r:FFD2     m:24 A010 266A86 1ACE1F 032600145263
2021.04.21 16:29:48.095 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 24 A0 10 266A86 1ACE1F 032600145263
2021.04.21 16:29:48.181 4: CUL_Parse: cul868 A 0A 24 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:48.186 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 24 80 02 1ACE1F 266A86 00
2021.04.21 16:29:48.320 4: CUL_Parse: cul868 A 1A 25 A010 266A86 1ACE1F 0381000000326400FF00FF2412222320001D -59.5
2021.04.21 16:29:48.350 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579792D d:FF r:FFD2     m:25 A010 266A86 1ACE1F 0381000000326400FF00FF241222232000
2021.04.21 16:29:48.354 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 25 A0 10 266A86 1ACE1F 0381000000326400FF00FF241222232000
2021.04.21 16:29:48.430 4: CUL_Parse: cul868 A 0A 25 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:48.435 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 25 80 02 1ACE1F 266A86 00
2021.04.21 16:29:48.565 4: CUL_Parse: cul868 A 16 26 A010 266A86 1ACE1F 039014C80A050500C80A0A040416 -63
2021.04.21 16:29:48.595 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797A23 d:FF r:FFD2     m:26 A010 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:29:48.599 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 26 A0 10 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:29:48.679 4: CUL_Parse: cul868 A 0A 26 8002 1ACE1F 266A86 0040 -42
2021.04.21 16:29:48.684 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 26 80 02 1ACE1F 266A86 00
2021.04.21 16:29:48.808 4: CUL_Parse: cul868 A 0F 27 A010 266A86 1ACE1F 03A6201452631C -60
2021.04.21 16:29:48.838 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797B16 d:FF r:FFD2     m:27 A010 266A86 1ACE1F 03A620145263
2021.04.21 16:29:48.842 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 27 A0 10 266A86 1ACE1F 03A620145263
2021.04.21 16:29:48.928 4: CUL_Parse: cul868 A 0A 27 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:48.933 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 27 80 02 1ACE1F 266A86 00
2021.04.21 16:29:49.054 4: CUL_Parse: cul868 A 0C 28 A010 266A86 1ACE1F 03000016 -63
2021.04.21 16:29:49.156 0: HMLAN_Send:  hmlan1 S:SF4D6510A stat:  00 t:00000000 d:01 r:F4D6510A m:2A A001 1ACE1F 266A86 0204266A860103
2021.04.21 16:29:49.184 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797C0D d:FF r:FFD2     m:28 A010 266A86 1ACE1F 030000
2021.04.21 16:29:49.187 4: CUL_Parse: cul868 A 0A 28 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:49.191 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 28 A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:49.194 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 28 80 02 1ACE1F 266A86 00
2021.04.21 16:29:49.448 4: CUL_Parse: cul868 A 10 2A A001 1ACE1F 266A86 0204266A86010341 -41.5
2021.04.21 16:29:49.453 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2A A0 01 1ACE1F 266A86 0204266A860103
2021.04.21 16:29:49.581 4: CUL_Parse: cul868 A 1A 2A A010 266A86 1ACE1F 0301000000326400FF00FF0044546420001D -59.5
2021.04.21 16:29:49.611 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797E1B d:FF r:FFD2     m:2A A010 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.21 16:29:49.615 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 2A A0 10 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.21 16:29:49.697 4: CUL_Parse: cul868 A 0A 2A 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:49.702 0: HMLAN_Parse: hmlan1 R:RF4D6510A stat:0001 t:05797E20 d:FF r:FFD2     m:2A A010 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.21 16:29:49.705 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2A 80 02 1ACE1F 266A86 00
2021.04.21 16:29:49.832 4: CUL_Parse: cul868 A 16 2B A010 266A86 1ACE1F 031014C80A050500C80A0A04041D -59.5
2021.04.21 16:29:49.863 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05797F16 d:FF r:FFD2     m:2B A010 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:29:49.867 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 2B A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:29:49.946 4: CUL_Parse: cul868 A 0A 2B 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:49.951 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2B 80 02 1ACE1F 266A86 00
2021.04.21 16:29:50.075 4: CUL_Parse: cul868 A 0F 2C A010 266A86 1ACE1F 0326001452631C -60
2021.04.21 16:29:50.105 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05798009 d:FF r:FFD2     m:2C A010 266A86 1ACE1F 032600145263
2021.04.21 16:29:50.109 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 2C A0 10 266A86 1ACE1F 032600145263
2021.04.21 16:29:50.195 4: CUL_Parse: cul868 A 0A 2C 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:50.200 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2C 80 02 1ACE1F 266A86 00
2021.04.21 16:29:50.333 4: CUL_Parse: cul868 A 1A 2D A010 266A86 1ACE1F 03810000003264000A00FF00445464200016 -63
2021.04.21 16:29:50.364 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0579810B d:FF r:FFD2     m:2D A010 266A86 1ACE1F 03810000003264000A00FF004454642000
2021.04.21 16:29:50.367 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 2D A0 10 266A86 1ACE1F 03810000003264000A00FF004454642000
2021.04.21 16:29:50.444 4: CUL_Parse: cul868 A 0A 2D 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:50.449 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2D 80 02 1ACE1F 266A86 00
2021.04.21 16:29:50.578 4: CUL_Parse: cul868 A 16 2E A010 266A86 1ACE1F 039014C80A050500C80A0A04041D -59.5
2021.04.21 16:29:50.609 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:05798201 d:FF r:FFD2     m:2E A010 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:29:50.612 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 2E A0 10 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:29:50.692 4: CUL_Parse: cul868 A 0A 2E 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:50.697 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2E 80 02 1ACE1F 266A86 00
2021.04.21 16:29:50.822 4: CUL_Parse: cul868 A 0F 2F A010 266A86 1ACE1F 03A62014526316 -63
2021.04.21 16:29:50.853 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:057982F4 d:FF r:FFD2     m:2F A010 266A86 1ACE1F 03A620145263
2021.04.21 16:29:50.857 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 2F A0 10 266A86 1ACE1F 03A620145263
2021.04.21 16:29:50.942 4: CUL_Parse: cul868 A 0A 2F 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:50.947 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 2F 80 02 1ACE1F 266A86 00
2021.04.21 16:29:51.026 4: CUL_Parse: cul868 A 0C 8B 8670 1BF81B 000000 00CD2F26 -55
2021.04.21 16:29:51.032 0: HMUARTLGW hmuart1 recv: 01 05 00 00 32 msg: 8B 86 70 1BF81B 000000 00CD2F
2021.04.21 16:29:51.036 0: HMLAN_Parse: hmlan1 R:E1BF81B   stat:0000 t:057983C0 d:FF r:FFD2     m:8B 8670 1BF81B 000000 00CD2F
2021.04.21 16:29:51.068 4: CUL_Parse: cul868 A 0C 30 A010 266A86 1ACE1F 0300001C -60
2021.04.21 16:29:51.169 0: HMLAN_Send:  hmlan1 S:SF4D658E8 stat:  00 t:00000000 d:01 r:F4D658E8 m:32 A001 1ACE1F 266A86 0204266A860203
2021.04.21 16:29:51.197 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:057983EB d:FF r:FFD2     m:30 A010 266A86 1ACE1F 030000
2021.04.21 16:29:51.200 4: CUL_Parse: cul868 A 0A 30 8002 1ACE1F 266A86 0041 -41.5
2021.04.21 16:29:51.204 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 30 A0 10 266A86 1ACE1F 030000
2021.04.21 16:29:51.207 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2E msg: 30 80 02 1ACE1F 266A86 00
2021.04.21 16:29:53.974 4: CUL_Parse: cul868 A 0C 28 8670 206278 000000 80993AEF -82.5
2021.04.21 16:29:54.028 0: HMUARTLGW hmuart1 recv: 01 05 00 00 4F msg: 28 86 70 206278 000000 80993A
2021.04.21 16:29:55.635 0: HMUARTLGW hmuart1 send: 00 08
2021.04.21 16:29:55.640 0: HMUARTLGW hmuart1 recv: 00 040207, state 98
2021.04.21 16:29:55.641 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.21 16:29:55.642 0: HMUARTLGW hmuart1 roundtrip delay: 0.0034
2021.04.21 16:29:55.860 0: HMLAN_Send:  hmlan1 S:SF4D66B8C stat:  00 t:00000000 d:01 r:F4D66B8C m:32 A001 1ACE1F 266A86 0204266A860203
2021.04.21 16:29:55.861 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:56.863 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:57.865 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:58.866 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:59.869 1: HMLAN_Parse: hmlan1 new condition disconnected
2021.04.21 16:29:59.917 1: 192.168.1.9:1000 disconnected, waiting to reappear (hmlan1)
2021.04.21 16:29:59.927 1: HMLAN_Parse: hmlan1 new condition disconnected
2021.04.21 16:30:03.006 1: [Freezemon] freezemon: possible freeze starting at 16:30:00, delay is 3.005 possibly caused by: tmr-HMLAN_KeepAliveCheck(hmlan1)
2021.04.21 16:30:03.145 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 28 A2 58 B5B5B5 1C4E25 0000
2021.04.21 16:30:03.237 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 33 A0 01 1ACE1F 266A86 0204266A860203
2021.04.21 16:30:03.243 4: CUL_Parse: cul868 A 0B 28 A258 B5B5B5 1C4E25 000067 -22.5
2021.04.21 16:30:03.354 4: CUL_Parse: cul868 A 1A 33 A010 266A86 1ACE1F 0301000000326400FF00FF0012222320001C -60
2021.04.21 16:30:03.485 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 33 A0 10 266A86 1ACE1F 0301000000326400FF00FF001222232000
2021.04.21 16:30:03.618 4: CUL_Parse: cul868 A 16 34 A010 266A86 1ACE1F 031014C80A050500C80A0A040415 -63.5
2021.04.21 16:30:03.748 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 34 A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:30:03.753 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 34 80 02 1ACE1F 266A86 00
2021.04.21 16:30:03.802 4: CUL_Parse: cul868 A 0B 28 A258 B5B5B5 1C4E25 000067 -22.5
2021.04.21 16:30:03.876 4: CUL_Parse: cul868 A 0F 35 A010 266A86 1ACE1F 03260014526315 -63.5
2021.04.21 16:30:04.038 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 35 A0 10 266A86 1ACE1F 032600145263
2021.04.21 16:30:04.050 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 35 80 02 1ACE1F 266A86 00
2021.04.21 16:30:04.062 0: HMUARTLGW hmuart1 recv: 01 0404, state 100
2021.04.21 16:30:04.063 0: HMUARTLGW hmuart1 can't send due to unknown problem (no response?)
2021.04.21 16:30:04.144 4: CUL_Parse: cul868 A 1A 36 A010 266A86 1ACE1F 0381000000326400FF00FF00122223200014 -64
2021.04.21 16:30:04.279 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 36 A0 10 266A86 1ACE1F 0381000000326400FF00FF001222232000
2021.04.21 16:30:04.282 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 36 80 02 1ACE1F 266A86 00
2021.04.21 16:30:04.407 4: CUL_Parse: cul868 A 16 37 A010 266A86 1ACE1F 039014C80A050500C80A0A04041C -60
2021.04.21 16:30:04.539 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 37 A0 10 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:30:04.542 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 37 80 02 1ACE1F 266A86 00
2021.04.21 16:30:04.665 4: CUL_Parse: cul868 A 0F 38 A010 266A86 1ACE1F 03A6201452631D -59.5
2021.04.21 16:30:04.795 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 38 A0 10 266A86 1ACE1F 03A620145263
2021.04.21 16:30:04.800 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 38 80 02 1ACE1F 266A86 00
2021.04.21 16:30:04.920 4: CUL_Parse: cul868 A 0C 39 A010 266A86 1ACE1F 0300001D -59.5
2021.04.21 16:30:05.056 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 39 A0 10 266A86 1ACE1F 030000
2021.04.21 16:30:05.059 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 39 80 02 1ACE1F 266A86 00
2021.04.21 16:30:05.082 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 3B A0 01 1ACE1F 266A86 0304266A860203
2021.04.21 16:30:05.213 4: CUL_Parse: cul868 A 1A 3B A010 266A86 1ACE1F 0301000000326400FF00FF0012222320001C -60
2021.04.21 16:30:05.343 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 3B A0 10 266A86 1ACE1F 0301000000326400FF00FF001222232000
2021.04.21 16:30:05.347 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 3B 80 02 1ACE1F 266A86 00
2021.04.21 16:30:05.476 4: CUL_Parse: cul868 A 16 3C A010 266A86 1ACE1F 031014C80A050500C80A0A040415 -63.5
2021.04.21 16:30:05.606 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 3C A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:30:05.611 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 3C 80 02 1ACE1F 266A86 00
2021.04.21 16:30:05.734 4: CUL_Parse: cul868 A 0F 3D A010 266A86 1ACE1F 03260014526315 -63.5
2021.04.21 16:30:05.864 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 3D A0 10 266A86 1ACE1F 032600145263
2021.04.21 16:30:05.869 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 3D 80 02 1ACE1F 266A86 00
2021.04.21 16:30:06.002 4: CUL_Parse: cul868 A 1A 3E A010 266A86 1ACE1F 0381000000326400FF00FF00122223200016 -63
2021.04.21 16:30:06.205 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 3E A0 10 266A86 1ACE1F 0381000000326400FF00FF001222232000
2021.04.21 16:30:06.208 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 3E 80 02 1ACE1F 266A86 00
2021.04.21 16:30:06.266 4: CUL_Parse: cul868 A 16 3F A010 266A86 1ACE1F 039014C80A050500C80A0A04041D -59.5
2021.04.21 16:30:06.396 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 3F A0 10 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:30:06.400 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 3F 80 02 1ACE1F 266A86 00
2021.04.21 16:30:06.523 4: CUL_Parse: cul868 A 0F 40 A010 266A86 1ACE1F 03A6201452631D -59.5
2021.04.21 16:30:06.653 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 40 A0 10 266A86 1ACE1F 03A620145263
2021.04.21 16:30:06.658 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 40 80 02 1ACE1F 266A86 00
2021.04.21 16:30:06.778 4: CUL_Parse: cul868 A 0C 41 A010 266A86 1ACE1F 0300001D -59.5
2021.04.21 16:30:06.911 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 41 A0 10 266A86 1ACE1F 030000
2021.04.21 16:30:06.914 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 41 80 02 1ACE1F 266A86 00
2021.04.21 16:30:06.941 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 43 A0 01 1ACE1F 266A86 0304266A860103
2021.04.21 16:30:07.071 4: CUL_Parse: cul868 A 1A 43 A010 266A86 1ACE1F 0301000000326400FF00FF0044546420001C -60
2021.04.21 16:30:07.177 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 43 A0 10 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.21 16:30:07.203 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 43 80 02 1ACE1F 266A86 00
2021.04.21 16:30:07.335 4: CUL_Parse: cul868 A 16 44 A010 266A86 1ACE1F 031014C80A050500C80A0A040416 -63
2021.04.21 16:30:07.440 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 44 A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.21 16:30:07.467 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 44 80 02 1ACE1F 266A86 00
2021.04.21 16:30:07.592 4: CUL_Parse: cul868 A 0F 45 A010 266A86 1ACE1F 03260014526317 -62.5
2021.04.21 16:30:07.698 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 45 A0 10 266A86 1ACE1F 032600145263
2021.04.21 16:30:07.724 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 45 80 02 1ACE1F 266A86 00
2021.04.21 16:30:07.860 4: CUL_Parse: cul868 A 1A 46 A010 266A86 1ACE1F 03810000003264000A00FF00445464200017 -62.5
2021.04.21 16:30:07.965 4: CUL_Parse: cul868 A 0C FD 8670 206219 000000 007D47E3 -88.5
2021.04.21 16:30:08.102 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 46 A0 10 266A86 1ACE1F 03810000003264000A00FF004454642000
2021.04.21 16:30:08.105 0: HMUARTLGW hmuart1 recv: 01 05 00 00 50 msg: FD 86 70 206219 000000 007D47
2021.04.21 16:30:08.129 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 46 80 02 1ACE1F 266A86 00
2021.04.21 16:30:08.134 4: CUL_Parse: cul868 A 16 47 A010 266A86 1ACE1F 039014C80A050500C80A0A04041C -60
2021.04.21 16:30:08.239 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 47 A0 10 266A86 1ACE1F 039014C80A050500C80A0A0404
2021.04.21 16:30:08.266 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 47 80 02 1ACE1F 266A86 00
2021.04.21 16:30:08.392 4: CUL_Parse: cul868 A 0F 48 A010 266A86 1ACE1F 03A62014526314 -64
2021.04.21 16:30:08.497 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 48 A0 10 266A86 1ACE1F 03A620145263
2021.04.21 16:30:08.523 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 48 80 02 1ACE1F 266A86 00
2021.04.21 16:30:08.666 4: CUL_Parse: cul868 A 0C 49 A010 266A86 1ACE1F 03000015 -63.5
2021.04.21 16:30:08.703 2: HMinfo hminfo get:configCheck :-f,^(DimPBU01|DimPBU01_Dim|DimPBU01_Dim_V_01|DimPBU01_Dim_V_02|DimPBU01)$
2021.04.21 16:30:08.865 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 49 A0 10 266A86 1ACE1F 030000
2021.04.21 16:30:08.868 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 49 80 02 1ACE1F 266A86 00



5. "gestörte" keepalive intervalle / zusätzliche keepalive
das beispiel aus 4. zeigt auch eine weitere "besonderheit":
das während des getconfig reingrätschende keepalive war zudem völlig unnötig, da es nur 1,5 sek nach dem letzten keepalive gesendet wurde.

direkt vor dem getconfig gab es ein "reguläres" und erfolgreiches keepalive.
wenn das eingestellte interval der keepalive von 25 sek eingehalten worden wäre, hätte es während des getconfig kein weiteres keepalive geben dürfen, da ja dieses getconfig in der regel nach 18 sek erledigt ist.

dieses phänomen der zusätzlichen/vorgezogenen keepalive ist keine ausnahme, sondern die regel. es kommt bei allen getconfigs, die ich mit diesem dimmer gemacht habe, vor. aber auch cmds an andere devices zeigen dieses phänomen.
die abstände zwischen den keepalive folgen allerdings keiner regel. es hat eher den anschein, dass nach einigen cmds an den hmlan automatisch keepalive erzeugt werden.   

und das "fatale" an diesen zusätzlichen keepalive ist, dass sie immer quasi gleichzeitig mit anderen cmds gemeinsam an den hmlan gesendet werden.
und mein gefühl sagt, dass dies zu reboots des hmlan führen kann.

hier mal nur die keepalive rund um das getconfig.
ausgehend vom ersten keepalive folgen die weiteren keiner zeitlichen regel.
2021.04.21 16:29:39.195 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:39.201 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:0579558E IDcnt:0011 L:4 %
2021.04.21 16:29:39.517 3: CUL_HM set DimPBU01 getConfig noArg
...
2021.04.21 16:29:40.519 0: HMLAN_Send:  hmlan1 S:SF4D62F44 stat:  00 t:00000000 d:01 r:F4D62F44 m:08 A001 1ACE1F 266A86 01040000000001
2021.04.21 16:29:40.520 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:40.812 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:05795BC1 IDcnt:0011 L:4 %
...
2021.04.21 16:29:55.860 0: HMLAN_Send:  hmlan1 S:SF4D66B8C stat:  00 t:00000000 d:01 r:F4D66B8C m:32 A001 1ACE1F 266A86 0204266A860203
2021.04.21 16:29:55.861 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:56.863 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:57.865 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:58.866 0: HMLAN_Send:  hmlan1 I:K
2021.04.21 16:29:59.869 1: HMLAN_Parse: hmlan1 new condition disconnected
2021.04.21 16:29:59.917 1: 192.168.1.9:1000 disconnected, waiting to reappear (hmlan1)




zusammenfassung

a. werden die "zusätzlichen" keepalive absichtlich erzeugt? kann man die vielleicht verhindern?
b. könnte man das gleichzeitige senden von cmds mit keepalive vermeiden?
c. wirken nicht auch "normale" cmds an den hmlan als "pseudo-keepalive", sodass echte keepalive zb auch erst nach einem vollständigen getconfig-ablauf gesendet werden könnten?


gruss frank


edit:
hier noch ein beispiel von einem hmlan reboot, der kurz nach mitternacht infolge eines timerequest erfolgte.
auch hier war vor dem reboot wieder ein unnötiger, zusätzlicher keepalive zu sehen, der sich an ein HMLAN_Send "geheftet" hat.
2021.04.17 00:01:03.371 0: HMLAN_Send:  hmlan1 S:SDCB3A3A2 stat:  00 t:00000000 d:01 r:DCB3A3A2 m:21 803F 1ACE1F 1BF81B 0202280CD3AF
2021.04.17 00:01:03.372 0: HMLAN_Send:  hmlan1 I:K


2021.04.16 23:59:55.993 0: HMLAN_Send:  hmlan1 I:K
2021.04.16 23:59:55.997 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:01F116B2 IDcnt:0010 L:1 %
2021.04.17 00:00:03.029 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 8F A2 58 B2B2B2 1DFC2F 00FD
2021.04.17 00:00:03.066 4: CUL_Parse: cul868 A 0B 8F A258 B2B2B2 1DFC2F 00FD66 -23
2021.04.17 00:00:03.074 0: HMLAN_Parse: hmlan1 R:EB2B2B2   stat:0000 t:01F13245 d:FF r:FFCD     m:8F A258 B2B2B2 1DFC2F 00FD
2021.04.17 00:00:03.187 4: CUL_Parse: cul868 A 0E 8F 8202 1DFC2F B2B2B2 0101C6002D16 -63
2021.04.17 00:00:03.217 0: HMUARTLGW hmuart1 recv: 01 04 03 00 2C msg: 8F 82 02 1DFC2F B2B2B2 0101C6002D
2021.04.17 00:00:03.221 0: HMLAN_Parse: hmlan1 R:E1DFC2F   stat:0000 t:01F132C8 d:FF r:FFDE     m:8F 8202 1DFC2F B2B2B2 0101C6002D
2021.04.17 00:00:07.308 0: HMUARTLGW hmuart1 send: 00 08
2021.04.17 00:00:07.312 0: HMUARTLGW hmuart1 recv: 00 04020A, state 98
2021.04.17 00:00:07.314 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.17 00:00:07.314 0: HMUARTLGW hmuart1 roundtrip delay: 0.0032
2021.04.17 00:00:07.952 4: CUL_Parse: cul868 A 0C F1 8670 20DFE1 000000 00C53308 -70
2021.04.17 00:00:07.960 0: HMUARTLGW hmuart1 recv: 01 05 00 00 42 msg: F1 86 70 20DFE1 000000 00C533
2021.04.17 00:00:07.963 0: HMLAN_Parse: hmlan1 R:E20DFE1   stat:0000 t:01F14566 d:FF r:FFCC     m:F1 8670 20DFE1 000000 00C533
2021.04.17 00:00:08.136 4: CUL_Parse: cul868 A 09 F2 A03F 20DFE1 1ACE1F 08 -70
2021.04.17 00:00:08.237 0: HMLAN_Send:  hmlan1 S:SDCB2CC44 stat:  00 t:00000000 d:01 r:DCB2CC44 m:F2 803F 1ACE1F 20DFE1 0202280CD378
2021.04.17 00:00:08.262 0: HMLAN_Parse: hmlan1 R:E20DFE1   stat:0000 t:01F1461F d:FF r:FFCC     m:F2 A03F 20DFE1 1ACE1F
2021.04.17 00:00:08.265 0: HMUARTLGW hmuart1 recv: 01 05 00 00 43 msg: F2 A0 3F 20DFE1 1ACE1F
2021.04.17 00:00:08.269 4: CUL_Parse: cul868 A 0F F2 803F 1ACE1F 20DFE1 0202280CD3783E -43
2021.04.17 00:00:08.273 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: F2 80 3F 1ACE1F 20DFE1 0202280CD378
2021.04.17 00:00:08.536 4: CUL_Parse: cul868 A 0F F2 803F 1ACE1F 20DFE1 0202280CD3783E -43
2021.04.17 00:00:08.540 0: HMLAN_Parse: hmlan1 R:E20DFE1   stat:0000 t:01F1461F d:FF r:FFCC     m:F2 A03F 20DFE1 1ACE1F
2021.04.17 00:00:08.543 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: F2 80 3F 1ACE1F 20DFE1 0202280CD378
2021.04.17 00:00:08.547 0: HMLAN_Parse: hmlan1 R:RDCB2CC44 stat:0002 t:00000000 d:FF r:7FFF     m:F2 803F 1ACE1F 20DFE1 0202280CD378
2021.04.17 00:00:13.129 4: CUL_Parse: cul868 A 14 B0 805E 266EA5 1ACE1F 000000000000000000000013 -64.5
2021.04.17 00:00:13.135 0: HMUARTLGW hmuart1 recv: 01 05 00 00 35 msg: B0 80 5E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 00:00:13.139 0: HMLAN_Parse: hmlan1 R:E266EA5   stat:0000 t:01F1599E d:FF r:FFCC     m:B0 805E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 00:00:20.994 0: HMLAN_Send:  hmlan1 I:K
2021.04.17 00:00:20.999 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:01F1785F IDcnt:0010 L:1 %
2021.04.17 00:00:22.313 0: HMUARTLGW hmuart1 send: 00 08
2021.04.17 00:00:22.318 0: HMUARTLGW hmuart1 recv: 00 04020A, state 98
2021.04.17 00:00:22.319 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.17 00:00:22.320 0: HMUARTLGW hmuart1 roundtrip delay: 0.0036
2021.04.17 00:00:31.987 4: CUL_Parse: cul868 A 14 B1 805E 266EA5 1ACE1F 000000000000000000000013 -64.5
2021.04.17 00:00:32.001 0: HMUARTLGW hmuart1 recv: 01 05 00 00 34 msg: B1 80 5E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 00:00:32.005 0: HMLAN_Parse: hmlan1 R:E266EA5   stat:0000 t:01F1A34B d:FF r:FFCD     m:B1 805E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 00:00:37.318 0: HMUARTLGW hmuart1 send: 00 08
2021.04.17 00:00:37.323 0: HMUARTLGW hmuart1 recv: 01 05 00 00 4B msg: 64 86 70 2064CB 000000 006B3F
2021.04.17 00:00:37.331 4: CUL_Parse: cul868 A 0C 64 8670 2064CB 000000 006B3FF0 -82
2021.04.17 00:00:37.333 4: CUL_HM Thermostat.GZ dupe: dont process
2021.04.17 00:00:37.335 0: HMLAN_Parse: hmlan1 R:E2064CB   stat:0000 t:01F1B81F d:FF r:FFAC     m:64 8670 2064CB 000000 006B3F
2021.04.17 00:00:37.337 4: CUL_HM Thermostat.GZ dupe: dont process
2021.04.17 00:00:37.339 0: HMUARTLGW hmuart1 recv: 00 04020A, state 98
2021.04.17 00:00:37.340 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.17 00:00:37.556 0: HMLAN_Parse: hmlan1 R:E2064CB   stat:0000 t:01F1B8DD d:FF r:FFAB     m:65 A03F 2064CB 1ACE1F
2021.04.17 00:00:37.561 5: CUL_HM Thermostat.GZ protEvent:CMDs_done, 0 -> 0, +2064CB,00,00,00, 2
2021.04.17 00:00:37.562 5: CUL_HM Thermostat.GZ sent ACK:2
2021.04.17 00:00:37.582 0: HMUARTLGW hmuart1 recv: 01 05 01 00 4B msg: 65 A0 3F 2064CB 1ACE1F
2021.04.17 00:00:37.584 4: CUL_HM Thermostat.GZ dupe: dont process
2021.04.17 00:00:37.585 4: CUL_Parse: cul868 A 09 65 A03F 2064CB 1ACE1F EF -82.5
2021.04.17 00:00:37.587 4: CUL_HM Thermostat.GZ dupe: dont process
2021.04.17 00:00:37.609 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 65 80 3F 1ACE1F 2064CB 0202280CD395
2021.04.17 00:00:37.616 0: HMUARTLGW hmuart1 recv: 01 0408, state 101
2021.04.17 00:00:37.617 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 00:00:37.638 4: CUL_Parse: cul868 A 0F 65 803F 1ACE1F 2064CB 0204280CC59366 -23
2021.04.17 00:00:37.649 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:01F1B960 d:FF r:FFCD     m:65 803F 1ACE1F 2064CB 0204280CC593
2021.04.17 00:00:37.687 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 65 80 3F 1ACE1F 2064CB 0202280CD395
2021.04.17 00:00:37.781 0: HMUARTLGW hmuart1 recv: 01 0402, state 101
2021.04.17 00:00:37.782 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 00:00:37.783 4: CUL_Parse: cul868 A 0F 65 803F 1ACE1F 2064CB 0202280CD39566 -23
2021.04.17 00:00:37.791 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:01F1B9EE d:FF r:FFCD     m:65 803F 1ACE1F 2064CB 0202280CD395
2021.04.17 00:00:45.996 0: HMLAN_Send:  hmlan1 I:K
2021.04.17 00:00:46.000 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:01F1DA0B IDcnt:0010 L:1 %
2021.04.17 00:00:48.212 4: CUL_Parse: cul868 A 0C 4E 8670 1D252E 000000 00C2340E -67
2021.04.17 00:00:48.219 0: HMUARTLGW hmuart1 recv: 01 05 00 00 40 msg: 4E 86 70 1D252E 000000 00C234
2021.04.17 00:00:48.222 0: HMLAN_Parse: hmlan1 R:E1D252E   stat:0000 t:01F1E2AF d:FF r:FFC6     m:4E 8670 1D252E 000000 00C234
2021.04.17 00:00:48.423 4: CUL_Parse: cul868 A 09 4F A03F 1D252E 1ACE1F 0E -67
2021.04.17 00:00:48.523 0: HMLAN_Send:  hmlan1 S:SDCB369A1 stat:  00 t:00000000 d:01 r:DCB369A1 m:4F 803F 1ACE1F 1D252E 0202280CD3A0
2021.04.17 00:00:48.545 0: HMLAN_Parse: hmlan1 R:E1D252E   stat:0000 t:01F1E381 d:FF r:FFC6     m:4F A03F 1D252E 1ACE1F
2021.04.17 00:00:48.548 0: HMUARTLGW hmuart1 recv: 01 05 00 00 40 msg: 4F A0 3F 1D252E 1ACE1F
2021.04.17 00:00:48.551 4: CUL_Parse: cul868 A 0F 4F 803F 1ACE1F 1D252E 0202280CD3A03E -43
2021.04.17 00:00:48.556 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 4F 80 3F 1ACE1F 1D252E 0202280CD3A0
2021.04.17 00:00:48.822 4: CUL_Parse: cul868 A 0F 4F 803F 1ACE1F 1D252E 0202280CD3A03E -43
2021.04.17 00:00:48.825 0: HMLAN_Parse: hmlan1 R:E1D252E   stat:0000 t:01F1E381 d:FF r:FFC6     m:4F A03F 1D252E 1ACE1F
2021.04.17 00:00:48.828 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 4F 80 3F 1ACE1F 1D252E 0202280CD3A0
2021.04.17 00:00:48.831 0: HMLAN_Parse: hmlan1 R:RDCB369A1 stat:0002 t:00000000 d:FF r:7FFF     m:4F 803F 1ACE1F 1D252E 0202280CD3A0
2021.04.17 00:00:50.845 4: CUL_Parse: cul868 A 14 B2 805E 266EA5 1ACE1F 000000000000000000000013 -64.5
2021.04.17 00:00:50.859 0: HMUARTLGW hmuart1 recv: 01 05 00 00 35 msg: B2 80 5E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 00:00:50.863 0: HMLAN_Parse: hmlan1 R:E266EA5   stat:0000 t:01F1ECF7 d:FF r:FFCD     m:B2 805E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 00:00:52.323 0: HMUARTLGW hmuart1 send: 00 08
2021.04.17 00:00:52.327 0: HMUARTLGW hmuart1 recv: 00 04020A, state 98
2021.04.17 00:00:52.329 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.17 00:00:52.329 0: HMUARTLGW hmuart1 roundtrip delay: 0.0031
2021.04.17 00:01:03.068 4: CUL_Parse: cul868 A 0C 20 8670 1BF81B 000000 00CB3128 -54
2021.04.17 00:01:03.168 0: HMUARTLGW hmuart1 recv: 01 05 00 00 32 msg: 20 86 70 1BF81B 000000 00CB31
2021.04.17 00:01:03.171 0: HMLAN_Parse: hmlan1 R:E1BF81B   stat:0000 t:01F21CB9 d:FF r:FFD3     m:20 8670 1BF81B 000000 00CB31
2021.04.17 00:01:03.271 4: CUL_Parse: cul868 A 09 21 A03F 1BF81B 1ACE1F 27 -54.5
2021.04.17 00:01:03.371 0: HMLAN_Send:  hmlan1 S:SDCB3A3A2 stat:  00 t:00000000 d:01 r:DCB3A3A2 m:21 803F 1ACE1F 1BF81B 0202280CD3AF
2021.04.17 00:01:03.372 0: HMLAN_Send:  hmlan1 I:K
2021.04.17 00:01:03.395 0: HMUARTLGW hmuart1 recv: 01 05 00 00 32 msg: 21 A0 3F 1BF81B 1ACE1F
2021.04.17 00:01:03.398 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3E msg: 52 86 70 1DFDA5 000000 00803F
2021.04.17 00:01:03.403 0: HMLAN_Parse: hmlan1 R:E1BF81B   stat:0000 t:01F21D83 d:FF r:FFD3     m:21 A03F 1BF81B 1ACE1F
2021.04.17 00:01:03.406 4: CUL_Parse: cul868 A 0C 52 8670 1DFDA5 000000 00803F13 -64.5
2021.04.17 00:01:03.408 4: CUL_Parse: cul868 A 0F 21 803F 1ACE1F 1BF81B 0202280CD3AF3E -43
2021.04.17 00:01:03.413 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 21 80 3F 1ACE1F 1BF81B 0202280CD3AF
2021.04.17 00:01:03.560 4: CUL_Parse: cul868 A 09 53 A03F 1DFDA5 1ACE1F 12 -65
2021.04.17 00:01:03.585 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3E msg: 53 A0 3F 1DFDA5 1ACE1F
2021.04.17 00:01:03.662 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 53 80 3F 1ACE1F 1DFDA5 0202280CD3AF
2021.04.17 00:01:03.668 0: HMUARTLGW hmuart1 recv: 01 0408, state 101
2021.04.17 00:01:03.669 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 00:01:03.690 4: CUL_Parse: cul868 A 0F 53 803F 1ACE1F 1DFDA5 0204280CC5AD66 -23
2021.04.17 00:01:03.882 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 53 80 3F 1ACE1F 1DFDA5 0202280CD3AF
2021.04.17 00:01:03.912 0: HMUARTLGW hmuart1 recv: 01 0402, state 101
2021.04.17 00:01:03.914 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 00:01:03.915 4: CUL_Parse: cul868 A 0F 53 803F 1ACE1F 1DFDA5 0202280CD3AF66 -23
2021.04.17 00:01:04.373 0: HMLAN_Send:  hmlan1 I:K
2021.04.17 00:01:04.431 4: CUL_Parse: cul868 A 0F 21 803F 1ACE1F 1BF81B 0202280CD3AF3E -43
2021.04.17 00:01:04.436 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 21 80 3F 1ACE1F 1BF81B 0202280CD3AF
2021.04.17 00:01:05.374 0: HMLAN_Send:  hmlan1 I:K
2021.04.17 00:01:06.375 0: HMLAN_Send:  hmlan1 I:K
2021.04.17 00:01:07.327 0: HMUARTLGW hmuart1 send: 00 08
2021.04.17 00:01:07.332 0: HMUARTLGW hmuart1 recv: 00 04020A, state 98
2021.04.17 00:01:07.333 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.17 00:01:07.333 0: HMUARTLGW hmuart1 roundtrip delay: 0.0031
2021.04.17 00:01:07.376 1: HMLAN_Parse: hmlan1 new condition disconnected
2021.04.17 00:01:07.395 3: n_log_io_condition return value: 2021-04-17 00:01:07
2021.04.17 00:01:07.422 1: 192.168.1.9:1000 disconnected, waiting to reappear (hmlan1)
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

frank

ich denke, ich habe die "zusätzlichen" keepalive in HMLAN_SimpleWrite() entdeckt:

  if ($hash->{helper}{q}{scnt} == 10){
    $hash->{helper}{q}{scnt} = 0;
    HMLAN_KeepAlive("x:$name") ;
  }


welche idee steckt dahinter, bei jedem 10. cmd ein keepalive zusätzlich zu senden?

ich kommentiere die zeile "HMLAN_KeepAlive("x:$name") ;" einfach mal aus.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

frank

erster eindruck ohne zusätzliche keepalive:

die keepalive kommen nun regelmässig alle 25 sek, sieht sehr auber aus.
auch während den getconfigs werden sie planmässig ausgeführt.

allerdings revidiere ich jetzt meine theorie, dass die zusätzlichen keepalive während der getconfigs zu reboots geführt haben könnten.

ergebnis nach einem stresstest mit 22 getconfig in folge: 2 reboots beim hmlan.
der hmlan hat effektiv nur 17 ausgeführt, 2 davon nur zu etwa 3/4 wegen den reboots.
der hmuart hat während der reboots perfekt im fliegenden wechsel übernommen, so dass ich gar nichts gemerkt habe.

nach den ersten 3 getconfig gab es gut 10 min pause.
dann nach weiteren 6 und 3/4 => reboot.
hmuart hat 3 und 1/4 übernommen.
ab dem 14. getconfig hat der hmlan wieder 6 und 3/4 bis zu 2. reboot ausgeführt.
die restlichen 2 und 1/4 wurden wieder vom hmuart übernommen.


dann werde ich mir demnächst mal neue kondensatoren besorgen und auch das netzteil testen.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

martinp876

zusätzliche keepalive haben bislang noch nicht geschadet - auser (minimal) Performace.

Ein HMLAN zeigt an, wie lange das Delay der keepalive maximal war. Wir steht dieser?
Ich kann es nach dem Ableben meines treuen HMLAN nicht mehr testen.

Also:
- wie ist der maximale Abstand der keepAlive?
- wie ist die Funklast und kommen wir in Overload?

noansi

Hallo Frank,

Zitatwelche idee steckt dahinter, bei jedem 10. cmd ein keepalive zusätzlich zu senden?
Wenn mein kurzer Blick in den Code es richtig interpretiert hat ist die Idee ein Update des Load, der damit gepollt wird.

Gruß, Ansgar.

frank

hallo martin,

ZitatAlso:
- wie ist der maximale Abstand der keepAlive?
- wie ist die Funklast und kommen wir in Overload?
das ist doch genauestens im sniff zu sehen.
die keepalives vor und nach dem hmlan reboot sogar noch mal extra rauskopiert unter punkt 5.

16s vor reboot regulärer keepalive -> 4% load
15s vor reboot zusätzlicher keepalive ->4% load

im sniff unter punkt 4 ist die init meldung vom hmlan zu sehen, die 0% load zeigt. ebenso ist die uptime zurückgesetzt, also totalcrash am hmlan.

ich würde es brgrüssen, wenn die zusätzlichen keepalives gecancelt werden.
ausser zusätzlichem stress für den hmlan, kann ich keinen "mehrwert" dadurch erkennen.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

frank

Zitat von: noansi am 24 April 2021, 09:14:23
Hallo Frank,
Wenn mein kurzer Blick in den Code es richtig interpretiert hat ist die Idee ein Update des Load, der damit gepollt wird.

das wird ja eh alle 25s geliefert.
wenn die zusätzlichen keepalive einen bezug zum overload hätten könnte ich den sinn ja vielleicht verstehen.

zwischen 2 zusätzlichen keepalive können aber stunden, tage oder wochen liegen. eben 10 beliebige cmds zu beliebigen devices zu beliebigen zeitpunkten.

ein getconfig beim dimmer besteht aus mindesten 13 cmds. im beispiel oben wären es 3 keepalive in 19s geworden. auf den 3. hatte der hmlan wohl keine lust mehr und ist vorher ausgestiegen.  :)

soweit ich mich erinnere, erfolgen die internen load berechnungen im hmlan auch nicht kontinuierlich, sondern in intervallen. vielleicht 1 oder 5 oder 10 min?
somit ist auch in der regel nichts neues zu erfahren.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

mgernoth

Hi,

hab gerade ein Déjà-vu ;-)

https://forum.fhem.de/index.php/topic,20776.msg456300.html#msg456300
https://forum.fhem.de/index.php/topic,20776.msg489732.html#msg489732

Hab den Patch seitdem bei mir lokal drin...

Viele Grüße
  Michael

frank

FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

frank

hallo michael,

scheinbar hilft der patch auch hier beim dimmer.  :)


seit 2 tagen habe ich deine 00_HMLAN.pm von 2016 im einsatz.
zunächst etwas enttäuscht, da nach 5 getconfig bereits der erste hmlan reboot erfolgte.
aber nach ein paar stunden habe ich erneut eine salve mit 18 getconfig gestartet => kein reboot, alles bestens.
heute noch eine salve mit 27 getconfig gestartet => wieder kein reboot zu beobachten.
im gegenteil, heute hat der dimmer aufgegeben. scheinbar ist er im overload, denn der hmlan zeigte zu der zeit bereits highload an.


nun frage ich mich natürlich, warum der hmlan ausgerechnet beim 5. getconfig nicht mehr wollte.
"zufällig" ist er genau 30 sek nach dem vorletzten keepalive ausgestiegen. wirklich zufall?

hier sind die letzten 2 getconfig vor dem reboot.
vielleicht fällt dir ja noch etwas auf, das man verbessern könnte.


schade, dass martin den patch noch nicht übernommen hat.
vielleicht würde sein hmlan dann noch leben.  ;)

gruss frank


edit: sniff in datei gepackt, da zu lang für codeblock
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

noansi

Hallo Frank, hallo Michael,

hier
2021.04.25 11:02:52.568 0: HMLAN_Send:  hmlan1 S:S08446DC0 stat:  00 t:00000000 d:01 r:08446DC0 m:D2 A001 1ACE1F 266A86 0304266A860103
2021.04.25 11:02:52.592 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0A155A9A d:FF r:FFD2     m:C2 A010 266A86 1ACE1F 030000

würde ich sagen, hat dem HMLAN schon was nicht geschmeckt, denn danach empfängt keines der anderen IOs die Sendenachricht von HMLAN. Auch nicht eine der Wiederholungen. Und das war 9,002s nach dem KeepAlive.

Macht HMLAN CCA? Dann wäre noch eine weitere Möglichkeit, dass es sich daran in ungünstiger zeitlicher Konstellation oder Menge verschluckt.
Denn es passiert ja noch etwas mehr in the Air drumherum:
2021.04.25 11:02:52.568 0: HMLAN_Send:  hmlan1 S:S08446DC0 stat:  00 t:00000000 d:01 r:08446DC0 m:D2 A001 1ACE1F 266A86 0304266A860103
2021.04.25 11:02:52.592 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0A155A9A d:FF r:FFD2     m:C2 A010 266A86 1ACE1F 030000
2021.04.25 11:02:52.596 4: CUL_Parse: cul868 A 0A C2 8002 1ACE1F 266A86 0040 -42
2021.04.25 11:02:52.600 0: HMUARTLGW hmuart1 recv: 01 05 00 00 39 msg: C2 A0 10 266A86 1ACE1F 030000
2021.04.25 11:02:52.603 0: HMUARTLGW hmuart1 recv: 01 05 00 00 30 msg: C2 80 02 1ACE1F 266A86 00


Gruß, Ansgar.

frank

moin,

seit 99 getconfigs kein reboot mehr.
das waren 4 salven, jeweils bis zum overload des dimmers.

das zeigt mir mehr als deutlich, dass sich der patch von @mgernoth aus beitrag #7 positiv auf den hmlan auswirkt.
https://forum.fhem.de/index.php/topic,120600.msg1152020.html#msg1152020


als ursache für den einen reboot, beim 5. getconfig nach umstellung auf den patch, vermute ich zur zeit, dass der hmlan bereits eine "schädigung" durch die normale 00_HMLAN.pm mitgebracht hatte.

auch die aktuelle 00_HMLAN.pm (18152) hat bereits eine salve mit 27 getconfigs erfolgreich überstanden.
wer für seinen hmlan etwas gutes tun möchte, sollte umgehend den patch einspielen.
um auch eventuelle "vorschädigungen" zu beseitigen, könnte ein manueller reboot des hmlan, während die gepatchte 00_HMLAN.pm bereits läuft, vorteilhaft sein.


gruss frank


edit: die gepatchte, aktuelle 00_HMLAN.pm angehängt
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Gisbert

Hallo Frank,

ich hab die in Antwort #11 veröffentlichte Datei im Einsatz. Gleichzeitig habe ich den Datendurchsatz im Switch auf 10 Mbit/s für den Adapter begrenzt. Damit läuft der HM-LAN-Adapter stabil. Die Zeit wird zeigen, ob er ultrastabil läuft.

Ich bin begeistert.
Gibt es die Datei irgendwann über den Fhem update-Prozess? Zur Sicherheit habe ich das Modul vom update vorerst ausgeschlossen, um nicht das schöne Ergebnis zu zerstören.

Viele​ Grüße​ Gisbert​
Aktuelles FHEM | PROXMOX | Fujitsu Futro S740 | Debian 12 | UniFi | Homematic, VCCU, HMUART | ESP8266 | ATtiny85 | Wasser-, Stromzähler | tuya local | Wlan-Kamera | SIGNALduino, Flamingo Rauchmelder FA21/22RF | RHASSPY

frank

ZitatGibt es die Datei irgendwann über den Fhem update-Prozess?
wäre schon schön, wenn martin den patch endlich mal einbauen würde, damit alle noch lebenden hmlan davon profitieren könnten.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

frank

danke, eingecheckt.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html