[cul_hm] HM-LC-DIM1TPBU-FM: state von channel 3 zeigt kein unreachable an

Begonnen von frank, 26 Februar 2021, 10:25:59

Vorheriges Thema - Nächstes Thema

noansi

Hallo Frank,

Zitat1. dimmer ohne spannung
chn3 meldet jetzt auch unreachable, allerdings ist es, wie bei chn2, "geraten", da weiterhin nur requests an chn1 gesendet werden.
Der 20s Timeout wird für eine Antwort gesetzt und im Sekunden Takt ein weiterer Channel mit Status Request getestet. Nach 22s müssen demnach alle 3 Channels auf Timeout stehen, wenn nicht geantwortet wird.
Repeats kommen mit maximal 6s Intervall, d.h. nach max. 3 x 6s = 18s sind alle Repeats von Channel 1 gesendet, 13s waren es bei Dir. Nach weiteren max. 6s geht das Protokoll auf resendfail und wirft den ganzen Protokollstack weg, also auch die requests an Channel 2 und 3.

Die 20s Timeout passen grundsätzlich zu den Wiederholungen. Das Ergebnis der unreachables 2 und 3 entspricht nicht einem direkten Kommunikationsergebnis mit dem jeweiligen Channel, passt aber, weil das device nicht in angemessener Zeit antwortet, respektive sicherlich auch nicht auf Anfragen an Channel 2 und 3 geantwortet hätte. ;) Feature by design.

Mit einem Standard CUL wäre der Timeout allerdings etwas knapp, denn mit dem ist es wesentlich wahrscheinlichlicher, dass eine Anfrage scheitert, da er nicht selbst wiederholt. Damit kann es für Channel 2 und 3 eng werden, wenn jedes mal eher die eine der letzten Wiederholungen erfolgreich ist.

Hängt natürlich auch noch an der maximalen Anzahl Channels von devices, die einen stautsRequest erlauben, ob für holprige Kommunikation nicht doch eher ein höherer Timeout als 20s angemessen wäre.

Ich hoffe, ich habe die Zeiten richtig raus gelesen. Im Mittel kommen die Wiederholungen übrigens früher, weil ein random Anteil eingebaut ist. Ändert aber nichts daran, dass bei ausbleibenden Antworten nichts mehr an Channel 2 und 3 gesendet wird, weil das Protokoll es verwirft.

Gruß, Ansgar.

PS: Mit welcher CUL_HM Version hast Du den Änderungsvorschlag getestet?

frank

ZitatPS: Mit welcher CUL_HM Version hast Du den Änderungsvorschlag getestet?
gute frage.  :)

deine letzte mit den zusätzlichen logeinträgen für die wakeup-status-queue-probleme von hier:
https://forum.fhem.de/index.php/topic,119853.msg1147692.html#msg1147692

# $Id: 10_CUL_HM.pm 24158 2021-04-05 13:33:24Z martinp876 $
# noansi: modified for testing wakup/lazy config changes



ZitatÄndert aber nichts daran, dass bei ausbleibenden Antworten nichts mehr an Channel 2 und 3 gesendet wird, weil das Protokoll es verwirft.
das wirft dann aber martins beschreibung zu unreachable über den haufen.

danach ist nur chn1 unreachable, da explizit nur chn1 nicht antwortet.
chn2/3 müssten dann zb unknown zeigen, um zu signalisieren, dass kein aktueller zustand lieferbar ist.
zumindestens nicht unreachable und auch nicht die zustände vor der prüfung.

besser wäre natürlich, alle channel werden wirklich abgefragt.
am meisten stört mich immer, dass im log steht, dass ein statusrequest auf einen bestimmten chn ausgelöst wurde.
mit default verbose einstellungen suggeriert das log, dass für alle 3 channel ein statusrequest gesendet wurde. so gesehen sind das "fake" meldungen.

wenn das protokoll die weiteren requests verwirft, werden diese requests zu früh auf den stack gegeben. dann dürfte jeder weitere request erst dann auf den stack kommen, wenn der vorherige bereits vollständig "verarbeitet" wurde.

Zitat"unreachable" wird nur bei "automatischen" StatusRequest bearbeitet - und wird nur auf einen Kanal angewendet. D.h. wenn ein Kanal eines Device unreachable anzeigt wird dies nicht auf die anderen angewendet.
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,

Zitatdanach ist nur chn1 unreachable, da explizit nur chn1 nicht antwortet.
Nach dem was ich aus dem Code verstanden habe, richtig.

Zitatchn2/3 müssten dann zb unknown zeigen, um zu signalisieren, dass kein aktueller zustand lieferbar ist.
zumindestens nicht unreachable
Im Prinzip ja.

besser wäre natürlich, alle channel werden wirklich abgefragt.
Da msgRepeat zur Anwendung kommt, würdest Du ein mehrfaches an Funklast erzeugen/credits verschwenden, für das gleiche Ergebnis.
Natürlich kannst Du das Experiment mit verringertem msgRepeat wiederholen und dann müßten in der Zeit alle Channels abgefragt werden können. Für den Normalbetrieb wirst Du damit natürlich eher weniger glücklich. Auch würdest Du mit extra für den automatischen statusRequest verringerten msgRepeat nicht glücklich werden.
Denkbar wäre noch, bei jedem automatischen statusRequest einen einzigen zyklisch wechselnden channel abzufragen. Bedeutet für die Statusaktualisierung natürlich, dass nicht alle channels "synchron" angezeigt würden.

Für das Ping des Actiondetectors beispielsweise habe ich in meiner Sonderversion auf statusRequest zu nur einem (gleichen) channel reduziert, falls statusRequest dafür zur Anwendung kommt.

Zitatmit default verbose einstellungen suggeriert das log, dass für alle 3 channel ein statusrequest gesendet wurde. so gesehen sind das "fake" meldungen.
Vielleicht kann Dir Martin mit einer anderen Formulierung der Log Meldung entgegen kommen?! Vorschlag?

Zitatwenn das protokoll die weiteren requests verwirft, werden diese requests zu früh auf den stack gegeben. dann dürfte jeder weitere request erst dann auf den stack kommen, wenn der vorherige bereits vollständig "verarbeitet" wurde.
Um den Preis mehr verschwendeter credits. Ich würde es dabei belassen wollen und lieber den Log Text näher der Realität anpassen.
Der Aufwand von mehr Code zur Präzisierung sollte auch mit dem Nutzen abgewägt werden, denke ich.
Dass auch der letzte channel unreacheble meldet halte ich dagegen für wichtig, weil sonst funktionsrelevant regelmäßig was falsches suggeriert wird.
Edit: Erledigt.

Das Wakup Problem sehe ich auch als wichtiger an.

Zitatwenn ein Kanal eines Device unreachable anzeigt wird dies nicht auf die anderen angewendet.
Wird es auch nicht direkt, nur indirekt. Würde das unreachable erst bei Kanal 2 eintreten, würde nicht Kanal 1 auf unreachable gesetzt. Bei Eintreten bei Kanal 3 würden 1 und 2 auch nicht auf unreachable gesetzt. Auch bei nur z.B. 2 würden 1 und 3 nicht auf unreachable gesetzt.

Zitatdeine letzte mit den zusätzlichen logeinträgen für die wakeup-status-queue-probleme von hier:
Dann sollte ich die wohl mal auf Martins letzten Stand synchronisieren, damit das Verhalten aus dem letzten 3. auch sicher besser zum SVN Stand passt. ;-)
Sollte ich mal nicht einschlafen können, kann ich anfangen 00_CUL_HM.pm zu zählen...  ;D

Gruß, Ansgar.

noansi

Hallo Frank,

ich habe hier https://forum.fhem.de/index.php/topic,119853.msg1147692.html#msg1147692 den Teststand an 24214 angenähert.
Wenn Du 3. bitte nochmal wiederholen könntest...

Gruß, Ansgar.

frank

Zitat von: noansi am 14 April 2021, 21:52:28
Wenn Du 3. bitte nochmal wiederholen könntest...
der unreachable patch ist in deiner neuen cul_hm nicht enthalten. absicht?

nach spgszufuhr selbes ergebnis => PeerIncomm chn1

2021.04.15 10:09:44.851 4: CUL_Parse: cul868 A 0F 00 A410 266A86 1ACE1F 060100008000FA -77
2021.04.15 10:09:44.854 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 10:09:44.870 2: HMinfo hminfo get:configCheck :-f,^(DimPBU01|DimPBU01_Dim|DimPBU01_Dim_V_01|DimPBU01_Dim_V_02|DimPBU01)$
2021.04.15 10:09:44.920 0: HMLAN_Send:  hmlan1 I:+266A86,00,00,00
2021.04.15 10:09:44.922 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 10:09:44.923 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:44.945 1: ----- BLACKOUT ----- DimPBU01(266A86) -> no blackout-detect for battery-devices
2021.04.15 10:09:44.982 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:45.075 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 00 A4 10 266A86 1ACE1F 060100008000
2021.04.15 10:09:45.077 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:45.079 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD735833 d:FF r:FFCE     m:00 A410 266A86 1ACE1F 060100008000
2021.04.15 10:09:45.080 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:45.175 4: CUL_Parse: cul868 A 0F 01 A410 266A86 1ACE1F 0602000080000A -69
2021.04.15 10:09:45.177 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 10:09:45.180 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 10:09:45.181 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:45.205 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:45.270 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 01 A4 10 266A86 1ACE1F 060200008000
2021.04.15 10:09:45.272 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:45.273 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD735977 d:FF r:FFCE     m:01 A410 266A86 1ACE1F 060200008000
2021.04.15 10:09:45.275 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:45.295 4: CUL_Parse: cul868 A 0A 01 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:45.300 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 01 80 02 1ACE1F 266A86 00
2021.04.15 10:09:45.779 4: CUL_Parse: cul868 A 0F 02 A410 266A86 1ACE1F 06030000800009 -69.5
2021.04.15 10:09:45.782 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 10:09:45.785 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 10:09:45.786 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:45.810 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:45.875 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 02 A4 10 266A86 1ACE1F 060300008000
2021.04.15 10:09:45.877 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:45.879 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD735BD3 d:FF r:FFCE     m:02 A410 266A86 1ACE1F 060300008000
2021.04.15 10:09:45.881 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:45.899 4: CUL_Parse: cul868 A 0A 02 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:45.904 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 02 80 02 1ACE1F 266A86 00
2021.04.15 10:09:45.919 3: CUL_HM set DimPBU01 getConfig noArg
2021.04.15 10:09:45.938 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:45.957 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:45.959 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:1, 0 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:45.977 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.018 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.038 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.040 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:2, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.058 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.077 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.079 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:3, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.117 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.137 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.139 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.157 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.177 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.178 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:5, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.213 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.233 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.235 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:6, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.253 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.273 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.275 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.276 0: HMLAN_Send:  hmlan1 S:SD494379C stat:  00 t:00000000 d:01 r:D494379C m:03 A001 1ACE1F 266A86 00040000000000
2021.04.15 10:09:46.295 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.297 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:46.307 4: CUL_Parse: cul868 A 10 03 A001 1ACE1F 266A86 0004000000000040 -42
2021.04.15 10:09:46.312 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 03 A0 01 1ACE1F 266A86 00040000000000
2021.04.15 10:09:46.438 4: CUL_Parse: cul868 A 18 03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000EE -83
2021.04.15 10:09:46.441 4: mTp:10 wait:RegisterRead got mStp:02 :
          forPeer:
          nAddr:0
          forChn:00
          forList:00
          mNo:3
          Pending:RegisterRead
          cmd:As1003A0011ACE1F266A8600040000000000
          reSent:1
2021.04.15 10:09:46.446 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:46.447 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:46.449 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:6, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.474 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.477 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD735E65 d:FF r:FFCE     m:03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 10:09:46.478 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:46.481 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 03 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 10:09:46.483 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:46.554 4: CUL_Parse: cul868 A 0A 03 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:46.559 0: HMLAN_Parse: hmlan1 R:RD494379C stat:0001 t:AD735E6A d:FF r:FFCE     m:03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 10:09:46.561 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:46.664 0: HMLAN_Send:  hmlan1 S:SD49438BB stat:  00 t:00000000 d:01 r:D49438BB m:04 A001 1ACE1F 266A86 01040000000001
2021.04.15 10:09:46.665 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:46.668 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 03 80 02 1ACE1F 266A86 00
2021.04.15 10:09:46.681 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 030000F4 -80
2021.04.15 10:09:46.683 4: mTp:10 wait:RegisterRead got mStp:03 :
          forList:01
          nAddr:0
          forPeer:
          forChn:01
          reSent:1
          mNo:4
          cmd:As1004A0011ACE1F266A8601040000000001
          Pending:RegisterRead
2021.04.15 10:09:46.689 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:46.690 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:46.691 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:5, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:46.716 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:46.719 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD735F59 d:FF r:FFCE     m:04 A010 266A86 1ACE1F 030000
2021.04.15 10:09:46.721 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:46.723 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 04 A0 10 266A86 1ACE1F 030000
2021.04.15 10:09:46.725 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:46.803 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:46.812 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 04 80 02 1ACE1F 266A86 00
2021.04.15 10:09:47.078 4: CUL_Parse: cul868 A 10 04 A001 1ACE1F 266A86 0104000000000141 -41.5
2021.04.15 10:09:47.083 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 10:09:47.216 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 04 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 10:09:47.219 4: mTp:10 wait: got mStp:02 :
         
2021.04.15 10:09:47.219 4: got unexpected RegisterRead. Not waiting for msgNo:4
2021.04.15 10:09:47.221 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:47.221 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:47.240 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.243 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD73616B d:FF r:FFCE     m:04 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 10:09:47.245 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:47.341 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.361 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.362 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:5, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:47.364 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:47.367 0: HMLAN_Parse: hmlan1 R:RD49438BB stat:0001 t:AD736170 d:FF r:FFCE     m:04 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 10:09:47.369 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:47.371 0: HMLAN_Send:  hmlan1 S:SD4943BE2 stat:  00 t:00000000 d:01 r:D4943BE2 m:05 A001 1ACE1F 266A86 0103
2021.04.15 10:09:47.373 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:47.375 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 04 80 02 1ACE1F 266A86 00
2021.04.15 10:09:47.454 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 0208000C -68
2021.04.15 10:09:47.456 4: mTp:10 wait:PeerList got mStp:02 :
          reSent:1
          Pending:PeerList
          forChn:01
          cmd:As0B05A0011ACE1F266A860103
          mNo:5
2021.04.15 10:09:47.457 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:5
2021.04.15 10:09:47.459 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:47.460 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:47.461 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:47.485 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.488 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD73625E d:FF r:FFCE     m:05 A010 266A86 1ACE1F 020800
2021.04.15 10:09:47.490 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:47.492 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 05 A0 10 266A86 1ACE1F 020800
2021.04.15 10:09:47.493 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:47.609 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:47.616 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 05 80 02 1ACE1F 266A86 00
2021.04.15 10:09:47.669 4: CUL_Parse: cul868 A 0C CE 8670 1D252E 000000 00B63110 -66
2021.04.15 10:09:47.732 4: CUL_Parse: cul868 A 0C 06 A010 266A86 1ACE1F 030000F2 -81
2021.04.15 10:09:47.735 4: mTp:10 wait: got mStp:03 :
         
2021.04.15 10:09:47.735 4: got unexpected RegisterRead. Not waiting for msgNo:6
2021.04.15 10:09:47.737 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:47.738 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:47.757 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.760 0: HMLAN_Parse: hmlan1 R:E1D252E   stat:0000 t:AD736335 d:FF r:FFC4     m:CE 8670 1D252E 000000 00B631
2021.04.15 10:09:47.762 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736357 d:FF r:FFCE     m:06 A010 266A86 1ACE1F 030000
2021.04.15 10:09:47.764 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:47.766 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: CE 86 70 1D252E 000000 00B631
2021.04.15 10:09:47.769 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 06 A0 10 266A86 1ACE1F 030000
2021.04.15 10:09:47.771 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:47.824 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:47.829 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 06 80 02 1ACE1F 266A86 00
2021.04.15 10:09:47.857 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.877 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:47.879 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:48.096 4: CUL_Parse: cul868 A 0B 05 A001 1ACE1F 266A86 010340 -42
2021.04.15 10:09:48.101 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.15 10:09:48.229 4: CUL_Parse: cul868 A 16 05 A010 266A86 1ACE1F 01266A8601266A860200000000F2 -81
2021.04.15 10:09:48.232 4: mTp:10 wait: got mStp:01 :
         
2021.04.15 10:09:48.233 4: got unexpected PeerList. Not waiting for msgNo:5
2021.04.15 10:09:48.234 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:48.235 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:48.254 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:48.257 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736566 d:FF r:FFCE     m:05 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:48.259 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:48.261 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 05 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:48.263 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:48.354 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:48.374 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:48.376 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:48.377 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:48.381 0: HMLAN_Parse: hmlan1 R:RD4943BE2 stat:0001 t:AD73656B d:FF r:FFCE     m:05 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:48.383 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:48.485 0: HMLAN_Send:  hmlan1 S:SD4943FD8 stat:  00 t:00000000 d:01 r:D4943FD8 m:06 A001 1ACE1F 266A86 02040000000001
2021.04.15 10:09:48.487 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:48.489 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 05 80 02 1ACE1F 266A86 00
2021.04.15 10:09:48.623 4: CUL_Parse: cul868 A 10 06 A001 1ACE1F 266A86 0204000000000140 -42
2021.04.15 10:09:48.628 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 06 A0 01 1ACE1F 266A86 02040000000001
2021.04.15 10:09:48.757 4: CUL_Parse: cul868 A 1A 06 A010 266A86 1ACE1F 02300632503364344B3550560057245900EC -84
2021.04.15 10:09:48.760 4: mTp:10 wait:RegisterRead got mStp:02 :
          forList:01
          forPeer:
          nAddr:0
          forChn:02
          reSent:1
          mNo:6
          Pending:RegisterRead
          cmd:As1006A0011ACE1F266A8602040000000001
2021.04.15 10:09:48.762 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:48.763 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:48.782 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:48.785 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736775 d:FF r:FFCE     m:06 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 10:09:48.787 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:48.790 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 06 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 10:09:48.791 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:48.872 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:48.877 0: HMLAN_Parse: hmlan1 R:RD4943FD8 stat:0001 t:AD73677A d:FF r:FFCE     m:06 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 10:09:48.879 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:48.881 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 06 80 02 1ACE1F 266A86 00
2021.04.15 10:09:48.999 4: CUL_Parse: cul868 A 0C 07 A010 266A86 1ACE1F 0208000E -67
2021.04.15 10:09:49.001 4: mTp:10 wait:RegisterRead got mStp:02 :
          forList:01
          forPeer:
          nAddr:0
          forChn:02
          reSent:1
          mNo:6
          Pending:RegisterRead
          cmd:As1006A0011ACE1F266A8602040000000001
2021.04.15 10:09:49.003 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:49.004 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:49.024 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:49.029 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736868 d:FF r:FFCE     m:07 A010 266A86 1ACE1F 020800
2021.04.15 10:09:49.031 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:49.033 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 07 A0 10 266A86 1ACE1F 020800
2021.04.15 10:09:49.035 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:49.121 4: CUL_Parse: cul868 A 0A 07 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:49.127 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 07 80 02 1ACE1F 266A86 00
2021.04.15 10:09:49.248 4: CUL_Parse: cul868 A 0C 08 A010 266A86 1ACE1F 030000F5 -79.5
2021.04.15 10:09:49.250 4: mTp:10 wait:RegisterRead got mStp:03 :
          forList:01
          forPeer:
          nAddr:0
          forChn:02
          reSent:1
          mNo:7
          Pending:RegisterRead
          cmd:As1006A0011ACE1F266A8602040000000001
2021.04.15 10:09:49.256 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:49.257 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:49.349 0: HMLAN_Send:  hmlan1 S:SD4944342 stat:  00 t:00000000 d:01 r:D4944342 m:09 A001 1ACE1F 266A86 0203
2021.04.15 10:09:49.351 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:49.370 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:49.373 4: CUL_Parse: cul868 A 0A 08 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:49.377 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736961 d:FF r:FFCE     m:08 A010 266A86 1ACE1F 030000
2021.04.15 10:09:49.379 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:49.381 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 08 A0 10 266A86 1ACE1F 030000
2021.04.15 10:09:49.382 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:49.384 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 08 80 02 1ACE1F 266A86 00
2021.04.15 10:09:49.642 4: CUL_Parse: cul868 A 0B 09 A001 1ACE1F 266A86 020340 -42
2021.04.15 10:09:49.646 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 09 A0 01 1ACE1F 266A86 0203
2021.04.15 10:09:49.776 4: CUL_Parse: cul868 A 16 09 A010 266A86 1ACE1F 01266A8601266A860200000000EC -84
2021.04.15 10:09:49.778 4: mTp:10 wait:PeerList got mStp:01 :
          mNo:9
          forChn:02
          Pending:PeerList
          cmd:As0B09A0011ACE1F266A860203
          reSent:1
2021.04.15 10:09:49.787 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:49.788 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:49.790 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:49.814 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:49.817 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736B70 d:FF r:FFCE     m:09 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:49.819 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:49.821 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 09 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:49.823 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:49.894 4: CUL_Parse: cul868 A 0A 09 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:49.899 0: HMLAN_Parse: hmlan1 R:RD4944342 stat:0001 t:AD736B75 d:FF r:FFCE     m:09 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:49.901 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.003 0: HMLAN_Send:  hmlan1 S:SD49445C6 stat:  00 t:00000000 d:01 r:D49445C6 m:0A A001 1ACE1F 266A86 03040000000001
2021.04.15 10:09:50.005 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:50.007 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 09 80 02 1ACE1F 266A86 00
2021.04.15 10:09:50.168 4: CUL_Parse: cul868 A 10 0A A001 1ACE1F 266A86 0304000000000140 -42
2021.04.15 10:09:50.173 0: HMUARTLGW hmuart1 recv: 01 05 00 00 32 msg: 0A A0 01 1ACE1F 266A86 03040000000001
2021.04.15 10:09:50.259 0: HMUARTLGW hmuart1 send: 00 08
2021.04.15 10:09:50.263 0: HMUARTLGW hmuart1 recv: 00 040209, state 98
2021.04.15 10:09:50.264 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.15 10:09:50.265 0: HMUARTLGW hmuart1 roundtrip delay: 0.0027
2021.04.15 10:09:50.302 4: CUL_Parse: cul868 A 1A 0A A010 266A86 1ACE1F 02300632503364344B35505600572459000D -67.5
2021.04.15 10:09:50.305 4: mTp:10 wait:RegisterRead got mStp:02 :
          reSent:1
          mNo:10
          Pending:RegisterRead
          cmd:As100AA0011ACE1F266A8603040000000001
          forList:01
          forPeer:
          nAddr:0
          forChn:03
2021.04.15 10:09:50.307 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:50.308 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:50.327 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:50.330 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736D7E d:FF r:FFCE     m:0A A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 10:09:50.332 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.334 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0A A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 10:09:50.336 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.417 4: CUL_Parse: cul868 A 0A 0A 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:50.423 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 19 A2 58 B4B4B4 1CE9F5 00FD
2021.04.15 10:09:50.449 0: HMLAN_Parse: hmlan1 R:RD49445C6 stat:0001 t:AD736D83 d:FF r:FFCE     m:0A A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 10:09:50.451 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.453 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0A 80 02 1ACE1F 266A86 00
2021.04.15 10:09:50.548 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0B A0 10 266A86 1ACE1F 020800
2021.04.15 10:09:50.550 4: mTp:10 wait:RegisterRead got mStp:02 :
          reSent:1
          mNo:10
          Pending:RegisterRead
          cmd:As100AA0011ACE1F266A8603040000000001
          forList:01
          forPeer:
          nAddr:0
          forChn:03
2021.04.15 10:09:50.552 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:50.553 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:50.572 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:50.575 4: CUL_Parse: cul868 A 0B 19 A258 B4B4B4 1CE9F5 00FD67 -22.5
2021.04.15 10:09:50.578 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736E71 d:FF r:FFCE     m:0B A010 266A86 1ACE1F 020800
2021.04.15 10:09:50.580 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.666 4: CUL_Parse: cul868 A 0A 0B 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:50.672 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0B 80 02 1ACE1F 266A86 00
2021.04.15 10:09:50.793 4: CUL_Parse: cul868 A 0C 0C A010 266A86 1ACE1F 0300000B -68.5
2021.04.15 10:09:50.795 4: mTp:10 wait:RegisterRead got mStp:03 :
          reSent:1
          mNo:11
          Pending:RegisterRead
          cmd:As100AA0011ACE1F266A8603040000000001
          forList:01
          forPeer:
          nAddr:0
          forChn:03
2021.04.15 10:09:50.801 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:50.802 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:50.894 0: HMLAN_Send:  hmlan1 S:SD494494A stat:  00 t:00000000 d:01 r:D494494A m:0D A001 1ACE1F 266A86 0303
2021.04.15 10:09:50.896 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:50.915 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:50.918 4: CUL_Parse: cul868 A 0B 19 A258 B4B4B4 1CE9F5 00FD67 -22.5
2021.04.15 10:09:50.921 4: CUL_Parse: cul868 A 0A 0C 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:50.925 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD736F6A d:FF r:FFCE     m:0C A010 266A86 1ACE1F 030000
2021.04.15 10:09:50.927 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.928 0: HMLAN_Parse: hmlan1 R:EB4B4B4   stat:0000 t:AD736FAA d:FF r:FFCD     m:19 A258 B4B4B4 1CE9F5 00FD
2021.04.15 10:09:50.931 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0C A0 10 266A86 1ACE1F 030000
2021.04.15 10:09:50.932 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:50.934 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0C 80 02 1ACE1F 266A86 00
2021.04.15 10:09:51.172 4: CUL_Parse: cul868 A 0B 19 A258 B4B4B4 1CE9F5 00FD67 -22.5
2021.04.15 10:09:51.387 4: CUL_Parse: cul868 A 0B 0D A001 1ACE1F 266A86 030340 -42
2021.04.15 10:09:51.392 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0D A0 01 1ACE1F 266A86 0303
2021.04.15 10:09:51.424 0: HMUARTLGW hmuart1 recv: 01 0404, state 100
2021.04.15 10:09:51.425 0: HMUARTLGW hmuart1 can't send due to unknown problem (no response?)
2021.04.15 10:09:51.521 4: CUL_Parse: cul868 A 16 0D A010 266A86 1ACE1F 01266A8601266A8602000000000B -68.5
2021.04.15 10:09:51.523 4: mTp:10 wait:PeerList got mStp:01 :
          mNo:13
          cmd:As0B0DA0011ACE1F266A860303
          Pending:PeerList
          forChn:03
          reSent:1
2021.04.15 10:09:51.531 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:51.533 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:51.534 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 1 -> 2, +266A86,00,00,00, 0
2021.04.15 10:09:51.558 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:51.561 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD737242 d:FF r:FFCE     m:0D A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:51.563 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:51.565 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0D A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:51.567 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:51.640 4: CUL_Parse: cul868 A 0A 0D 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:51.644 0: HMLAN_Parse: hmlan1 R:RD494494A stat:0001 t:AD737247 d:FF r:FFCE     m:0D A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 10:09:51.646 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:51.748 0: HMLAN_Send:  hmlan1 S:SD4944C97 stat:  00 t:00000000 d:01 r:D4944C97 m:0E A001 1ACE1F 266A86 0204266A860103
2021.04.15 10:09:51.749 0: HMLAN_Send:  hmlan1 I:K
2021.04.15 10:09:51.752 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:51.754 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0D 80 02 1ACE1F 266A86 00
2021.04.15 10:09:51.757 0: HMLAN_Parse: hmlan1 V:03C5 sNo:JEQ0315335 d:1C671E O:1ACE1F t:AD73732C IDcnt:0013 L:10 %
2021.04.15 10:09:51.915 4: CUL_Parse: cul868 A 10 0E A001 1ACE1F 266A86 0204266A86010340 -42
2021.04.15 10:09:51.920 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0E A0 01 1ACE1F 266A86 0204266A860103
2021.04.15 10:09:52.054 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0E A0 10 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.15 10:09:52.056 4: mTp:10 wait:RegisterRead got mStp:03 :
          forChn:02
          nAddr:0
          forPeer:self01
          forList:03
          cmd:As100EA0011ACE1F266A860204266A860103
          Pending:RegisterRead
          mNo:14
          reSent:1
2021.04.15 10:09:52.058 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:52.059 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:52.078 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:52.081 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD737451 d:FF r:FFCE     m:0E A010 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.15 10:09:52.085 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:52.165 4: CUL_Parse: cul868 A 0A 0E 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:52.180 0: HMLAN_Parse: hmlan1 R:RD4944C97 stat:0001 t:AD737456 d:FF r:FFCE     m:0E A010 266A86 1ACE1F 0301000000326400FF00FF004454642000
2021.04.15 10:09:52.183 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:52.186 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0E 80 02 1ACE1F 266A86 00
2021.04.15 10:09:52.299 4: CUL_Parse: cul868 A 16 0F A010 266A86 1ACE1F 031014C80A050500C80A0A04040D -67.5
2021.04.15 10:09:52.303 4: mTp:10 wait:RegisterRead got mStp:03 :
          forChn:02
          nAddr:0
          forPeer:self01
          forList:03
          cmd:As100EA0011ACE1F266A860204266A860103
          Pending:RegisterRead
          mNo:14
          reSent:1
2021.04.15 10:09:52.306 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 10:09:52.307 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 10:09:52.337 5: CUL_HM set DimPBU01 ?
2021.04.15 10:09:52.341 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3B msg: 0F A0 10 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.15 10:09:52.343 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:52.344 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AD73754C d:FF r:FFCE     m:0F A010 266A86 1ACE1F 031014C80A050500C80A0A0404
2021.04.15 10:09:52.346 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 10:09:52.413 4: CUL_Parse: cul868 A 0A 0F 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:52.418 0: HMUARTLGW hmuart1 recv: 01 05 00 00 2F msg: 0F 80 02 1ACE1F 266A86 00
2021.04.15 10:09:52.542 4: CUL_Parse: cul868 A 0F 10 A010 266A86 1ACE1F 032600145263F1 -81.5
2021.04.15 10:09:52.544 4: mTp:10 wait:RegisterRead got mStp:03 :
          forChn:02
          nAddr:0
          forPeer:self01
          forList:03
          cmd:As100EA0011ACE1F266A860204266A860103
          Pending:RegisterRead
          mNo:15
          reSent:1




ich habe das gefühl, dass die letzten 2 bytes (0x0000) beim senden von liste_0 fhem bereits genügen, um die vollständigkeit der liste anzunehmen.
dadurch geht es jetzt mit den msgnummern durcheinander, da der dimmer trotzdem noch explizit 030000 für das ende liste mit #04 sendet.

die folgende anforderung von liste_1 sollte eigentlich mit #05 erfolgen, nicht mit #04.
allerdings sollte der dimmer trotzdem die ausgabe der liste mit #04 senden, nicht mit #05.

eventuell bringt das wiederholte autonome ack mit #04 den dimmer durcheinander?

2021.04.15 10:09:46.307 4: CUL_Parse: cul868 A 10 03 A001 1ACE1F 266A86 0004000000000040 -42
2021.04.15 10:09:46.438 4: CUL_Parse: cul868 A 18 03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000EE -83
2021.04.15 10:09:46.554 4: CUL_Parse: cul868 A 0A 03 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:46.681 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 030000F4 -80
2021.04.15 10:09:46.803 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:47.078 4: CUL_Parse: cul868 A 10 04 A001 1ACE1F 266A86 0104000000000141 -41.5
2021.04.15 10:09:47.364 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0040 -42
2021.04.15 10:09:47.454 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 0208000C -68
2021.04.15 10:09:47.454 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 0208000C -68
2021.04.15 10:09:47.609 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0041 -41.5
2021.04.15 10:09:47.732 4: CUL_Parse: cul868 A 0C 06 A010 266A86 1ACE1F 030000F2 -81



edit:
das passiert immer, aber nur, wenn der dimmer bei fhem restart offline ist, also unreachable, und anschliessend ein automatisches getconfig nach spgszufuhr ausgelöst wird.

perfekt ist, dass überhaupt ein automatisches getconfig kommt und ebenso nach ein paar minuten ein weiteres automatisches getconfig auf den channel1 der beim ersten mal scheiterte. letzendlich ist das device und seine channel sauber.

wenn ich auf dieses "saubere" device ein manuelles getconfig auslöse, kann ich überhaupt keine unregelmässigkeiten entdecken. das kann ja kein zufall sein!
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,

Zitatder unreachable patch ist in deiner neuen cul_hm nicht enthalten. absicht?
Danke für den Hinweis, ist korrigiert. Und nein, nur offensichtlich in den CUL_HMs verlaufen. :o

Zitatich habe das gefühl, dass die letzten 2 bytes (0x0000) beim senden von liste_0 fhem bereits genügen, um die vollständigkeit der liste anzunehmen.
Das Gefühl hatte ich auch schon, respektive bin beim letzten Log dazu schon drüber gestolpert. Ich habe es bei mir aber noch nicht bei einem device beobachten können, allerdings teste ich mit TSCULs.

Es könnte auch sein, dass Sende-busy-waiting von HMLAN in Verbindung mit autoack dazu führt, dass Nachrichten, über andere IOs empfangen, zur "Überholung" aus fhem Sicht führen.

Es wird auf jedenfall im Code die passende oder nächst höhere message number als korrekt empfangenes und zu verabeitendes Packet bewertet. Wenn m gesendet wird kommt die Antwort normalerweise als m. Wenn das device mehr zu erzählen hat, folgt nach Quittung m+1 usw. (oder gibt es auch buggy devices, die das wirklich falsch machen?). Aber wenn schon m+1 statt dem ersten m kommt, dann wird auch das akzeptiert. Wenn das schon die Ende Information enthält und das ist bei 030000 nunmal so, dann wird das Warten auf den Request als abgeschlossen betrachtet und ein folgendes m mit der eigentlichen Nutzinformation verworfen.

Die Empfangsreihenfolge ist in FHEM nur für ein und dasselbe IO über den Empfangspuffer garantiert. Mit mehreren IOs sind "Überholungen" über mehrere IOs möglich, wenn FHEM die Empfangspuffer der IOs nicht rechtzeitig leeren kann. Bei den IOs werden nacheinander die kompletten Empfangspuffer abgeholt und verarbeitet, d.h. es können sich mehrere Nachrichten im Puffer aufstauen, was natürlich in jedem IO dann so passiert.
Edit: Nicht übernommen

Kannst Du mal HMUARTLGW als IO für das device setzen und nochmal testen?
Wenn das nichts ändert, mal nur mit einem einzigen aktiven IO testen?

Gruß, Ansgar.

frank

hi ansgar,

ZitatKannst Du mal HMUARTLGW als IO für das device setzen und nochmal testen?
hatte ich vorhin schon gestartet, aber noch nicht genau durchgeschaut.
mit hmuart als preferd io hatten wohl alle 3 channel configcheck probleme.

ich hatte dem dimmer heute auch schon eine neue fw verpasst, was nichts neues ergab.


2021.04.15 18:46:54.650 4: CUL_Parse: cul868 A 0F 00 A410 266A86 1ACE1F 060100008000F6 -79
2021.04.15 18:46:54.657 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 18:46:54.679 2: HMinfo hminfo get:configCheck :-f,^(DimPBU01|DimPBU01_Dim|DimPBU01_Dim_V_01|DimPBU01_Dim_V_02|DimPBU01)$
2021.04.15 18:46:54.734 0: HMUARTLGW hmuart1 send: 01 06266A86000000
2021.04.15 18:46:54.738 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 18:46:54.739 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:54.764 1: ----- BLACKOUT ----- DimPBU01(266A86) -> no blackout-detect for battery-devices
2021.04.15 18:46:54.783 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:54.786 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:54.789 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:54.894 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CE267 d:FF r:FFD2     m:00 A410 266A86 1ACE1F 060100008000
2021.04.15 18:46:54.896 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:54.899 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3E msg: 00 A4 10 266A86 1ACE1F 060100008000
2021.04.15 18:46:54.901 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:54.903 0: HMUARTLGW hmuart1 recv: 01 04070101000EFFFFFFFFFFFFFFFF, state 90
2021.04.15 18:46:54.904 0: HMUARTLGW hmuart1 GetSet Ack: 07, state 90
2021.04.15 18:46:54.905 0: HMUARTLGW hmuart1 added peer: 266A86, aesChannels: FFFFFFFFFFFFFFFF
2021.04.15 18:46:54.908 0: HMUARTLGW hmuart1 send: 01 06266A86000000
2021.04.15 18:46:54.915 0: HMUARTLGW hmuart1 recv: 01 04070101000EFFFFFFFFFFFFFFFF, state 93
2021.04.15 18:46:54.916 0: HMUARTLGW hmuart1 GetSet Ack: 07, state 93
2021.04.15 18:46:54.916 0: HMUARTLGW hmuart1 added peer: 266A86, aesChannels: FFFFFFFFFFFFFFFF
2021.04.15 18:46:54.973 4: CUL_Parse: cul868 A 0F 01 A410 266A86 1ACE1F 0602000080000F -66.5
2021.04.15 18:46:54.976 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 18:46:54.980 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 18:46:54.981 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:55.006 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.008 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.011 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.084 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CE3AB d:FF r:FFD2     m:01 A410 266A86 1ACE1F 060200008000
2021.04.15 18:46:55.087 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:55.089 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 01 A4 10 266A86 1ACE1F 060200008000
2021.04.15 18:46:55.090 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:55.093 4: CUL_Parse: cul868 A 0A 01 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:55.103 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CE423 d:FF r:FFCD     m:01 8002 1ACE1F 266A86 00
2021.04.15 18:46:55.428 4: CUL_Parse: cul868 A 0F 02 A410 266A86 1ACE1F 060300008000F5 -79.5
2021.04.15 18:46:55.430 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 18:46:55.434 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 18:46:55.434 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:55.459 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.462 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.465 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.536 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CE572 d:FF r:FFD2     m:02 A410 266A86 1ACE1F 060300008000
2021.04.15 18:46:55.538 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:55.541 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3E msg: 02 A4 10 266A86 1ACE1F 060300008000
2021.04.15 18:46:55.542 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:55.547 4: CUL_Parse: cul868 A 0A 02 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:55.558 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CE5EA d:FF r:FFCD     m:02 8002 1ACE1F 266A86 00
2021.04.15 18:46:55.749 3: CUL_HM set DimPBU01 getConfig noArg
2021.04.15 18:46:55.768 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.771 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.773 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.794 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.796 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.799 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.800 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:1, 0 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:55.819 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.822 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.824 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.870 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.872 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.875 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.895 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.898 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.900 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.901 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:2, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:55.920 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.923 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.925 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.945 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.948 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.950 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.952 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:3, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:55.992 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.995 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:55.997 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.017 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.020 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.022 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.024 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:56.043 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.045 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.048 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.067 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.070 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.073 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.074 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:5, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:56.114 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.116 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.119 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.139 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.141 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.144 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.145 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:6, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:56.164 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.167 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.169 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.190 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.192 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.195 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.197 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:56.199 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 03 A0 01 1ACE1F 266A86 00040000000000
2021.04.15 18:46:56.220 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.223 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.226 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.228 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.233 4: CUL_Parse: cul868 A 10 03 A001 1ACE1F 266A86 0004000000000067 -22.5
2021.04.15 18:46:56.242 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CE895 d:FF r:FFCD     m:03 A001 1ACE1F 266A86 00040000000000
2021.04.15 18:46:56.363 4: CUL_Parse: cul868 A 18 03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF1800000011 -65.5
2021.04.15 18:46:56.366 4: mTp:10 wait:RegisterRead got mStp:02 :
          reSent:1
          forList:00
          forPeer:
          forChn:00
          cmd:As1003A0011ACE1F266A8600040000000000
          nAddr:0
          Pending:RegisterRead
          mNo:3
2021.04.15 18:46:56.371 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.372 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:56.374 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.394 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.397 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.399 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.402 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CE918 d:FF r:FFD2     m:03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 18:46:56.404 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.406 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:56.407 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:56.409 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 03 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 18:46:56.411 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.464 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 18:46:56.471 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:56.472 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:56.474 4: CUL_Parse: cul868 A 0A 03 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:56.485 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CE989 d:FF r:FFCD     m:03 8002 1ACE1F 266A86 00
2021.04.15 18:46:56.600 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 03000002 -73
2021.04.15 18:46:56.603 4: mTp:10 wait:RegisterRead got mStp:03 :
          forPeer:
          reSent:1
          forList:01
          cmd:As1004A0011ACE1F266A8601040000000001
          nAddr:0
          Pending:RegisterRead
          mNo:4
          forChn:01
2021.04.15 18:46:56.609 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.610 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:56.611 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.632 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.634 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.637 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.640 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CEA07 d:FF r:FFD1     m:04 A010 266A86 1ACE1F 030000
2021.04.15 18:46:56.642 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.644 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 04 A0 10 266A86 1ACE1F 030000
2021.04.15 18:46:56.646 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.704 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 18:46:56.710 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:56.712 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:56.724 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:56.735 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEA83 d:FF r:FFCD     m:04 8002 1ACE1F 266A86 00
2021.04.15 18:46:56.944 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 18:46:56.978 4: CUL_Parse: cul868 A 10 04 A001 1ACE1F 266A86 0104000000000167 -22.5
2021.04.15 18:46:56.988 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEB7F d:FF r:FFCD     m:04 A001 1ACE1F 266A86 01040000000001
2021.04.15 18:46:57.111 4: CUL_Parse: cul868 A 1A 04 A010 266A86 1ACE1F 02300632503364344B355056005724590111 -65.5
2021.04.15 18:46:57.115 4: mTp:10 wait:PeerList got mStp:02 :
          reSent:1
          forChn:01
          mNo:5
          cmd:As0B05A0011ACE1F266A860103
          Pending:PeerList
2021.04.15 18:46:57.115 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:4
2021.04.15 18:46:57.117 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.118 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:57.148 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.151 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.153 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.156 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:57.158 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:57.160 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 04 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 18:46:57.162 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.164 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CEC04 d:FF r:FFD2     m:04 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 18:46:57.165 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.214 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.15 18:46:57.220 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:57.221 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:57.223 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:57.232 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEC74 d:FF r:FFCD     m:04 8002 1ACE1F 266A86 00
2021.04.15 18:46:57.274 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.15 18:46:57.347 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 02080000 -74
2021.04.15 18:46:57.350 4: mTp:10 wait:PeerList got mStp:02 :
          reSent:1
          forChn:01
          mNo:5
          cmd:As0B05A0011ACE1F266A860103
          Pending:PeerList
2021.04.15 18:46:57.351 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:5
2021.04.15 18:46:57.353 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.354 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:57.356 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.378 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.381 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.383 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.386 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 05 A0 10 266A86 1ACE1F 020800
2021.04.15 18:46:57.388 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.390 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CECF2 d:FF r:FFD2     m:05 A010 266A86 1ACE1F 020800
2021.04.15 18:46:57.391 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.470 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:57.481 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CED6D d:FF r:FFCC     m:05 8002 1ACE1F 266A86 00
2021.04.15 18:46:57.491 4: CUL_Parse: cul868 A 0B 05 A001 1ACE1F 266A86 010367 -22.5
2021.04.15 18:46:57.597 4: CUL_Parse: cul868 A 0C 06 A010 266A86 1ACE1F 03000012 -65
2021.04.15 18:46:57.599 4: mTp:10 wait:RegisterRead got mStp:03 :
          forPeer:
          forList:01
          reSent:1
          mNo:6
          nAddr:0
          cmd:As1006A0011ACE1F266A8602040000000001
          Pending:RegisterRead
          forChn:02
2021.04.15 18:46:57.605 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.606 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:57.608 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.629 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.632 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.634 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.637 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CEDEC d:FF r:FFD1     m:06 A010 266A86 1ACE1F 030000
2021.04.15 18:46:57.638 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.761 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 06 A0 10 266A86 1ACE1F 030000
2021.04.15 18:46:57.762 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.779 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:57.790 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEEA2 d:FF r:FFCC     m:06 8002 1ACE1F 266A86 00
2021.04.15 18:46:57.799 4: CUL_Parse: cul868 A 0B 05 A001 1ACE1F 266A86 010367 -22.5
2021.04.15 18:46:57.801 2: CUL_HM DimPBU01 attack:011ACE1F266A8602040000000001,011ACE1F266A860203:11ACE1F266A860103
2021.04.15 18:46:57.937 1: ------ ATTACK-ALARM ----- DimPBU01(266A86) sabotageAttack_ErrIoAttack_cnt: 1
2021.04.15 18:46:57.944 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.946 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.949 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.952 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:57.953 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:57.955 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 05 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 18:46:57.957 4: mTp:10 wait:PeerList got mStp:01 :
          forChn:02
          reSent:1
          mNo:7
          Pending:PeerList
          cmd:As0B07A0011ACE1F266A860203
2021.04.15 18:46:57.958 4: waiting for: PeerList, got:PeerList # await msgNo:7, rec:5
2021.04.15 18:46:57.959 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.960 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:57.980 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.983 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.985 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.987 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CEF3C d:FF r:FFD2     m:05 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 18:46:57.989 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.991 4: CUL_Parse: cul868 A 16 05 A010 266A86 1ACE1F 01266A8601266A860200000000FD -75.5
2021.04.15 18:46:57.993 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:58.022 3: FHEMWEB WEB CSRF error: csrf_718376276120094 ne csrf_409553017702170 for client WEB_192.168.1.31_52810 / command {FW_makeImage('ring@red')}. For details see the csrfToken FHEMWEB attribute.
2021.04.15 18:46:58.028 3: FHEMWEB WEB CSRF error: csrf_300889678159889 ne csrf_409553017702170 for client WEB_192.168.1.31_52811 / command {FW_makeImage('ring@red')}. For details see the csrfToken FHEMWEB attribute.
2021.04.15 18:46:58.048 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:58.059 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 06 A0 01 1ACE1F 266A86 02040000000001
2021.04.15 18:46:58.062 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEFAF d:FF r:FFCC     m:05 8002 1ACE1F 266A86 00
2021.04.15 18:46:58.192 4: CUL_Parse: cul868 A 10 06 A001 1ACE1F 266A86 0204000000000167 -22.5
2021.04.15 18:46:58.202 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF03E d:FF r:FFCC     m:06 A001 1ACE1F 266A86 02040000000001
2021.04.15 18:46:58.325 4: CUL_Parse: cul868 A 1A 06 A010 266A86 1ACE1F 02300632503364344B355056005724590011 -65.5
2021.04.15 18:46:58.328 4: mTp:10 wait:PeerList got mStp:02 :
          forChn:02
          reSent:1
          mNo:7
          Pending:PeerList
          cmd:As0B07A0011ACE1F266A860203
2021.04.15 18:46:58.328 4: waiting for: PeerList, got:RegisterRead # await msgNo:7, rec:6
2021.04.15 18:46:58.330 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:58.331 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:58.351 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.354 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.356 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.359 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:58.361 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:58.363 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 06 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 18:46:58.364 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:58.366 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF0C3 d:FF r:FFD1     m:06 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 18:46:58.367 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:58.427 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 07 A0 01 1ACE1F 266A86 0203
2021.04.15 18:46:58.433 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:58.435 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:58.437 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:58.447 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF133 d:FF r:FFCD     m:06 8002 1ACE1F 266A86 00
2021.04.15 18:46:58.488 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 07 A0 01 1ACE1F 266A86 0203
2021.04.15 18:46:58.563 4: CUL_Parse: cul868 A 0C 07 A010 266A86 1ACE1F 020800FF -74.5
2021.04.15 18:46:58.565 4: mTp:10 wait:PeerList got mStp:02 :
          forChn:02
          reSent:1
          mNo:7
          Pending:PeerList
          cmd:As0B07A0011ACE1F266A860203
2021.04.15 18:46:58.566 4: waiting for: PeerList, got:RegisterRead # await msgNo:7, rec:7
2021.04.15 18:46:58.568 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:58.569 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:58.570 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:1, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:58.591 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.594 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.596 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.600 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 07 A0 10 266A86 1ACE1F 020800
2021.04.15 18:46:58.601 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:58.603 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF1B1 d:FF r:FFD2     m:07 A010 266A86 1ACE1F 020800
2021.04.15 18:46:58.604 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:58.685 4: CUL_Parse: cul868 A 0A 07 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:58.695 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF22B d:FF r:FFCD     m:07 8002 1ACE1F 266A86 00
2021.04.15 18:46:58.705 4: CUL_Parse: cul868 A 0B 07 A001 1ACE1F 266A86 020367 -22.5
2021.04.15 18:46:58.810 4: CUL_Parse: cul868 A 0C 08 A010 266A86 1ACE1F 03000010 -66
2021.04.15 18:46:58.813 4: mTp:10 wait:RegisterRead got mStp:03 :
          forChn:03
          Pending:RegisterRead
          nAddr:0
          cmd:As1008A0011ACE1F266A8603040000000001
          mNo:8
          forList:01
          reSent:1
          forPeer:
2021.04.15 18:46:58.818 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:1, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:58.819 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:58.821 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:0, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:58.841 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.844 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.847 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:58.849 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF2AA d:FF r:FFD2     m:08 A010 266A86 1ACE1F 030000
2021.04.15 18:46:58.851 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:58.983 4: CUL_Parse: cul868 A 0B 07 A001 1ACE1F 266A86 020367 -22.5
2021.04.15 18:46:58.984 2: CUL_HM DimPBU01 attack:011ACE1F266A8603040000000001,011ACE1F266A860303:11ACE1F266A860203
2021.04.15 18:46:58.993 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF355 d:FF r:FFCC     m:07 A001 1ACE1F 266A86 0203
2021.04.15 18:46:58.994 2: CUL_HM DimPBU01 attack:011ACE1F266A8603040000000001,011ACE1F266A860303:11ACE1F266A860203
2021.04.15 18:46:59.117 4: CUL_Parse: cul868 A 16 07 A010 266A86 1ACE1F 01266A8601266A860200000000FA -77
2021.04.15 18:46:59.120 4: mTp:10 wait:PeerList got mStp:01 :
          forChn:03
          reSent:1
          Pending:PeerList
          cmd:As0B09A0011ACE1F266A860303
          mNo:9
2021.04.15 18:46:59.120 4: waiting for: PeerList, got:PeerList # await msgNo:9, rec:7
2021.04.15 18:46:59.121 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:59.124 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:59.125 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:59.128 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 07 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 18:46:59.130 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:59.132 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF3DB d:FF r:FFD2     m:07 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 18:46:59.134 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:59.219 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 08 A0 01 1ACE1F 266A86 03040000000001
2021.04.15 18:46:59.226 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:59.227 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:59.231 4: CUL_Parse: cul868 A 0A 07 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:59.242 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF44E d:FF r:FFCC     m:07 8002 1ACE1F 266A86 00
2021.04.15 18:46:59.280 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 08 A0 01 1ACE1F 266A86 03040000000001
2021.04.15 18:46:59.375 4: CUL_Parse: cul868 A 10 08 A001 1ACE1F 266A86 0304000000000167 -22.5
2021.04.15 18:46:59.386 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF4DE d:FF r:FFCC     m:08 A001 1ACE1F 266A86 03040000000001
2021.04.15 18:46:59.509 4: CUL_Parse: cul868 A 1A 08 A010 266A86 1ACE1F 02300632503364344B355056005724590011 -65.5
2021.04.15 18:46:59.511 4: mTp:10 wait:PeerList got mStp:02 :
          forChn:03
          reSent:1
          Pending:PeerList
          cmd:As0B09A0011ACE1F266A860303
          mNo:9
2021.04.15 18:46:59.512 4: waiting for: PeerList, got:RegisterRead # await msgNo:9, rec:8
2021.04.15 18:46:59.513 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:59.516 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:59.517 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:59.519 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 08 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 18:46:59.521 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:59.524 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF562 d:FF r:FFD1     m:08 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 18:46:59.525 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:59.611 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 09 A0 01 1ACE1F 266A86 0303
2021.04.15 18:46:59.617 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:59.618 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:59.620 4: CUL_Parse: cul868 A 0A 08 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:59.630 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF5D2 d:FF r:FFCC     m:08 8002 1ACE1F 266A86 00
2021.04.15 18:46:59.671 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 09 A0 01 1ACE1F 266A86 0303
2021.04.15 18:46:59.745 4: CUL_Parse: cul868 A 0C 09 A010 266A86 1ACE1F 020800FA -77
2021.04.15 18:46:59.748 4: mTp:10 wait:PeerList got mStp:02 :
          forChn:03
          reSent:1
          Pending:PeerList
          cmd:As0B09A0011ACE1F266A860303
          mNo:9
2021.04.15 18:46:59.748 4: waiting for: PeerList, got:RegisterRead # await msgNo:9, rec:9
2021.04.15 18:46:59.750 5: CUL_HM DimPBU01 protEvent:CMDs_done, 1 -> 0, +266A86,00,00,00, 0
2021.04.15 18:46:59.751 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:59.774 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:59.777 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:59.779 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:59.783 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 09 A0 10 266A86 1ACE1F 020800
2021.04.15 18:46:59.784 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:59.786 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF650 d:FF r:FFD1     m:09 A010 266A86 1ACE1F 020800
2021.04.15 18:46:59.788 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:59.868 4: CUL_Parse: cul868 A 0A 09 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:59.878 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF6CA d:FF r:FFCC     m:09 8002 1ACE1F 266A86 00
2021.04.15 18:46:59.888 4: CUL_Parse: cul868 A 0B 09 A001 1ACE1F 266A86 030367 -22.5
2021.04.15 18:46:59.994 4: CUL_Parse: cul868 A 0C 0A A010 266A86 1ACE1F 03000011 -65.5
2021.04.15 18:46:59.997 4: mTp:10 wait: got mStp:03 :
         
2021.04.15 18:46:59.997 4: got unexpected RegisterRead. Not waiting for msgNo:10
2021.04.15 18:46:59.999 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 18:46:59.999 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:47:00.022 5: CUL_HM set DimPBU01 ?
2021.04.15 18:47:00.025 5: CUL_HM set DimPBU01 ?
2021.04.15 18:47:00.028 5: CUL_HM set DimPBU01 ?
2021.04.15 18:47:00.032 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF749 d:FF r:FFD1     m:0A A010 266A86 1ACE1F 030000
2021.04.15 18:47:00.033 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:47:00.158 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 0A A0 10 266A86 1ACE1F 030000
2021.04.15 18:47:00.159 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:47:00.177 4: CUL_Parse: cul868 A 0A 0A 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:47:00.187 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF7FF d:FF r:FFCD     m:0A 8002 1ACE1F 266A86 00
2021.04.15 18:47:00.197 4: CUL_Parse: cul868 A 0B 09 A001 1ACE1F 266A86 030367 -22.5
2021.04.15 18:47:00.331 4: CUL_Parse: cul868 A 16 09 A010 266A86 1ACE1F 01266A8601266A860200000000ED -83.5
2021.04.15 18:47:00.333 4: mTp:10 wait: got mStp:01 :
         
2021.04.15 18:47:00.334 4: got unexpected PeerList. Not waiting for msgNo:9
2021.04.15 18:47:00.335 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 18:47:00.336 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:47:00.359 5: CUL_HM set DimPBU01 ?
2021.04.15 18:47:00.362 5: CUL_HM set DimPBU01 ?
2021.04.15 18:47:00.364 5: CUL_HM set DimPBU01 ?
2021.04.15 18:47:00.367 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:47:00.369 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:47:00.370 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 09 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 18:47:00.371 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:47:00.373 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CF899 d:FF r:FFD1     m:09 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 18:47:00.375 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:47:00.445 4: CUL_Parse: cul868 A 0A 09 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:47:00.456 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CF90C d:FF r:FFCD     m:09 8002 1ACE1F 266A86 00



edit:
kurzer check mit cul: NULL configcheck fehler.   ;)     edit: :(

2021.04.15 23:25:22.901 4: CUL_Parse: cul868 A 0F 00 A410 266A86 1ACE1F 06010000800012 -65
2021.04.15 23:25:22.904 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 23:25:22.917 2: HMinfo hminfo get:configCheck :-f,^(DimPBU01|DimPBU01_Dim|DimPBU01_Dim_V_01|DimPBU01_Dim_V_02|DimPBU01)$
2021.04.15 23:25:23.004 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 23:25:23.005 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:23.024 1: ----- BLACKOUT ----- DimPBU01(266A86) -> no blackout-detect for battery-devices
2021.04.15 23:25:23.039 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:23.133 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BDDB1 d:FF r:FFD1     m:00 A410 266A86 1ACE1F 060100008000
2021.04.15 23:25:23.134 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:23.136 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BDE31 d:FF r:FFD9     m:00 8002 1ACE1F 266A86 00
2021.04.15 23:25:23.140 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 00 A4 10 266A86 1ACE1F 060100008000
2021.04.15 23:25:23.141 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:23.143 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 00 80 02 1ACE1F 266A86 00
2021.04.15 23:25:23.355 4: CUL_Parse: cul868 A 0F 01 A410 266A86 1ACE1F 06020000800006 -71
2021.04.15 23:25:23.358 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 23:25:23.458 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 23:25:23.459 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:23.482 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:23.546 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BDF76 d:FF r:FFD1     m:01 A410 266A86 1ACE1F 060200008000
2021.04.15 23:25:23.548 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:23.549 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BDFF8 d:FF r:FFD9     m:01 8002 1ACE1F 266A86 00
2021.04.15 23:25:23.553 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 01 A4 10 266A86 1ACE1F 060200008000
2021.04.15 23:25:23.554 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:23.556 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 01 80 02 1ACE1F 266A86 00
2021.04.15 23:25:23.962 3: CUL_HM set DimPBU01_Dim_V_02 statusRequest noArg
2021.04.15 23:25:23.980 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:24.000 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:24.002 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:1, 0 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:24.023 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:24.025 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:0, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:24.035 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 02 A0 01 1ACE1F 266A86 030E
2021.04.15 23:25:24.043 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BE21C d:FF r:FFD9     m:02 A001 1ACE1F 266A86 030E
2021.04.15 23:25:24.161 4: CUL_Parse: cul868 A 0F 02 A410 266A86 1ACE1F 06030000460012 -65
2021.04.15 23:25:24.163 4: mTp:10 wait: got mStp:06 :
          mNo:2
          cmd:As0B02A0011ACE1F266A86030E
          reSent:1
2021.04.15 23:25:24.263 5: CUL_HM DimPBU01 protEvent:CMDs_done, 1 -> 0, +266A86,00,00,00, 0
2021.04.15 23:25:24.264 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:24.287 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:24.351 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BE29C d:FF r:FFD1     m:02 A410 266A86 1ACE1F 060300004600
2021.04.15 23:25:24.353 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:24.354 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BE31D d:FF r:FFDA     m:02 8002 1ACE1F 266A86 00
2021.04.15 23:25:24.358 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3C msg: 02 A4 10 266A86 1ACE1F 060300004600
2021.04.15 23:25:24.360 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:24.362 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 02 80 02 1ACE1F 266A86 00
2021.04.15 23:25:24.621 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 54 A2 58 B1B1B1 1BFC52 00FD
2021.04.15 23:25:24.649 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BE46C d:FF r:FFD1     m:03 A410 266A86 1ACE1F 060300008000
2021.04.15 23:25:24.652 4: mTp:10 wait: got mStp:06 :
         
2021.04.15 23:25:24.727 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.15 23:25:24.728 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:24.751 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:24.815 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 03 A4 10 266A86 1ACE1F 060300008000
2021.04.15 23:25:24.817 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:24.819 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 03 80 02 1ACE1F 266A86 00
2021.04.15 23:25:24.822 4: CUL_Parse: cul868 A 0F 03 A410 266A86 1ACE1F 06030000800011 -65.5
2021.04.15 23:25:24.824 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:24.825 4: CUL_Parse: cul868 A 0B 54 A258 B1B1B1 1BFC52 00FD67 -22.5
2021.04.15 23:25:24.829 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BE4ED d:FF r:FFD9     m:03 8002 1ACE1F 266A86 00
2021.04.15 23:25:25.113 4: CUL_Parse: cul868 A 0B 54 A258 B1B1B1 1BFC52 00FD67 -22.5
2021.04.15 23:25:25.123 0: HMLAN_Parse: hmlan1 R:EB1B1B1   stat:0000 t:B04BE654 d:FF r:FFCD     m:54 A258 B1B1B1 1BFC52 00FD
2021.04.15 23:25:25.427 4: CUL_Parse: cul868 A 0B 54 A258 B1B1B1 1BFC52 00FD67 -22.5
2021.04.15 23:25:25.438 0: HMLAN_Parse: hmlan1 R:EB1B1B1   stat:0000 t:B04BE78F d:FF r:FFCD     m:54 A258 B1B1B1 1BFC52 00FD
2021.04.15 23:25:25.680 0: HMUARTLGW hmuart1 recv: 01 0404, state 100
2021.04.15 23:25:25.681 0: HMUARTLGW hmuart1 can't send due to unknown problem (no response?)
2021.04.15 23:25:27.338 0: HMUARTLGW hmuart1 send: 00 08
2021.04.15 23:25:27.342 0: HMUARTLGW hmuart1 recv: 00 04020A, state 98
2021.04.15 23:25:27.344 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.15 23:25:27.344 0: HMUARTLGW hmuart1 roundtrip delay: 0.0032
2021.04.15 23:25:28.028 3: CUL_HM set DimPBU01 getConfig noArg
2021.04.15 23:25:28.047 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.066 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.068 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:1, 0 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.086 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.127 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.146 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.148 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:2, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.166 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.185 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.187 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:3, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.226 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.245 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.247 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.266 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.285 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.287 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:5, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.325 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.345 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.347 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:6, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.365 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.384 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.386 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 23:25:28.407 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.409 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:28.424 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 04 A0 01 1ACE1F 266A86 00040000000000
2021.04.15 23:25:28.431 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF340 d:FF r:FFD9     m:04 A001 1ACE1F 266A86 00040000000000
2021.04.15 23:25:28.552 4: CUL_Parse: cul868 A 18 04 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF1800000013 -64.5
2021.04.15 23:25:28.555 4: mTp:10 wait:RegisterRead got mStp:02 :
          forChn:00
          reSent:1
          cmd:As1004A0011ACE1F266A8600040000000000
          nAddr:0
          forList:00
          mNo:4
          forPeer:
          Pending:RegisterRead
2021.04.15 23:25:28.655 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:28.656 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:28.659 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:28.678 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.681 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BF3C3 d:FF r:FFD1     m:04 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 23:25:28.682 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:28.685 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 04 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 23:25:28.686 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:28.688 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 04 80 02 1ACE1F 266A86 00
2021.04.15 23:25:28.692 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF445 d:FF r:FFD9     m:04 8002 1ACE1F 266A86 00
2021.04.15 23:25:28.715 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 05 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 23:25:28.722 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF463 d:FF r:FFD9     m:05 A001 1ACE1F 266A86 01040000000001
2021.04.15 23:25:28.807 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 03000005 -71.5
2021.04.15 23:25:28.810 4: mTp:10 wait:RegisterRead got mStp:03 :
          mNo:5
          nAddr:0
          cmd:As1005A0011ACE1F266A8601040000000001
          forList:01
          reSent:1
          forChn:01
          forPeer:
          Pending:RegisterRead
2021.04.15 23:25:28.910 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:28.911 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:28.914 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:28.933 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:28.935 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BF4C3 d:FF r:FFD2     m:05 A010 266A86 1ACE1F 030000
2021.04.15 23:25:28.937 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:28.939 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 05 A0 10 266A86 1ACE1F 030000
2021.04.15 23:25:28.941 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:28.942 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 05 80 02 1ACE1F 266A86 00
2021.04.15 23:25:28.947 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF544 d:FF r:FFDA     m:05 8002 1ACE1F 266A86 00
2021.04.15 23:25:28.964 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 06 A0 01 1ACE1F 266A86 0103
2021.04.15 23:25:28.972 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF55E d:FF r:FFD9     m:06 A001 1ACE1F 266A86 0103
2021.04.15 23:25:29.096 4: CUL_Parse: cul868 A 16 06 A010 266A86 1ACE1F 01266A8601266A86020000000005 -71.5
2021.04.15 23:25:29.099 4: mTp:10 wait:PeerList got mStp:01 :
          Pending:PeerList
          reSent:1
          forChn:01
          cmd:As0B06A0011ACE1F266A860103
          mNo:6
2021.04.15 23:25:29.199 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:29.200 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:29.202 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:29.222 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:29.224 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BF5E3 d:FF r:FFD2     m:06 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 23:25:29.226 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:29.229 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 06 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 23:25:29.230 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:29.232 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 06 80 02 1ACE1F 266A86 00
2021.04.15 23:25:29.236 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF665 d:FF r:FFD9     m:06 8002 1ACE1F 266A86 00
2021.04.15 23:25:29.259 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 07 A0 01 1ACE1F 266A86 02040000000001
2021.04.15 23:25:29.265 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF683 d:FF r:FFD9     m:07 A001 1ACE1F 266A86 02040000000001
2021.04.15 23:25:29.688 4: CUL_Parse: cul868 A 1A 07 A010 266A86 1ACE1F 02300632503364344B355056005724590015 -63.5
2021.04.15 23:25:29.690 4: mTp:10 wait:RegisterRead got mStp:02 :
          mNo:7
          nAddr:0
          forList:01
          cmd:As1007A0011ACE1F266A8602040000000001
          reSent:1
          forChn:02
          Pending:RegisterRead
          forPeer:
2021.04.15 23:25:29.790 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 23:25:29.791 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 23:25:29.810 5: CUL_HM set DimPBU01 ?
2021.04.15 23:25:29.813 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B04BF832 d:FF r:FFD2     m:07 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 23:25:29.815 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:29.817 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 07 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.15 23:25:29.818 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 23:25:29.821 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 07 80 02 1ACE1F 266A86 00
2021.04.15 23:25:29.827 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B04BF8B5 d:FF r:FFD9     m:07 8002 1ACE1F 266A86 00
2021.04.15 23:25:29.943 4: CUL_Parse: cul868 A 0C 08 A010 266A86 1ACE1F 02080014 -64
2021.04.15 23:25:29.945 4: mTp:10 wait:RegisterRead got mStp:02 :
          mNo:7
          nAddr:0
          forList:01
          cmd:As1007A0011ACE1F266A8602040000000001
          reSent:1
          forChn:02
          Pending:RegisterRead
          forPeer:
2021.04.15 23:25:30.045 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0



edit2:
mit cul als preferedIO geht es an der selben stelle schief, aber fhem merkt es nicht!!!
2021.04.15 23:25:28.424 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 04 A0 01 1ACE1F 266A86 00040000000000
2021.04.15 23:25:28.685 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 04 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 23:25:28.688 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 04 80 02 1ACE1F 266A86 00
2021.04.15 23:25:28.715 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 05 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 23:25:28.939 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 05 A0 10 266A86 1ACE1F 030000
2021.04.15 23:25:28.942 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 05 80 02 1ACE1F 266A86 00
2021.04.15 23:25:28.964 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 06 A0 01 1ACE1F 266A86 0103
2021.04.15 23:25:29.229 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 06 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.15 23:25:29.232 0: HMUARTLGW hmuart1 recv: 01 05 00 00 0E msg: 06 80 02 1ACE1F 266A86 00


die abschliessende msg vom dimmer für liste_0 von chn0:
2021.04.15 23:25:28.939 0: HMUARTLGW hmuart1 recv: 01 05 00 00 3D msg: 05 A0 10 266A86 1ACE1F 030000
wird fälschlicherweise als antwort für die frage nach liste_1/chn1 gewertet.
die frage nach liste_1/chn1 beantwortet der dimmer gar nicht (durch falsches timing nicht gehört, da beschäftigt?).
hier die registerlisten aus channel1 nach dem getconfig:

     2021-04-15 23:25:28   RegL_01.         00:00
     2021-04-15 23:25:35   RegL_03.self01   00:00 01:00 02:00 03:00 04:32 05:64 06:00 07:FF 08:00 09:FF 0A:01 0B:44 0C:54 0D:64 0E:20 0F:00 10:14 11:C8 12:0A 13:05 14:05 15:00 16:C8 17:0A 18:0A 19:04 1A:04 26:00 27:14 28:52 29:63 81:00 82:00 83:00 84:32 85:64 86:00 87:0A 88:00 89:FF 8A:A5 8B:44 8C:54 8D:64 8E:20 8F:00 90:14 91:C8 92:0A 93:05 94:05 95:00 96:C8 97:0A 98:0A 99:04 9A:04 A6:20 A7:14 A8:52 A9:63
     2021-04-15 23:25:33   RegL_03.self02   00:00 01:00 02:00 03:00 04:32 05:64 06:00 07:FF 08:00 09:FF 0A:01 0B:12 0C:22 0D:23 0E:20 0F:00 10:14 11:C8 12:0A 13:05 14:05 15:00 16:C8 17:0A 18:0A 19:04 1A:04 26:00 27:14 28:52 29:63 81:00 82:00 83:00 84:32 85:64 86:00 87:FF 88:00 89:FF 8A:24 8B:12 8C:22 8D:23 8E:20 8F:00 90:14 91:C8 92:0A 93:05 94:05 95:00 96:C8 97:0A 98:0A 99:04 9A:04 A6:20 A7:14 A8:52 A9:63



edit3:
auch beim hmuart als io gibt es schwierigkeiten durch die "unerwartete" dimmer msg:
2021.04.15 18:46:56.600 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 03000002 -73

zusätzlich werden sogar einige über den hmuart gesendete messages als attack eingestuft!!!


gruss 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

ein vergleich eines automatischen und eines manuellen getconfig jeweils mit hmuart als sender

das problem beginnt schon nach dem ersten send, wenn die erste antwort empfangen wird.
der cul ist immer das erst io, das empfängt, worauf fhem reagiert.
seltsamer weise kann ich aber den cul nicht im internal LASTinputDev sehen, da steht immer hmlan.
edit: wenn ich während des getconfig mit einem browser reload "reingretsche", ist der cul auch zu sehen.

warum unterscheidet sich bereits die erste logausgabe von "mTp:10 wait:RegisterRead got mStp:02".
nicht inhaltlich, aber in der reihenfolge?

beim automatischen getconfig wird daraufhin auch bereits der nächste cmd aufgerufen, da hier, im gegensatz zum manuellen getconfig, bereits folgender eintrag zu sehen ist:
2021.04.15 18:46:56.374 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0

bis dahin ist eigentlich alles identisch.
es müssen in fhem also 2 unterschiedliche ablaüfe existieren, für manuell und automatisch.
=> manueller ablauf wartet auf "030000", automatischer ablauf nicht.


1. automatisches getconfig nach pon => problem
####################### pon => getconfig, hmuart


2021.04.15 18:46:56.197 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.15 18:46:56.199 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 03 A0 01 1ACE1F 266A86 00040000000000
2021.04.15 18:46:56.220 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.223 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.226 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.228 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.233 4: CUL_Parse: cul868 A 10 03 A001 1ACE1F 266A86 0004000000000067 -22.5
2021.04.15 18:46:56.242 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CE895 d:FF r:FFCD     m:03 A001 1ACE1F 266A86 00040000000000
2021.04.15 18:46:56.363 4: CUL_Parse: cul868 A 18 03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF1800000011 -65.5
2021.04.15 18:46:56.366 4: mTp:10 wait:RegisterRead got mStp:02 :
          reSent:1
          forList:00
          forPeer:
          forChn:00
          cmd:As1003A0011ACE1F266A8600040000000000
          nAddr:0
          Pending:RegisterRead
          mNo:3
2021.04.15 18:46:56.371 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.372 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:56.374 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.394 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.397 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.399 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.402 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CE918 d:FF r:FFD2     m:03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 18:46:56.404 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.406 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:56.407 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:56.409 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 03 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.15 18:46:56.411 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.464 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 18:46:56.471 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:56.472 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:56.474 4: CUL_Parse: cul868 A 0A 03 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:56.485 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CE989 d:FF r:FFCD     m:03 8002 1ACE1F 266A86 00
2021.04.15 18:46:56.600 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 03000002 -73
2021.04.15 18:46:56.603 4: mTp:10 wait:RegisterRead got mStp:03 :
          forPeer:
          reSent:1
          forList:01
          cmd:As1004A0011ACE1F266A8601040000000001
          nAddr:0
          Pending:RegisterRead
          mNo:4
          forChn:01
2021.04.15 18:46:56.609 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.610 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:56.611 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:56.632 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.634 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.637 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:56.640 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CEA07 d:FF r:FFD1     m:04 A010 266A86 1ACE1F 030000
2021.04.15 18:46:56.642 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.644 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 04 A0 10 266A86 1ACE1F 030000
2021.04.15 18:46:56.646 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:56.704 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 18:46:56.710 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:56.712 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:56.724 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:56.735 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEA83 d:FF r:FFCD     m:04 8002 1ACE1F 266A86 00
2021.04.15 18:46:56.944 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.15 18:46:56.978 4: CUL_Parse: cul868 A 10 04 A001 1ACE1F 266A86 0104000000000167 -22.5
2021.04.15 18:46:56.988 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEB7F d:FF r:FFCD     m:04 A001 1ACE1F 266A86 01040000000001
2021.04.15 18:46:57.111 4: CUL_Parse: cul868 A 1A 04 A010 266A86 1ACE1F 02300632503364344B355056005724590111 -65.5
2021.04.15 18:46:57.115 4: mTp:10 wait:PeerList got mStp:02 :
          reSent:1
          forChn:01
          mNo:5
          cmd:As0B05A0011ACE1F266A860103
          Pending:PeerList
2021.04.15 18:46:57.115 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:4
2021.04.15 18:46:57.117 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.15 18:46:57.118 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 18:46:57.148 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.151 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.153 5: CUL_HM set DimPBU01 ?
2021.04.15 18:46:57.156 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 18:46:57.158 0: HMUARTLGW hmuart1 Ack: 02
2021.04.15 18:46:57.160 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3D msg: 04 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 18:46:57.162 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.164 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AF4CEC04 d:FF r:FFD2     m:04 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.15 18:46:57.165 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 18:46:57.214 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.15 18:46:57.220 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.15 18:46:57.221 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.15 18:46:57.223 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 18:46:57.232 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AF4CEC74 d:FF r:FFCD     m:04 8002 1ACE1F 266A86 00
2021.04.15 18:46:57.274 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.15 18:46:57.347 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 02080000 -74
2021.04.15 18:46:57.350 4: mTp:10 wait:PeerList got mStp:02 :
          reSent:1
          forChn:01
          mNo:5
          cmd:As0B05A0011ACE1F266A860103
          Pending:PeerList
2021.04.15 18:46:57.351 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:5



2. manuelles getconfig nach manuellem set => keine probleme
########################### set => getconfig, hmuart


2021.04.16 11:27:56.453 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.16 11:27:56.455 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 0B A0 01 1ACE1F 266A86 00040000000000
2021.04.16 11:27:56.478 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.481 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.484 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.486 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.16 11:27:56.489 4: CUL_Parse: cul868 A 10 0B A001 1ACE1F 266A86 0004000000000067 -22.5
2021.04.16 11:27:56.514 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B2E17A07 d:FF r:FFCC     m:0B A001 1ACE1F 266A86 00040000000000
2021.04.16 11:27:56.619 4: CUL_Parse: cul868 A 18 0B A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF180064641F -58.5
2021.04.16 11:27:56.622 4: mTp:10 wait:RegisterRead got mStp:02 :
          forChn:00
          reSent:1
          mNo:11
          forList:00
          nAddr:0
          cmd:As100BA0011ACE1F266A8600040000000000
          forPeer:
          Pending:RegisterRead
2021.04.16 11:27:56.624 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 11:27:56.625 5: CUL_HM DimPBU01 sent ACK:2
2021.04.16 11:27:56.644 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.647 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.649 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.652 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.16 11:27:56.653 0: HMUARTLGW hmuart1 Ack: 02
2021.04.16 11:27:56.654 0: HMUARTLGW hmuart1 send: 00 08
2021.04.16 11:27:56.657 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 0B A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18006464
2021.04.16 11:27:56.658 4: CUL_HM DimPBU01 dupe: dont process
2021.04.16 11:27:56.660 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B2E17A8A d:FF r:FFD3     m:0B A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18006464
2021.04.16 11:27:56.661 4: CUL_HM DimPBU01 dupe: dont process
2021.04.16 11:27:56.664 0: HMUARTLGW hmuart1 recv: 00 04020E, state 98
2021.04.16 11:27:56.665 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.16 11:27:56.730 4: CUL_Parse: cul868 A 0A 0B 8002 1ACE1F 266A86 0067 -22.5
2021.04.16 11:27:56.741 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B2E17AFB d:FF r:FFCC     m:0B 8002 1ACE1F 266A86 00
2021.04.16 11:27:56.857 4: CUL_Parse: cul868 A 0C 0C A010 266A86 1ACE1F 0300001B -60.5
2021.04.16 11:27:56.860 4: mTp:10 wait:RegisterRead got mStp:03 :
          forChn:00
          reSent:1
          mNo:11
          forList:00
          nAddr:0
          cmd:As100BA0011ACE1F266A8600040000000000
          forPeer:
          Pending:RegisterRead
2021.04.16 11:27:56.865 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 11:27:56.866 5: CUL_HM DimPBU01 sent ACK:2
2021.04.16 11:27:56.868 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 11:27:56.888 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.891 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.893 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:56.896 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 0C A0 10 266A86 1ACE1F 030000
2021.04.16 11:27:56.897 4: CUL_HM DimPBU01 dupe: dont process
2021.04.16 11:27:56.899 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B2E17B7A d:FF r:FFD3     m:0C A010 266A86 1ACE1F 030000
2021.04.16 11:27:56.900 4: CUL_HM DimPBU01 dupe: dont process
2021.04.16 11:27:56.960 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 0D A0 01 1ACE1F 266A86 01040000000001
2021.04.16 11:27:56.966 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.16 11:27:56.967 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.16 11:27:56.981 4: CUL_Parse: cul868 A 0A 0C 8002 1ACE1F 266A86 0067 -22.5
2021.04.16 11:27:56.991 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B2E17BF5 d:FF r:FFCC     m:0C 8002 1ACE1F 266A86 00
2021.04.16 11:27:57.020 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 0D A0 01 1ACE1F 266A86 01040000000001
2021.04.16 11:27:57.125 4: CUL_Parse: cul868 A 10 0D A001 1ACE1F 266A86 0104000000000167 -22.5
2021.04.16 11:27:57.135 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B2E17C84 d:FF r:FFCC     m:0D A001 1ACE1F 266A86 01040000000001
2021.04.16 11:27:57.257 4: CUL_Parse: cul868 A 1A 0D A010 266A86 1ACE1F 02300632503364344B35505600572459011F -58.5
2021.04.16 11:27:57.260 4: mTp:10 wait:RegisterRead got mStp:02 :
          Pending:RegisterRead
          forPeer:
          reSent:1
          forChn:01
          forList:01
          nAddr:0
          cmd:As100DA0011ACE1F266A8601040000000001
          mNo:13
2021.04.16 11:27:57.267 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 11:27:57.267 5: CUL_HM DimPBU01 sent ACK:2
2021.04.16 11:27:57.287 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:57.290 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:57.292 5: CUL_HM set DimPBU01 ?
2021.04.16 11:27:57.294 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.16 11:27:57.295 0: HMUARTLGW hmuart1 Ack: 02
2021.04.16 11:27:57.297 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 0D A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.16 11:27:57.298 4: CUL_HM DimPBU01 dupe: dont process
2021.04.16 11:27:57.300 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:B2E17D09 d:FF r:FFD3     m:0D A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.16 11:27:57.301 4: CUL_HM DimPBU01 dupe: dont process
2021.04.16 11:27:57.369 4: CUL_Parse: cul868 A 0A 0D 8002 1ACE1F 266A86 0067 -22.5
2021.04.16 11:27:57.378 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:B2E17D78 d:FF r:FFCC     m:0D 8002 1ACE1F 266A86 00
2021.04.16 11:27:57.495 4: CUL_Parse: cul868 A 0C 0E A010 266A86 1ACE1F 0208001F -58.5
2021.04.16 11:27:57.497 4: mTp:10 wait:RegisterRead got mStp:02 :
          Pending:RegisterRead
          forPeer:
          reSent:1
          forChn:01
          forList:01
          nAddr:0
          cmd:As100DA0011ACE1F266A8601040000000001
          mNo:13




edit:
ZitatWenn das nichts ändert, mal nur mit einem einzigen aktiven IO testen?
es sind die selben probleme zu sehen, wenn cul und hmlan offline sind.

####################### pon => getconfig, hmuart (cul, hmlan sind offline)


2021.04.16 14:30:43.780 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.16 14:30:43.782 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 03 A0 01 1ACE1F 266A86 00040000000000
2021.04.16 14:30:43.803 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:43.806 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:43.808 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:43.810 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.16 14:30:43.948 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.16 14:30:43.951 0: HMUARTLGW hmuart1 Ack: 02
2021.04.16 14:30:43.956 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 03 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.16 14:30:43.963 4: mTp:10 wait:RegisterRead got mStp:02 :
          forPeer:
          Pending:RegisterRead
          reSent:1
          forChn:00
          nAddr:0
          forList:00
          cmd:As1003A0011ACE1F266A8600040000000000
          mNo:3
2021.04.16 14:30:43.973 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 14:30:43.974 5: CUL_HM DimPBU01 sent ACK:2
2021.04.16 14:30:43.978 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 14:30:44.010 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.014 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.017 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.187 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 04 A0 10 266A86 1ACE1F 030000
2021.04.16 14:30:44.189 4: mTp:10 wait:RegisterRead got mStp:03 :
          mNo:4
          nAddr:0
          cmd:As1004A0011ACE1F266A8601040000000001
          forList:01
          forChn:01
          reSent:1
          forPeer:
          Pending:RegisterRead
2021.04.16 14:30:44.195 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 14:30:44.195 5: CUL_HM DimPBU01 sent ACK:2
2021.04.16 14:30:44.197 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 14:30:44.218 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.220 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.222 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.253 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.16 14:30:44.261 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.16 14:30:44.262 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.16 14:30:44.486 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.16 14:30:44.654 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.16 14:30:44.657 0: HMUARTLGW hmuart1 Ack: 02
2021.04.16 14:30:44.659 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.16 14:30:44.666 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 04 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.16 14:30:44.673 4: mTp:10 wait:PeerList got mStp:02 :
          cmd:As0B05A0011ACE1F266A860103
          mNo:5
          forChn:01
          Pending:PeerList
          reSent:1
2021.04.16 14:30:44.673 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:4
2021.04.16 14:30:44.676 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.16 14:30:44.678 5: CUL_HM DimPBU01 sent ACK:2
2021.04.16 14:30:44.713 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.717 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.720 5: CUL_HM set DimPBU01 ?
2021.04.16 14:30:44.724 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.16 14:30:44.726 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.16 14:30:44.892 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3C msg: 05 A0 10 266A86 1ACE1F 020800
2021.04.16 14:30:44.894 4: mTp:10 wait:PeerList got mStp:02 :
          cmd:As0B05A0011ACE1F266A860103
          mNo:5
          forChn:01
          Pending:PeerList
          reSent:1
2021.04.16 14:30:44.895 4: waiting for: PeerList, got:RegisterRead # await msgNo:5, rec:5
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,

im Anhang nochmal eine Version zum testen.

Bei Multimessage Reads wird auf eine komplette Sequenz vom Sende-mNo bis mNo mit Ende Kennzeichnung gewartet. Erst dann wird der nächste Befehl zum Senden freigegeben.
Damit sollte der Read auch out of order komplett werden, so er Gedanke.
Mal schauen, ob das was ändert beim "IO-Zoo".
Edit: Nicht übernommen.

Gruß, Ansgar.

Edit: Anhang geköscht

frank

moin ansgar,

leider identisches verhalten.

2021.04.17 09:48:48.166 4: CUL_Parse: cul868 A 0F 00 A410 266A86 1ACE1F 0601000080001A -61
2021.04.17 09:48:48.172 4: mTp:10 wait: got mStp:06 mNo:0 :
         
2021.04.17 09:48:48.193 2: HMinfo hminfo get:configCheck :-f,^(DimPBU01|DimPBU01_Dim|DimPBU01_Dim_V_01|DimPBU01_Dim_V_02|DimPBU01)$
2021.04.17 09:48:48.247 0: HMUARTLGW hmuart1 send: 01 06266A86000000
2021.04.17 09:48:48.251 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.17 09:48:48.252 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:48.274 1: ----- BLACKOUT ----- DimPBU01(266A86) -> no blackout-detect for battery-devices
2021.04.17 09:48:48.292 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:48.295 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:48.298 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:48.396 0: HMUARTLGW hmuart1 recv: 01 05 00 00 34 msg: 00 A4 10 266A86 1ACE1F 060100008000
2021.04.17 09:48:48.398 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:48.400 0: HMUARTLGW hmuart1 recv: 01 04070101000EFFFFFFFFFFFFFFFF, state 90
2021.04.17 09:48:48.401 0: HMUARTLGW hmuart1 GetSet Ack: 07, state 90
2021.04.17 09:48:48.402 0: HMUARTLGW hmuart1 added peer: 266A86, aesChannels: FFFFFFFFFFFFFFFF
2021.04.17 09:48:48.405 0: HMUARTLGW hmuart1 send: 01 06266A86000000
2021.04.17 09:48:48.408 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219DD0D d:FF r:FFD3     m:00 A410 266A86 1ACE1F 060100008000
2021.04.17 09:48:48.409 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:48.412 0: HMUARTLGW hmuart1 recv: 01 04070101000EFFFFFFFFFFFFFFFF, state 93
2021.04.17 09:48:48.414 0: HMUARTLGW hmuart1 GetSet Ack: 07, state 93
2021.04.17 09:48:48.414 0: HMUARTLGW hmuart1 added peer: 266A86, aesChannels: FFFFFFFFFFFFFFFF
2021.04.17 09:48:48.490 4: CUL_Parse: cul868 A 0F 01 A410 266A86 1ACE1F 0602000080001C -60
2021.04.17 09:48:48.493 4: mTp:10 wait: got mStp:06 mNo:1 :
         
2021.04.17 09:48:48.496 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.17 09:48:48.497 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:48.520 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:48.523 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:48.525 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:48.623 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 01 A4 10 266A86 1ACE1F 060200008000
2021.04.17 09:48:48.629 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:48.634 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219DE51 d:FF r:FFD3     m:01 A410 266A86 1ACE1F 060200008000
2021.04.17 09:48:48.637 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:48.639 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219DECA d:FF r:FFCE     m:01 8002 1ACE1F 266A86 00
2021.04.17 09:48:48.647 4: CUL_Parse: cul868 A 0A 01 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:49.104 4: CUL_Parse: cul868 A 0F 02 A410 266A86 1ACE1F 06030000800015 -63.5
2021.04.17 09:48:49.107 4: mTp:10 wait: got mStp:06 mNo:2 :
         
2021.04.17 09:48:49.113 5: CUL_HM DimPBU01 protEvent:CMDs_done, 0 -> 0, +266A86,00,00,00, 0
2021.04.17 09:48:49.114 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:49.149 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.152 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.155 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.233 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 02 A4 10 266A86 1ACE1F 060300008000
2021.04.17 09:48:49.235 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:49.238 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E0B7 d:FF r:FFD2     m:02 A410 266A86 1ACE1F 060300008000
2021.04.17 09:48:49.240 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:49.241 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E12F d:FF r:FFCF     m:02 8002 1ACE1F 266A86 00
2021.04.17 09:48:49.245 4: CUL_Parse: cul868 A 0A 02 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:49.250 3: CUL_HM set DimPBU01 getConfig noArg
2021.04.17 09:48:49.272 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.275 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.277 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.299 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.302 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.304 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.307 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:1, 0 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.328 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.331 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.333 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.378 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.381 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.384 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.405 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.409 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.411 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.413 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:2, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.433 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.437 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.439 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.461 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.464 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.467 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.469 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:3, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.515 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.518 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.521 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.542 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.545 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.548 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.550 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:4, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.570 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.573 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.576 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.597 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.601 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.603 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.605 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:5, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.647 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.651 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.653 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.675 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.678 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.680 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.682 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:6, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.703 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.705 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.708 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.730 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.733 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.736 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.738 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 09:48:49.740 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 03 A0 01 1ACE1F 266A86 00040000000000
2021.04.17 09:48:49.763 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.766 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.769 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.771 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:49.773 4: CUL_Parse: cul868 A 10 03 A001 1ACE1F 266A86 0004000000000066 -23
2021.04.17 09:48:49.782 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E353 d:FF r:FFCD     m:03 A001 1ACE1F 266A86 00040000000000
2021.04.17 09:48:49.903 4: CUL_Parse: cul868 A 18 03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF180000001C -60
2021.04.17 09:48:49.906 4: mTp:10 wait:RegisterRead got mStp:02 mNo:3 :
          Pending:RegisterRead
          forPeer:
          reSent:1
          mNo:3
          nAddr:0
          cmd:As1003A0011ACE1F266A8600040000000000
          mNoB:0
          mNoBmax:-1
          forChn:00
          forList:00
2021.04.17 09:48:49.912 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:49.913 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:49.914 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:49.934 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.936 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.938 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:49.941 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 09:48:49.942 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 09:48:49.944 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 03 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.17 09:48:49.945 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:49.947 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E3D6 d:FF r:FFD2     m:03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.17 09:48:49.948 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.006 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.17 09:48:50.013 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 09:48:50.014 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 09:48:50.016 4: CUL_Parse: cul868 A 0A 03 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:50.025 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E447 d:FF r:FFCE     m:03 8002 1ACE1F 266A86 00
2021.04.17 09:48:50.066 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 01040000000001
2021.04.17 09:48:50.142 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 03000017 -62.5
2021.04.17 09:48:50.145 4: mTp:10 wait:RegisterRead got mStp:03 mNo:4 :
          mNoBmax:-1
          cmd:As1004A0011ACE1F266A8601040000000001
          mNoB:0
          forChn:01
          forList:01
          mNo:4
          nAddr:0
          reSent:1
          forPeer:
          Pending:RegisterRead
2021.04.17 09:48:50.150 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.151 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:50.153 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.172 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.174 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.176 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.179 0: HMUARTLGW hmuart1 recv: 01 05 01 00 34 msg: 04 A0 10 266A86 1ACE1F 030000
2021.04.17 09:48:50.181 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.182 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E4C6 d:FF r:FFD2     m:04 A010 266A86 1ACE1F 030000
2021.04.17 09:48:50.184 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.264 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:50.274 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E540 d:FF r:FFCE     m:04 8002 1ACE1F 266A86 00
2021.04.17 09:48:50.289 4: CUL_Parse: cul868 A 10 04 A001 1ACE1F 266A86 0104000000000166 -23
2021.04.17 09:48:50.423 4: CUL_Parse: cul868 A 1A 04 A010 266A86 1ACE1F 02300632503364344B35505600572459011D -59.5
2021.04.17 09:48:50.425 4: mTp:10 wait:PeerList got mStp:02 mNo:4 :
          Pending:PeerList
          reSent:1
          mNo:5
          forChn:01
          mNoBmax:-1
          mNoB:0
          cmd:As0B05A0011ACE1F266A860103
2021.04.17 09:48:50.426 4: PeerList
2021.04.17 09:48:50.427 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.428 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:50.446 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.449 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.451 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.454 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 09:48:50.455 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 09:48:50.457 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 04 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.17 09:48:50.458 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.460 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E5DD d:FF r:FFD2     m:04 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.17 09:48:50.461 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.525 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.17 09:48:50.531 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 09:48:50.532 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 09:48:50.534 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:50.542 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E64C d:FF r:FFCE     m:04 8002 1ACE1F 266A86 00
2021.04.17 09:48:50.585 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 05 A0 01 1ACE1F 266A86 0103
2021.04.17 09:48:50.659 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 02080017 -62.5
2021.04.17 09:48:50.662 4: mTp:10 wait:PeerList got mStp:02 mNo:5 :
          Pending:PeerList
          reSent:1
          mNo:5
          forChn:01
          mNoBmax:-1
          mNoB:0
          cmd:As0B05A0011ACE1F266A860103
2021.04.17 09:48:50.662 4: PeerList
2021.04.17 09:48:50.664 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.665 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:50.667 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.686 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.688 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.690 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.693 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 05 A0 10 266A86 1ACE1F 020800
2021.04.17 09:48:50.695 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.696 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E6CB d:FF r:FFD2     m:05 A010 266A86 1ACE1F 020800
2021.04.17 09:48:50.698 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:50.782 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:50.792 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E746 d:FF r:FFCE     m:05 8002 1ACE1F 266A86 00
2021.04.17 09:48:50.802 4: CUL_Parse: cul868 A 0B 05 A001 1ACE1F 266A86 010366 -23
2021.04.17 09:48:50.909 4: CUL_Parse: cul868 A 0C 06 A010 266A86 1ACE1F 0300001C -60
2021.04.17 09:48:50.911 4: mTp:10 wait:RegisterRead got mStp:03 mNo:6 :
          forChn:02
          forList:01
          mNoB:0
          cmd:As1006A0011ACE1F266A8602040000000001
          mNoBmax:-1
          nAddr:0
          mNo:6
          reSent:1
          Pending:RegisterRead
          forPeer:
2021.04.17 09:48:50.917 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:3, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.918 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:50.919 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:50.938 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.941 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.943 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:50.945 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E7C4 d:FF r:FFD2     m:06 A010 266A86 1ACE1F 030000
2021.04.17 09:48:50.947 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.073 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 06 A0 10 266A86 1ACE1F 030000
2021.04.17 09:48:51.074 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.092 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:51.102 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E87C d:FF r:FFCE     m:06 8002 1ACE1F 266A86 00
2021.04.17 09:48:51.112 4: CUL_Parse: cul868 A 0B 05 A001 1ACE1F 266A86 010366 -23
2021.04.17 09:48:51.114 2: CUL_HM DimPBU01 attack:011ACE1F266A8602040000000001,011ACE1F266A860203:11ACE1F266A860103
2021.04.17 09:48:51.231 1: ------ ATTACK-ALARM ----- DimPBU01(266A86) sabotageAttack_ErrIoAttack_cnt: 1
2021.04.17 09:48:51.236 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.239 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.241 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.246 4: CUL_Parse: cul868 A 16 05 A010 266A86 1ACE1F 01266A8601266A86020000000016 -63
2021.04.17 09:48:51.249 4: mTp:10 wait:PeerList got mStp:01 mNo:5 :
          forChn:02
          mNoB:0
          cmd:As0B07A0011ACE1F266A860203
          mNoBmax:-1
          mNo:7
          reSent:1
          Pending:PeerList
2021.04.17 09:48:51.249 4: waiting for Peerlist: msgNo:7+, rec:5
2021.04.17 09:48:51.250 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:51.251 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:51.270 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.273 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.275 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.277 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 09:48:51.279 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 09:48:51.280 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 05 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 09:48:51.282 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.283 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219E916 d:FF r:FFD2     m:05 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 09:48:51.285 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.288 3: FHEMWEB WEB CSRF error: csrf_180346975978813 ne csrf_158781283845335 for client WEB_192.168.1.31_49734 / command {FW_makeImage('ring@red')}. For details see the csrfToken FHEMWEB attribute.
2021.04.17 09:48:51.290 3: FHEMWEB WEB CSRF error: csrf_180346975978813 ne csrf_158781283845335 for client WEB_192.168.1.31_49731 / command {FW_makeImage('ring@red')}. For details see the csrfToken FHEMWEB attribute.
2021.04.17 09:48:51.349 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 06 A0 01 1ACE1F 266A86 02040000000001
2021.04.17 09:48:51.355 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 09:48:51.356 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 09:48:51.360 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:51.371 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219E989 d:FF r:FFCE     m:05 8002 1ACE1F 266A86 00
2021.04.17 09:48:51.409 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 06 A0 01 1ACE1F 266A86 02040000000001
2021.04.17 09:48:51.504 4: CUL_Parse: cul868 A 10 06 A001 1ACE1F 266A86 0204000000000166 -23
2021.04.17 09:48:51.514 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219EA18 d:FF r:FFCE     m:06 A001 1ACE1F 266A86 02040000000001
2021.04.17 09:48:51.638 4: CUL_Parse: cul868 A 1A 06 A010 266A86 1ACE1F 02300632503364344B35505600572459001D -59.5
2021.04.17 09:48:51.641 4: mTp:10 wait:PeerList got mStp:02 mNo:6 :
          forChn:02
          mNoB:0
          cmd:As0B07A0011ACE1F266A860203
          mNoBmax:-1
          mNo:7
          reSent:1
          Pending:PeerList
2021.04.17 09:48:51.641 4: PeerList
2021.04.17 09:48:51.642 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:51.643 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:51.662 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.664 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.667 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.669 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 09:48:51.670 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 09:48:51.672 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 06 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.17 09:48:51.674 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.675 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219EA9D d:FF r:FFD2     m:06 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.17 09:48:51.677 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.740 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 07 A0 01 1ACE1F 266A86 0203
2021.04.17 09:48:51.746 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 09:48:51.748 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 09:48:51.750 4: CUL_Parse: cul868 A 0A 06 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:51.758 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219EB0C d:FF r:FFCE     m:06 8002 1ACE1F 266A86 00
2021.04.17 09:48:51.800 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 07 A0 01 1ACE1F 266A86 0203
2021.04.17 09:48:51.875 4: CUL_Parse: cul868 A 0C 07 A010 266A86 1ACE1F 0208001D -59.5
2021.04.17 09:48:51.877 4: mTp:10 wait:PeerList got mStp:02 mNo:7 :
          forChn:02
          mNoB:0
          cmd:As0B07A0011ACE1F266A860203
          mNoBmax:-1
          mNo:7
          reSent:1
          Pending:PeerList
2021.04.17 09:48:51.878 4: PeerList
2021.04.17 09:48:51.880 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:2, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:51.880 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:51.882 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:1, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:51.901 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.903 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.905 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:51.908 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 07 A0 10 266A86 1ACE1F 020800
2021.04.17 09:48:51.910 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.911 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219EB8B d:FF r:FFD2     m:07 A010 266A86 1ACE1F 020800
2021.04.17 09:48:51.913 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:51.998 4: CUL_Parse: cul868 A 0A 07 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:52.008 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219EC06 d:FF r:FFCE     m:07 8002 1ACE1F 266A86 00
2021.04.17 09:48:52.018 4: CUL_Parse: cul868 A 0B 07 A001 1ACE1F 266A86 020366 -23
2021.04.17 09:48:52.165 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219EC84 d:FF r:FFD2     m:08 A010 266A86 1ACE1F 030000
2021.04.17 09:48:52.169 4: mTp:10 wait:RegisterRead got mStp:03 mNo:8 :
          reSent:1
          forPeer:
          Pending:RegisterRead
          mNoBmax:-1
          mNoB:0
          cmd:As1008A0011ACE1F266A8603040000000001
          forList:01
          forChn:03
          mNo:8
          nAddr:0
2021.04.17 09:48:52.174 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:1, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:52.175 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:52.177 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:0, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 09:48:52.197 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:52.200 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:52.202 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:52.204 4: CUL_Parse: cul868 A 0C 08 A010 266A86 1ACE1F 03000017 -62.5
2021.04.17 09:48:52.206 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:52.288 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 08 A0 10 266A86 1ACE1F 030000
2021.04.17 09:48:52.290 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:52.306 4: CUL_Parse: cul868 A 0A 08 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:52.318 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219ED3B d:FF r:FFCE     m:08 8002 1ACE1F 266A86 00
2021.04.17 09:48:52.330 4: CUL_Parse: cul868 A 0B 07 A001 1ACE1F 266A86 020366 -23
2021.04.17 09:48:52.331 2: CUL_HM DimPBU01 attack:011ACE1F266A8603040000000001,011ACE1F266A860303:11ACE1F266A860203
2021.04.17 09:48:52.391 1: ------ ATTACK-ALARM ----- DimPBU01(266A86) sabotageAttack_ErrIoAttack_cnt: 2
2021.04.17 09:48:52.396 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:52.399 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:52.401 5: CUL_HM set DimPBU01 ?
2021.04.17 09:48:52.423 3: FHEMWEB WEB CSRF error: csrf_180346975978813 ne csrf_158781283845335 for client WEB_192.168.1.31_49734 / command {FW_makeImage('ring@red')}. For details see the csrfToken FHEMWEB attribute.
2021.04.17 09:48:52.426 3: FHEMWEB WEB CSRF error: csrf_180346975978813 ne csrf_158781283845335 for client WEB_192.168.1.31_49731 / command {FW_makeImage('ring@red')}. For details see the csrfToken FHEMWEB attribute.
2021.04.17 09:48:52.461 4: CUL_Parse: cul868 A 16 07 A010 266A86 1ACE1F 01266A8601266A8602000000001D -59.5
2021.04.17 09:48:52.464 4: mTp:10 wait:PeerList got mStp:01 mNo:7 :
          mNo:9
          cmd:As0B09A0011ACE1F266A860303
          mNoB:0
          mNoBmax:-1
          forChn:03
          Pending:PeerList
          reSent:1
2021.04.17 09:48:52.465 4: waiting for Peerlist: msgNo:9+, rec:7
2021.04.17 09:48:52.466 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:52.468 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 09:48:52.469 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 09:48:52.472 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 07 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 09:48:52.474 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:52.475 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219EDD5 d:FF r:FFD2     m:07 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 09:48:52.477 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:52.564 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 08 A0 01 1ACE1F 266A86 03040000000001
2021.04.17 09:48:52.571 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 09:48:52.572 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 09:48:52.575 4: CUL_Parse: cul868 A 0A 07 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:52.586 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219EE48 d:FF r:FFCE     m:07 8002 1ACE1F 266A86 00
2021.04.17 09:48:52.624 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 08 A0 01 1ACE1F 266A86 03040000000001
2021.04.17 09:48:52.719 4: CUL_Parse: cul868 A 10 08 A001 1ACE1F 266A86 0304000000000166 -23
2021.04.17 09:48:52.729 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219EED7 d:FF r:FFCE     m:08 A001 1ACE1F 266A86 03040000000001
2021.04.17 09:48:52.853 4: CUL_Parse: cul868 A 1A 08 A010 266A86 1ACE1F 02300632503364344B355056005724590016 -63
2021.04.17 09:48:52.856 4: mTp:10 wait:PeerList got mStp:02 mNo:8 :
          mNo:9
          cmd:As0B09A0011ACE1F266A860303
          mNoB:0
          mNoBmax:-1
          forChn:03
          Pending:PeerList
          reSent:1
2021.04.17 09:48:52.856 4: PeerList
2021.04.17 09:48:52.857 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 09:48:52.860 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 09:48:52.861 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 09:48:52.863 0: HMUARTLGW hmuart1 send: 00 08
2021.04.17 09:48:52.866 0: HMUARTLGW hmuart1 recv: 01 05 01 00 35 msg: 08 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.17 09:48:52.867 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:52.870 0: HMUARTLGW hmuart1 recv: 00 04020C, state 98
2021.04.17 09:48:52.871 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.17 09:48:52.873 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:0219EF5C d:FF r:FFD2     m:08 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.17 09:48:52.874 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 09:48:52.963 4: CUL_Parse: cul868 A 0A 08 8002 1ACE1F 266A86 0066 -23
2021.04.17 09:48:52.973 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:0219EFCB d:FF r:FFCE     m:08 8002 1ACE1F 266A86 00
2021.04.17 09:48:53.090 4: CUL_Parse: cul868 A 0C 09 A010 266A86 1ACE1F 0208001D -59.5
2021.04.17 09:48:53.092 4: mTp:10 wait:PeerList got mStp:02 mNo:9 :
          mNo:9
          cmd:As0B09A0011ACE1F266A860303
          mNoB:0
          mNoBmax:-1
          forChn:03
          Pending:PeerList
          reSent:1
2021.04.17 09:48:53.093 4: PeerList
2021.04.17 09:48:53.094 5: CUL_HM DimPBU01 protEvent:CMDs_done, 1 -> 0, +266A86,00,00,00, 0
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,

das Drama nimmt hier seinen Anfang:
2021.04.15 18:46:56.363 4: CUL_Parse: cul868 A 18 03 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF1800000011 -65.5

gegenüber
2021.04.16 11:27:56.619 4: CUL_Parse: cul868 A 18 0B A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF180064641F -58.5


Im ersten Fall sendet das device am Ende schon Reg 0 Wert 0. Das wird von CUL_HM als Ende der Registerliste gewertet.
Im zweiten Fall sendet es dagegen Reg 100(0x64) Wert 100. Also noch kein Ende.
Danach kommt in beiden Fällen
2021.04.15 18:46:56.600 4: CUL_Parse: cul868 A 0C 04 A010 266A86 1ACE1F 03000002 -73

bzw.
2021.04.16 11:27:56.857 4: CUL_Parse: cul868 A 0C 0C A010 266A86 1ACE1F 0300001B -60.5


Demnach ist das device in beiden Fällen erst mit der zweiten Antwort fertig, die mit Format 03 statt 02 kommt.

Leider ist es nicht bei allen devices so, dass das Ende des Registerreads mit 030000 signalisiert wird. Reg 0 Wert 0 ist normal, schon in der Format 02 Antwort.

Ist dieses Register 100 nun ein Bug oder ein Feature des device?

Gruß, Ansgar.

frank

hi ansgar,

Zitatdas Drama nimmt hier seinen Anfang:
gut beobachtet. ich hatte noch gar nicht gesehen, dass da auch mal nich 0x0000 steht.
ich habe jetzt erst mal nur die letzten 100 getconfigs nach liste_0 durchsucht.  :)

es ist wohl so, dass die letzten 2 bytes der liste_0 nach pon immer 0x0000 sind. alle weiteren fälle zeigen immer unterschiedliche "zufallswerte".
da hat der gute dimmer wohl ein fw bug, so dass die liste_0 immer einen speicherplatz zuviel anzeigt, der nicht zur liste_0 gehört. direkt nach pon ist der bereich wohl noch unbeschrieben, anschliessend steht dort irgend etwas.

das verhalten ist auch vor dem fw update zu beobachten, existiert also mindestens schon seit fw 2.6.

da braucht es wohl eine sonderbehandlung für die liste_0 dieses dimmers, da beide fälle mist sind.
so sieht die liste nach einem "funktionierenden" getconfig aus:
2021-04-17 13:18:46   RegL_00.         00:00 02:81 0A:1A 0B:CE 0C:1F 15:FF 18:00 54:13
2021.04.17 13:18:46.111 4: CUL_Parse: cul868 A 18 43 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF1800541320 -58
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,

häßlich, dieses Verhalten beim Register Read.

Zitatda hat der gute dimmer wohl ein fw bug, so dass die liste_0 immer einen speicherplatz zuviel anzeigt, der nicht zur liste_0 gehört.
HM-MOD-RE-8 Firmware 1.2 macht auch so was. Sollte bei dem wohl mal Batterie leer level werden.

Dann gibt es devices, die die message number nicht mit dem nächsten Satz Werte beim register read block erhöhen. Gibt es das auch beim peer read in Rohdaten zu beobachten?

Im Anhang wieder eine neue Version zum Testen.

Gruß, Ansgar.

Edit: Anhang gelöscht
Edit2: Erledigt. Hinweis: Random Adress/Data Päärchen können richtig gelesene wieder überschreiben und werden nicht unterdrückt.

frank

hi ansgar,

ich würde sagen, dass mit allen 3 io als sender alle getconfigs sauber gelesen wurden. prima.

2021.04.17 20:01:26.019 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:7, 2 -> 2, +266A86,00,00,00, 0
2021.04.17 20:01:26.021 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 04 A0 01 1ACE1F 266A86 00040000000000
2021.04.17 20:01:26.041 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.044 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.046 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.048 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 2 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:26.052 4: CUL_Parse: cul868 A 10 04 A001 1ACE1F 266A86 0004000000000066 -23
2021.04.17 20:01:26.063 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD18C d:FF r:FFCE     m:04 A001 1ACE1F 266A86 00040000000000
2021.04.17 20:01:26.185 4: CUL_Parse: cul868 A 18 04 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF1800000011 -65.5
2021.04.17 20:01:26.188 4: mTp:10 wait:RegisterRead got mStp:02 mNo:4 :
          nAddr:0
          forChn:00
          mNoB:0
          mNoBmax:-1
          forPeer:
          forList:00
          mNo:4
          Pending:RegisterRead
          reSent:1
          cmd:As1004A0011ACE1F266A8600040000000000
2021.04.17 20:01:26.193 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:26.194 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:26.196 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:26.215 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.218 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.220 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.222 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044AD20F d:FF r:FFD2     m:04 A010 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.17 20:01:26.224 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:26.225 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 20:01:26.227 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 20:01:26.228 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3A msg: 04 A0 10 266A86 1ACE1F 0202810A1A0BCE0C1F15FF18000000
2021.04.17 20:01:26.230 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:26.284 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 24 A0 01 1ACE1F 266A86 01040000000001
2021.04.17 20:01:26.291 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 20:01:26.292 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 20:01:26.296 4: CUL_Parse: cul868 A 0A 04 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:26.307 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD280 d:FF r:FFCE     m:04 8002 1ACE1F 266A86 00
2021.04.17 20:01:26.423 4: CUL_Parse: cul868 A 0C 05 A010 266A86 1ACE1F 03000018 -62
2021.04.17 20:01:26.425 4: mTp:10 wait:RegisterRead got mStp:03 mNo:5 :
          mNo:36
          forList:01
          forPeer:
          Pending:RegisterRead
          reSent:1
          cmd:As1024A0011ACE1F266A8601040000000001
          forChn:01
          nAddr:0
          mNoB:0
          mNoBmax:-1
2021.04.17 20:01:26.426 4: waiting for RegisterRead: msgNo:36+, rec:5
2021.04.17 20:01:26.427 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:26.427 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:26.446 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.449 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.451 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.453 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044AD2FE d:FF r:FFD1     m:05 A010 266A86 1ACE1F 030000
2021.04.17 20:01:26.455 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:26.457 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3A msg: 05 A0 10 266A86 1ACE1F 030000
2021.04.17 20:01:26.458 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:26.523 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 24 A0 01 1ACE1F 266A86 01040000000001
2021.04.17 20:01:26.530 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 20:01:26.531 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 20:01:26.545 4: CUL_Parse: cul868 A 0A 05 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:26.555 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD378 d:FF r:FFCE     m:05 8002 1ACE1F 266A86 00
2021.04.17 20:01:26.755 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 24 A0 01 1ACE1F 266A86 01040000000001
2021.04.17 20:01:26.787 4: CUL_Parse: cul868 A 10 24 A001 1ACE1F 266A86 0104000000000166 -23
2021.04.17 20:01:26.797 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD46A d:FF r:FFCE     m:24 A001 1ACE1F 266A86 01040000000001
2021.04.17 20:01:26.919 4: CUL_Parse: cul868 A 1A 24 A010 266A86 1ACE1F 02300632503364344B355056005724590118 -62
2021.04.17 20:01:26.922 4: mTp:10 wait:RegisterRead got mStp:02 mNo:36 :
          mNo:36
          forList:01
          forPeer:
          Pending:RegisterRead
          reSent:1
          cmd:As1024A0011ACE1F266A8601040000000001
          forChn:01
          nAddr:0
          mNoB:0
          mNoBmax:-1
2021.04.17 20:01:26.924 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:26.925 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:26.944 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.946 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.948 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:26.951 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044AD4EE d:FF r:FFD2     m:24 A010 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.17 20:01:26.952 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:26.954 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 20:01:26.955 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 20:01:26.956 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3A msg: 24 A0 10 266A86 1ACE1F 02300632503364344B3550560057245901
2021.04.17 20:01:26.958 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:27.029 4: CUL_Parse: cul868 A 0A 24 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:27.041 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD55E d:FF r:FFCE     m:24 8002 1ACE1F 266A86 00
2021.04.17 20:01:27.157 4: CUL_Parse: cul868 A 0C 25 A010 266A86 1ACE1F 02080010 -66
2021.04.17 20:01:27.159 4: mTp:10 wait:RegisterRead got mStp:02 mNo:37 :
          mNo:36
          forList:01
          forPeer:
          Pending:RegisterRead
          reSent:1
          cmd:As1024A0011ACE1F266A8601040000000001
          forChn:01
          nAddr:0
          mNoB:1
          mNoBmax:0
2021.04.17 20:01:27.161 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:27.162 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:27.181 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.183 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.185 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.188 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044AD5DC d:FF r:FFD2     m:25 A010 266A86 1ACE1F 020800
2021.04.17 20:01:27.189 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:27.191 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3B msg: 25 A0 10 266A86 1ACE1F 020800
2021.04.17 20:01:27.193 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:27.280 4: CUL_Parse: cul868 A 0A 25 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:27.290 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD657 d:FF r:FFCE     m:25 8002 1ACE1F 266A86 00
2021.04.17 20:01:27.406 4: CUL_Parse: cul868 A 0C 26 A010 266A86 1ACE1F 03000018 -62
2021.04.17 20:01:27.409 4: mTp:10 wait:RegisterRead got mStp:03 mNo:38 :
          mNo:36
          forList:01
          forPeer:
          Pending:RegisterRead
          reSent:1
          cmd:As1024A0011ACE1F266A8601040000000001
          forChn:01
          nAddr:0
          mNoB:3
          mNoBmax:1
2021.04.17 20:01:27.414 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:27.415 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:27.417 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:27.436 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.439 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.441 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.443 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044AD6D5 d:FF r:FFD2     m:26 A010 266A86 1ACE1F 030000
2021.04.17 20:01:27.445 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:27.447 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3A msg: 26 A0 10 266A86 1ACE1F 030000
2021.04.17 20:01:27.448 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:27.508 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 27 A0 01 1ACE1F 266A86 0103
2021.04.17 20:01:27.514 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 20:01:27.515 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 20:01:27.529 4: CUL_Parse: cul868 A 0A 26 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:27.540 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD751 d:FF r:FFCE     m:26 8002 1ACE1F 266A86 00
2021.04.17 20:01:27.744 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 27 A0 01 1ACE1F 266A86 0103
2021.04.17 20:01:27.771 4: CUL_Parse: cul868 A 0B 27 A001 1ACE1F 266A86 010366 -23
2021.04.17 20:01:27.781 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD842 d:FF r:FFCE     m:27 A001 1ACE1F 266A86 0103
2021.04.17 20:01:27.905 4: CUL_Parse: cul868 A 16 27 A010 266A86 1ACE1F 01266A8601266A86020000000011 -65.5
2021.04.17 20:01:27.908 4: mTp:10 wait:PeerList got mStp:01 mNo:39 :
          mNo:39
          Pending:PeerList
          reSent:1
          cmd:As0B27A0011ACE1F266A860103
          forChn:01
          mNoBmax:-1
          mNoB:0
2021.04.17 20:01:27.917 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:27.917 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:27.919 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:27.938 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.940 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.942 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:27.945 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044AD8C8 d:FF r:FFD1     m:27 A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 20:01:27.946 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:27.948 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 20:01:27.949 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 20:01:27.951 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3B msg: 27 A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 20:01:27.952 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:28.006 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 47 A0 01 1ACE1F 266A86 02040000000001
2021.04.17 20:01:28.013 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 20:01:28.014 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 20:01:28.019 4: CUL_Parse: cul868 A 0A 27 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:28.030 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AD93B d:FF r:FFCE     m:27 8002 1ACE1F 266A86 00
2021.04.17 20:01:28.246 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 47 A0 01 1ACE1F 266A86 02040000000001
2021.04.17 20:01:28.263 4: CUL_Parse: cul868 A 14 9E 805E 266EA5 1ACE1F 00000000000000000000001F -58.5
2021.04.17 20:01:28.269 0: HMUARTLGW hmuart1 recv: 01 05 00 00 34 msg: 9E 80 5E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 20:01:28.272 0: HMLAN_Parse: hmlan1 R:E266EA5   stat:0000 t:044ADA2E d:FF r:FFCD     m:9E 805E 266EA5 1ACE1F 0000000000000000000000
2021.04.17 20:01:28.291 4: CUL_Parse: cul868 A 10 47 A001 1ACE1F 266A86 0204000000000166 -23
2021.04.17 20:01:28.301 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044ADA4A d:FF r:FFCE     m:47 A001 1ACE1F 266A86 02040000000001
2021.04.17 20:01:28.425 4: CUL_Parse: cul868 A 1A 47 A010 266A86 1ACE1F 02300632503364344B355056005724590019 -61.5
2021.04.17 20:01:28.431 4: mTp:10 wait:RegisterRead got mStp:02 mNo:71 :
          reSent:1
          cmd:As1047A0011ACE1F266A8602040000000001
          Pending:RegisterRead
          mNo:71
          forList:01
          forPeer:
          mNoB:0
          mNoBmax:-1
          forChn:02
          nAddr:0
2021.04.17 20:01:28.434 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:28.435 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:28.454 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.457 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.460 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.463 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044ADACE d:FF r:FFD1     m:47 A010 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.17 20:01:28.464 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:28.466 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 20:01:28.467 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 20:01:28.469 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3A msg: 47 A0 10 266A86 1ACE1F 02300632503364344B3550560057245900
2021.04.17 20:01:28.470 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:28.535 4: CUL_Parse: cul868 A 0A 47 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:28.545 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044ADB3E d:FF r:FFCE     m:47 8002 1ACE1F 266A86 00
2021.04.17 20:01:28.661 4: CUL_Parse: cul868 A 0C 48 A010 266A86 1ACE1F 02080010 -66
2021.04.17 20:01:28.664 4: mTp:10 wait:RegisterRead got mStp:02 mNo:72 :
          reSent:1
          cmd:As1047A0011ACE1F266A8602040000000001
          Pending:RegisterRead
          mNo:71
          forList:01
          forPeer:
          mNoB:1
          mNoBmax:0
          forChn:02
          nAddr:0
2021.04.17 20:01:28.666 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:28.667 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:28.685 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.688 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.690 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.692 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044ADBBC d:FF r:FFD2     m:48 A010 266A86 1ACE1F 020800
2021.04.17 20:01:28.694 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:28.696 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3B msg: 48 A0 10 266A86 1ACE1F 020800
2021.04.17 20:01:28.697 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:28.784 4: CUL_Parse: cul868 A 0A 48 8002 1ACE1F 266A86 0067 -22.5
2021.04.17 20:01:28.794 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044ADC37 d:FF r:FFCE     m:48 8002 1ACE1F 266A86 00
2021.04.17 20:01:28.910 4: CUL_Parse: cul868 A 0C 49 A010 266A86 1ACE1F 03000018 -62
2021.04.17 20:01:28.912 4: mTp:10 wait:RegisterRead got mStp:03 mNo:73 :
          reSent:1
          cmd:As1047A0011ACE1F266A8602040000000001
          Pending:RegisterRead
          mNo:71
          forList:01
          forPeer:
          mNoB:3
          mNoBmax:1
          forChn:02
          nAddr:0
2021.04.17 20:01:28.918 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:28.919 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:28.921 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:4, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:28.940 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.942 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.944 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:28.947 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044ADCB6 d:FF r:FFD1     m:49 A010 266A86 1ACE1F 030000
2021.04.17 20:01:28.948 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:28.950 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3A msg: 49 A0 10 266A86 1ACE1F 030000
2021.04.17 20:01:28.952 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:29.012 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 4A A0 01 1ACE1F 266A86 0203
2021.04.17 20:01:29.018 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 20:01:29.019 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 20:01:29.033 4: CUL_Parse: cul868 A 0A 49 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:29.042 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044ADD30 d:FF r:FFCE     m:49 8002 1ACE1F 266A86 00
2021.04.17 20:01:29.248 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 4A A0 01 1ACE1F 266A86 0203
2021.04.17 20:01:29.274 4: CUL_Parse: cul868 A 0B 4A A001 1ACE1F 266A86 020366 -23
2021.04.17 20:01:29.284 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044ADE22 d:FF r:FFCE     m:4A A001 1ACE1F 266A86 0203
2021.04.17 20:01:29.409 4: CUL_Parse: cul868 A 16 4A A010 266A86 1ACE1F 01266A8601266A86020000000011 -65.5
2021.04.17 20:01:29.411 4: mTp:10 wait:PeerList got mStp:01 mNo:74 :
          forChn:02
          mNoBmax:-1
          mNoB:0
          Pending:PeerList
          mNo:74
          cmd:As0B4AA0011ACE1F266A860203
          reSent:1
2021.04.17 20:01:29.420 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:6, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:29.420 5: CUL_HM DimPBU01 sent ACK:2
2021.04.17 20:01:29.422 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:5, 1 -> 1, +266A86,00,00,00, 0
2021.04.17 20:01:29.441 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:29.443 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:29.445 5: CUL_HM set DimPBU01 ?
2021.04.17 20:01:29.448 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:044ADEA8 d:FF r:FFD2     m:4A A010 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 20:01:29.449 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:29.451 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.17 20:01:29.452 0: HMUARTLGW hmuart1 Ack: 02
2021.04.17 20:01:29.454 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3B msg: 4A A0 10 266A86 1ACE1F 01266A8601266A860200000000
2021.04.17 20:01:29.455 4: CUL_HM DimPBU01 dupe: dont process
2021.04.17 20:01:29.510 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 6A A0 01 1ACE1F 266A86 03040000000001
2021.04.17 20:01:29.516 0: HMUARTLGW hmuart1 recv: 01 0408, state 100
2021.04.17 20:01:29.518 0: HMUARTLGW hmuart1 IO currently busy, trying again in a bit
2021.04.17 20:01:29.522 4: CUL_Parse: cul868 A 0A 4A 8002 1ACE1F 266A86 0066 -23
2021.04.17 20:01:29.532 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044ADF1A d:FF r:FFCE     m:4A 8002 1ACE1F 266A86 00
2021.04.17 20:01:29.749 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 6A A0 01 1ACE1F 266A86 03040000000001
2021.04.17 20:01:29.781 4: CUL_Parse: cul868 A 10 6A A001 1ACE1F 266A86 0304000000000166 -23
2021.04.17 20:01:29.791 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:044AE01C d:FF r:FFCE     m:6A A001 1ACE1F 266A86 03040000000001
2021.04.17 20:01:29.914 4: CUL_Parse: cul868 A 1A 6A A010 266A86 1ACE1F 02300632503364344B355056005724590011 -65.5
2021.04.17 20:01:29.917 4: mTp:10 wait:RegisterRead got mStp:02 mNo:106 :
          cmd:As106AA0011ACE1F266A8603040000000001
          reSent:1
          Pending:RegisterRead
          forPeer:
          forList:01
          mNo:106
          mNoB:0
          mNoBmax:-1
          nAddr:0
          forChn:03



wenn das pseudo-register in liste_0 nicht 0x0000 ist (bei jedem normalen getconfig), wird es aktuell noch ins reading der liste geschrieben. ebenso wird es im configfile mit "get saveConfig" im regBulk cmd gespeichert, um die liste damit restaurieren zu können.

#======== store device data:DimPBU01 === from: 2021-04-17 21:20:43
#---      entity:DimPBU01
setreading DimPBU01 D-firmware 2.6
setreading DimPBU01 D-serialNr KEQ1110205
setreading DimPBU01 .D-devInfo 110100
setreading DimPBU01 .D-stc 20
set DimPBU01 regBulk RegL_00.  00:00 02:81 0A:1A 0B:CE 0C:1F 15:FF 18:00 6F:68 #2021-04-17 21:04:37
#     timestamp of the readings for reference
#        2021-04-17 21:04:37 :RegL_00.


ich denke es wäre besser, dieses pseudo-register zu unterdrücken. 
vor allem, was passiert, wenn dieses register eine echte adresse repräsentiert, aber einen anderen oder auch unzulässigen wert hat?
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,

Zitatich würde sagen, dass mit allen 3 io als sender alle getconfigs sauber gelesen wurden. prima.
Widerstand ist zwecklos...  ;)

Du hattest von Firmwareupdate geschrieben, 2.6 ist betroffen und welche hast Du noch mit dem Bug entdeckt?
Zum Test im Anhang.

Gruß, Ansgar.

Edit: Anhang gelöscht