[virtueller TC] Eine geänderte Fehlerbehandlung macht Probleme

Begonnen von frank, 24 November 2015, 13:38:52

Vorheriges Thema - Nächstes Thema

frank

hallo martin,

irgend etwas hat sich im ablauf nach einem miss geändert, wodurch es nun vermutlich vermehrt zum einschlafen der vd kommt. bisher wurde nach einem miss der nächst mögliche kommunikationszeitpunkt genutzt, obwohl msgReduce eigentlich die folgenden zeitpunkte auslassen würde. also das selbe verhalten wie bei einer änderung von valvePosTC. für valvePosTC ist der ablauf korrekt. ein miss hat aber keinen einfluss mehr auf den ablauf.

hier mal ein log (msgReduce=2).

m:163 => erfolgreich, ok
m:164 => ausgelassen, ok
m:165 => ausgelassen, ok
m:166 => erfolgreich, ok
m:167 => nicht ausgelassen wegen valvePos, miss, ok
m:168 => ausgelassen, falsch
m:169 => erfolgreich, ok

2015.11.24 08:13:26.284 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:163 ->164 t:1448349206.27706->1448349361.77706  M:1448349206.28318 :155.5
2015.11.24 08:13:26.289 0: HMLAN_Send:  hmlan1 S:S38570860 stat:  00 t:00000000 d:01 r:38570860 m:A4 A258 B5B5B5 1C4E25 0314
2015.11.24 08:13:26.352 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02C76495 d:FF r:FFE0     m:A4 A258 B5B5B5 1C4E25 0314
2015.11.24 08:13:26.476 0: HMLAN_Parse: hmlan1 R:E1C4E25   stat:0000 t:2B185471 d:FF r:FFBB     m:A4 8202 1C4E25 B5B5B5 01010C1043
2015.11.24 08:13:26.809 0: HMLAN_Parse: hmusb1 R:E1C4E25   stat:0000 t:02C76515 d:FF r:FFC0     m:A4 8202 1C4E25 B5B5B5 01010C1043
2015.11.24 08:13:26.827 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02C76533 d:FF r:FFDF     m:A4 A258 B5B5B5 1C4E25 0314
2015.11.24 08:13:26.840 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02C765FB d:FF r:FFDF     m:A4 A258 B5B5B5 1C4E25 0314
2015.11.24 08:13:26.858 0: HMLAN_Parse: hmlan1 R:R38570860 stat:0008 t:00000000 d:FF r:7FFF     m:A4 A258 B5B5B5 1C4E25 0314
2015.11.24 08:13:26.860 0: HMLAN_Parse: hmlan1 no ACK from 1C4E25
2015.11.24 08:16:02.377 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:164 ->165 t:1448349361.78317->1448349502.78318  M:1448349362.3768 :141
2015.11.24 08:17:43.190 1: Perfmon: possible freeze starting at 08:17:41, delay is 2.189
2015.11.24 08:18:22.791 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:165 ->166 t:1448349502.78318->1448349629.53317  M:1448349502.79068 :126.75
2015.11.24 08:20:29.540 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:166 ->167 t:1448349629.53317->1448349805.78317  M:1448349629.53985 :176.25
2015.11.24 08:20:29.545 0: HMLAN_Send:  hmlan1 S:+1C4E25,00,00,00
2015.11.24 08:20:29.548 0: HMLAN_Send:  hmlan1 S:S385D7DB9 stat:  00 t:00000000 d:01 r:385D7DB9 m:A7 A258 B5B5B5 1C4E25 0014
2015.11.24 08:20:29.608 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02CDD9E8 d:FF r:FFDF     m:A7 A258 B5B5B5 1C4E25 0014
2015.11.24 08:20:29.737 0: HMLAN_Parse: hmlan1 R:E1C4E25   stat:0000 t:2B1ECA06 d:FF r:FFBB     m:A7 8202 1C4E25 B5B5B5 0101100044
2015.11.24 08:20:30.251 0: HMLAN_Parse: hmlan1 R:R385D7DB9 stat:0008 t:00000000 d:FF r:7FFF     m:A7 A258 B5B5B5 1C4E25 0014
2015.11.24 08:20:30.254 0: HMLAN_Parse: hmlan1 no ACK from 1C4E25
2015.11.24 08:20:30.257 0: HMLAN_Parse: hmusb1 R:E1C4E25   stat:0000 t:02CDDA69 d:FF r:FFC0     m:A7 8202 1C4E25 B5B5B5 0101100044
2015.11.24 08:20:30.273 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02CDDA86 d:FF r:FFDF     m:A7 A258 B5B5B5 1C4E25 0014
2015.11.24 08:20:30.288 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02CDDB4E d:FF r:FFDF     m:A7 A258 B5B5B5 1C4E25 0014
2015.11.24 08:22:27.915 3: CUL_HM set VentilControler.AZ.Nord_Btn1 valvePos 11
2015.11.24 08:23:25.954 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:167 ->168 t:1448349805.78985->1448349967.53985  M:1448349805.9542 :161.75
2015.11.24 08:23:25.960 0: HMLAN_Send:  hmlan1 S:+1C4E25,00,00,00
2015.11.24 08:23:25.962 0: HMLAN_Send:  hmlan1 S:S38602ED7 stat:  00 t:00000000 d:01 r:38602ED7 m:A8 A258 B5B5B5 1C4E25 031C
2015.11.24 08:23:26.406 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02D08C93 d:FF r:FFDF     m:A8 A258 B5B5B5 1C4E25 031C
2015.11.24 08:23:26.571 0: HMLAN_Parse: hmlan1 R:R38602ED7 stat:0008 t:00000000 d:FF r:7FFF     m:A8 A258 B5B5B5 1C4E25 031C
2015.11.24 08:23:26.573 0: HMLAN_Parse: hmlan1 no ACK from 1C4E25
2015.11.24 08:23:35.961 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2015.11.24 08:23:57.085 1: Perfmon: possible freeze starting at 08:23:54, delay is 3.084
2015.11.24 08:26:07.547 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:168 ->169 t:1448349967.53985->1448350115.03985  M:1448349967.54642 :147.5
2015.11.24 08:26:29.228 3: CUL_HM set VentilControler.AZ.Nord_Btn1 valvePos 8
2015.11.24 08:28:35.117 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:169 ->170 t:1448350115.03985->1448350248.03985  M:1448350115.11677 :133
2015.11.24 08:28:35.124 0: HMLAN_Send:  hmlan1 S:S3864E682 stat:  00 t:00000000 d:01 r:3864E682 m:AA A258 B5B5B5 1C4E25 0314
2015.11.24 08:28:35.168 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02D542A8 d:FF r:FFE0     m:AA A258 B5B5B5 1C4E25 0314
2015.11.24 08:28:35.297 0: HMLAN_Parse: hmusb1 R:E1C4E25   stat:0000 t:02D54329 d:FF r:FFC0     m:AA 8202 1C4E25 B5B5B5 0101102044
2015.11.24 08:28:35.649 0: HMLAN_Parse: hmlan1 R:E1C4E25   stat:0000 t:2B263311 d:FF r:FFBB     m:AA 8202 1C4E25 B5B5B5 0101102044
2015.11.24 08:28:35.669 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02D54347 d:FF r:FFDF     m:AA A258 B5B5B5 1C4E25 0314
2015.11.24 08:28:35.684 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02D54410 d:FF r:FFDF     m:AA A258 B5B5B5 1C4E25 0314
2015.11.24 08:28:35.703 0: HMLAN_Parse: hmlan1 R:R3864E682 stat:0008 t:00000000 d:FF r:7FFF     m:AA A258 B5B5B5 1C4E25 0314
2015.11.24 08:28:35.706 0: HMLAN_Parse: hmlan1 no ACK from 1C4E25
2015.11.24 08:29:01.508 1: Perfmon: possible freeze starting at 08:29:00, delay is 1.508
2015.11.24 08:30:48.124 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:170 ->171 t:1448350248.11677->1448350430.61677  M:1448350248.1234 :182.5
2015.11.24 08:30:59.274 1: ----- NOTIFY ----- Thermostat.WZ_Climate desired-temp: 18.5
2015.11.24 08:31:56.369 1: Perfmon: possible freeze starting at 08:31:54, delay is 2.369
2015.11.24 08:33:50.623 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:171 ->172 t:1448350430.61677->1448350598.61677  M:1448350430.62302 :168
2015.11.24 08:34:31.217 3: CUL_HM set VentilControler.AZ.Nord_Btn1 valvePos 5
2015.11.24 08:34:41.546 1: ----- SYNC-ITR1500 ----- FB_IT02_chn03:on => IT10:on
2015.11.24 08:36:38.623 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:172 ->173 t:1448350598.61677->1448350752.36677  M:1448350598.62299 :153.75
2015.11.24 08:36:38.629 0: HMLAN_Send:  hmlan1 S:S386C4734 stat:  00 t:00000000 d:01 r:386C4734 m:AD A258 B5B5B5 1C4E25 030C
2015.11.24 08:36:38.680 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02DCA353 d:FF r:FFE0     m:AD A258 B5B5B5 1C4E25 030C
2015.11.24 08:36:38.808 0: HMLAN_Parse: hmusb1 R:E1C4E25   stat:0000 t:02DCA3D4 d:FF r:FFBF     m:AD 8202 1C4E25 B5B5B5 0101102045
2015.11.24 08:36:38.990 0: HMLAN_Parse: hmlan1 R:E1C4E25   stat:0000 t:2B2D9406 d:FF r:FFB9     m:AD 8202 1C4E25 B5B5B5 0101102045
2015.11.24 08:36:39.008 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02DCA3F1 d:FF r:FFE0     m:AD A258 B5B5B5 1C4E25 030C
2015.11.24 08:36:39.039 0: HMLAN_Parse: hmusb1 R:EB5B5B5   stat:0000 t:02DCA4B9 d:FF r:FFE0     m:AD A258 B5B5B5 1C4E25 030C
2015.11.24 08:36:39.196 0: HMLAN_Parse: hmlan1 R:R386C4734 stat:0008 t:00000000 d:FF r:7FFF     m:AD A258 B5B5B5 1C4E25 030C
2015.11.24 08:36:39.198 0: HMLAN_Parse: hmlan1 no ACK from 1C4E25


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

martinp876

Hm. Geaendert habe ich nichts an dieser stelle. Mal sehen

frank

#2
es ist durchaus möglich, dass die änderung schon sehr alt ist (1 jahr?). das ist mir auch nur durch zufall aufgefallen, da ich gerade einen zähler eingebaut habe, der mir die absolute anzahl und die durchschnittlichen kommunikationsfehler pro tag anzeigt.

edit: ich habe mir noch mal den thread "tc emulieren" angeschaut und denke nun, dass der fehler wohl schon seit dem einbau von msgreduce vorhanden ist. die funktionen in 10_cul_hm vom 30.06.2014 sehen mir identisch aus.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html