TC emulieren

frank · 20 Februar 2014, 11:06:11

hallo martin,

das positive vorweg.
v4992 hatte beim resetversuch die besten rahmenbedingungen aller versuche. in den 8 std vom einspielen bis zum restart gab es genau 5 miss mit 5 vd. also pro vd einer in 8 std. die system verzögerungen wurden bei mir durch häufige aufrufe von GetHttpFile hervorgerufen. durch austausch mit HttpUtils_NonblockingGet sind meine miss quasi verschwunden.

jetzt das negative.
es hat nur 1 vd überlebt. beim einspielen gestern nacht auch schon. die dann allerdings beim ersten echten event (2.). seltsam ist die berechnung des selben events bei B4B4B401 vor und nach shutdown.

Code Auswählen

2014.02.20 09:23:57.062 1: VD-test B4B4B401 c:147 t:1392884798802 x:161750
2014.02.20 09:23:57.070 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.20 09:23:57.072 0: HMLAN_Send:  HMLAN1 S:S4E64DED0 stat:  00 t:00000000 d:01 r:4E64DED0 m:93 A258 B4B4B4 1CE9F5 03FD
2014.02.20 09:23:57.223 0: HMLAN_Parse: HMLAN1 R:R4E64DD32 stat:0008 t:00000000 d:FF r:7FFF     m:C6 A258 B3B3B3 193A9A 0042
2014.02.20 09:23:57.226 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 09:23:57.256 0: HMLAN_Parse: HMLAN1 R:E1CE9F5   stat:0000 t:1176F19F d:FF r:FFBA     m:93 8202 1CE9F5 B4B4B4 0101C60047
2014.02.20 09:23:57.637 0: HMLAN_Parse: HMLAN1 R:R4E64DED0 stat:0008 t:00000000 d:FF r:7FFF     m:93 A258 B4B4B4 1CE9F5 03FD
2014.02.20 09:23:57.639 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 09:25:00.063 1: VD-test B1B1B101 c:25 t:1392884881053 x:181000
2014.02.20 09:25:00.072 0: HMLAN_Send:  HMLAN1 S:S4E65D4EA stat:  00 t:00000000 d:01 r:4E65D4EA m:19 A258 B1B1B1 1BFC52 03FD
2014.02.20 09:25:00.258 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:1177E7C1 d:FF r:FFB5     m:19 8202 1BFC52 B1B1B1 01011A104A
2014.02.20 09:25:00.638 0: HMLAN_Parse: HMLAN1 R:R4E65D4EA stat:0008 t:00000000 d:FF r:7FFF     m:19 A258 B1B1B1 1BFC52 03FD
2014.02.20 09:25:00.641 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 09:25:13.075 1: VD-test B2B2B201 c:193 t:1392884885815 x:172750
2014.02.20 09:25:13.083 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.20 09:25:13.086 0: HMLAN_Send:  HMLAN1 S:S4E6607BD stat:  00 t:00000000 d:01 r:4E6607BD m:C1 A258 B2B2B2 1DFC2F 0311
2014.02.20 09:25:13.269 0: HMLAN_Parse: HMLAN1 R:E1DFC2F   stat:0000 t:11781A96 d:FF r:FFC8     m:C1 8202 1DFC2F B2B2B2 0101081035
2014.02.20 09:25:13.650 0: HMLAN_Parse: HMLAN1 R:R4E6607BD stat:0008 t:00000000 d:FF r:7FFF     m:C1 A258 B2B2B2 1DFC2F 0311
2014.02.20 09:25:13.652 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 09:25:37.600 1: VD-test B5B5B501 c:15 t:1392884866093 x:128500
2014.02.20 09:25:37.608 0: HMLAN_Send:  HMLAN1 S:S4E66678A stat:  00 t:00000000 d:01 r:4E66678A m:0F A258 B5B5B5 1C4E25 0007
2014.02.20 09:25:37.789 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:11787A62 d:FF r:FFB3     m:0F 8202 1C4E25 B5B5B5 010106004C
2014.02.20 09:25:38.170 0: HMLAN_Parse: HMLAN1 R:R4E66678A stat:0008 t:00000000 d:FF r:7FFF     m:0F A258 B5B5B5 1C4E25 0007
2014.02.20 09:25:38.174 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 09:26:24.168 1: VD-test B3B3B301 c:199 t:1392884917147 x:133000
2014.02.20 09:26:24.186 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.20 09:26:24.189 0: HMLAN_Send:  HMLAN1 S:S4E671D7D stat:  00 t:00000000 d:01 r:4E671D7D m:C7 A258 B3B3B3 193A9A 033A
2014.02.20 09:26:24.376 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:11793060 d:FF r:FFB5     m:C7 8202 193A9A B3B3B3 0101342049
2014.02.20 09:26:24.754 0: HMLAN_Parse: HMLAN1 R:R4E671D7D stat:0008 t:00000000 d:FF r:7FFF     m:C7 A258 B3B3B3 193A9A 033A
2014.02.20 09:26:24.757 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 09:26:38.822 1: VD-test B4B4B401 c:148 t:1392884946311 x:147500


2014.02.20 09:27:35.749 0: Server shutdown
2014.02.20 09:27:35.779 4: CUL_send:  cul868X0 0     
2014.02.20 09:27:40.541 1: Including fhem.cfg
2014.02.20 09:27:45.570 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.20 09:27:45.626 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.20 09:27:56.335 1: Including ./log/fhem.save
2014.02.20 09:27:58.770 1: HCS BROETJE monitoring of devices started
2014.02.20 09:27:59.151 0: Server started with 298 defined entities (version $Id: fhem.pl 4935 2014-02-15 08:34:09Z rudolfkoenig $, os linux, user root, pid 6710)
2014.02.20 09:27:59.159 1: Perfmon: possible freeze starting at 09:27:42, delay is 17.159
2014.02.20 09:28:00.183 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.20 09:28:03.237 1: Perfmon: possible freeze starting at 09:28:00, delay is 3.236
2014.02.20 09:28:04.967 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: restart
2014.02.20 09:28:04.982 1: ##### restart ##### n: c:15 t:1392884866099
2014.02.20 09:28:05.179 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: restart
2014.02.20 09:28:05.192 1: ##### restart ##### n: c:199 t:1392884917167
2014.02.20 09:28:05.388 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: restart
2014.02.20 09:28:05.399 1: ##### restart ##### n: c:25 t:1392884881062
2014.02.20 09:28:05.561 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: restart
2014.02.20 09:28:05.571 1: ##### restart ##### n: c:193 t:1392884885824
2014.02.20 09:28:05.726 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: restart
2014.02.20 09:28:05.737 1: ##### restart ##### n: c:147 t:1392884798811
2014.02.20 09:28:06.586 1: VD-test B5B5B501 c:16 t:1392885011349 x:145250
2014.02.20 09:28:06.628 1: VD-test B1B1B101 c:26 t:1392885010062 x:129000
2014.02.20 09:28:06.668 1: VD-test B4B4B401 c:148 t:1392884960061 x:161250
2014.02.20 09:28:06.708 1: VD-test B2B2B201 c:194 t:1392885023574 x:137750
2014.02.20 09:28:06.891 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: init
2014.02.20 09:28:06.906 1: ##### init ##### n:1DFC2F c:194 t:1392885023574
2014.02.20 09:28:06.917 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.20 09:28:06.919 0: HMLAN_Send:  HMLAN1 S:S4E68AEC7 stat:  00 t:00000000 d:01 r:4E68AEC7 m:C2 A258 B2B2B2 1DFC2F 030A
2014.02.20 09:28:07.385 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: init
2014.02.20 09:28:07.398 1: ##### init ##### n:193A9A c:199 t:1392884917167
2014.02.20 09:28:07.411 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.20 09:28:07.416 0: HMLAN_Send:  HMLAN1 S:S4E68B0B3 stat:  00 t:00000000 d:01 r:4E68B0B3 m:C7 A258 B3B3B3 193A9A 033A
2014.02.20 09:28:07.654 1: Perfmon: possible freeze starting at 09:28:04, delay is 3.653
2014.02.20 09:28:07.805 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: init
2014.02.20 09:28:07.820 1: ##### init ##### n:1CE9F5 c:148 t:1392884960061
2014.02.20 09:28:07.831 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.20 09:28:07.834 0: HMLAN_Send:  HMLAN1 S:S4E68B259 stat:  00 t:00000000 d:01 r:4E68B259 m:94 A258 B4B4B4 1CE9F5 03FD
2014.02.20 09:28:08.219 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: init
2014.02.20 09:28:08.230 1: ##### init ##### n:1C4E25 c:16 t:1392885011349
2014.02.20 09:28:08.243 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.20 09:28:08.245 0: HMLAN_Send:  HMLAN1 S:S4E68B3F5 stat:  00 t:00000000 d:01 r:4E68B3F5 m:10 A258 B5B5B5 1C4E25 0307
2014.02.20 09:28:08.452 0: HMLAN_Parse: HMLAN1 R:R4E68AEC7 stat:0008 t:00000000 d:FF r:7FFF     m:C2 A258 B2B2B2 1DFC2F 030A
2014.02.20 09:28:08.455 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 09:28:08.459 0: HMLAN_Parse: HMLAN1 R:R4E68B0B3 stat:0008 t:00000000 d:FF r:7FFF     m:C7 A258 B3B3B3 193A9A 033A
2014.02.20 09:28:08.461 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 09:28:08.484 0: HMLAN_Parse: HMLAN1 R:R4E68B259 stat:0008 t:00000000 d:FF r:7FFF     m:94 A258 B4B4B4 1CE9F5 03FD
2014.02.20 09:28:08.487 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 09:28:08.872 0: HMLAN_Parse: HMLAN1 R:R4E68B3F5 stat:0008 t:00000000 d:FF r:7FFF     m:10 A258 B5B5B5 1C4E25 0307
2014.02.20 09:28:08.875 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 09:28:17.002 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_1
2014.02.20 09:28:17.468 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_1
2014.02.20 09:28:17.889 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.20 09:28:18.304 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_1
2014.02.20 09:28:37.177 1: VD-test B3B3B301 c:200 t:1392885099667 x:182500
2014.02.20 09:28:37.185 0: HMLAN_Send:  HMLAN1 S:S4E692503 stat:  00 t:00000000 d:01 r:4E692503 m:C8 A258 B3B3B3 193A9A 033A
2014.02.20 09:28:37.570 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:117B38BE d:FF r:FFB5     m:C8 8202 193A9A B3B3B3 01012C004A
2014.02.20 09:28:37.750 0: HMLAN_Parse: HMLAN1 R:R4E692503 stat:0008 t:00000000 d:FF r:7FFF     m:C8 A258 B3B3B3 193A9A 033A
2014.02.20 09:28:37.753 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 09:28:47.250 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: ok
2014.02.20 09:28:59.401 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: init
2014.02.20 09:28:59.413 1: ##### init ##### n:1BFC52 c:26 t:1392885010062
2014.02.20 09:28:59.423 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.20 09:28:59.426 0: HMLAN_Send:  HMLAN1 S:S4E697BE2 stat:  00 t:00000000 d:01 r:4E697BE2 m:1A A258 B1B1B1 1BFC52 03FD
2014.02.20 09:29:00.034 0: HMLAN_Parse: HMLAN1 R:R4E697BE2 stat:0008 t:00000000 d:FF r:7FFF     m:1A A258 B1B1B1 1BFC52 03FD
2014.02.20 09:29:00.036 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 09:29:09.559 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_1
2014.02.20 09:29:20.071 1: VD-test B4B4B401 c:149 t:1392885093061 x:133000
2014.02.20 09:29:20.079 0: HMLAN_Send:  HMLAN1 S:S4E69CC92 stat:  00 t:00000000 d:01 r:4E69CC92 m:95 A258 B4B4B4 1CE9F5 03FD
2014.02.20 09:29:20.688 0: HMLAN_Parse: HMLAN1 R:R4E69CC92 stat:0008 t:00000000 d:FF r:7FFF     m:95 A258 B4B4B4 1CE9F5 03FD
2014.02.20 09:29:20.691 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 09:29:30.141 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_2
2014.02.20 09:30:10.073 1: VD-test B1B1B101 c:27 t:1392885162062 x:152000
2014.02.20 09:30:10.081 0: HMLAN_Send:  HMLAN1 S:S4E6A8FE3 stat:  00 t:00000000 d:01 r:4E6A8FE3 m:1B A258 B1B1B1 1BFC52 03FD
2014.02.20 09:30:10.688 0: HMLAN_Parse: HMLAN1 R:R4E6A8FE3 stat:0008 t:00000000 d:FF r:7FFF     m:1B A258 B1B1B1 1BFC52 03FD
2014.02.20 09:30:10.691 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 09:30:11.359 1: VD-test B5B5B501 c:17 t:1392885175099 x:163750
2014.02.20 09:30:11.367 0: HMLAN_Send:  HMLAN1 S:S4E6A94EA stat:  00 t:00000000 d:01 r:4E6A94EA m:11 A258 B5B5B5 1C4E25 0307
2014.02.20 09:30:11.975 0: HMLAN_Parse: HMLAN1 R:R4E6A94EA stat:0008 t:00000000 d:FF r:7FFF     m:11 A258 B5B5B5 1C4E25 0307
2014.02.20 09:30:11.977 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 09:30:20.142 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_2
2014.02.20 09:30:21.430 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_2
2014.02.20 09:30:23.838 1: VD-test B2B2B201 c:195 t:1392885167324 x:143750
2014.02.20 09:30:23.845 0: HMLAN_Send:  HMLAN1 S:S4E6AC5A7 stat:  00 t:00000000 d:01 r:4E6AC5A7 m:C3 A258 B2B2B2 1DFC2F 0311
2014.02.20 09:30:24.455 0: HMLAN_Parse: HMLAN1 R:R4E6AC5A7 stat:0008 t:00000000 d:FF r:7FFF     m:C3 A258 B2B2B2 1DFC2F 0311
2014.02.20 09:30:24.460 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 09:30:33.907 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_2
2014.02.20 09:31:33.070 1: VD-test B4B4B401 c:150 t:1392885275561 x:182500
2014.02.20 09:31:33.079 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.20 09:31:33.082 0: HMLAN_Send:  HMLAN1 S:S4E6BD419 stat:  00 t:00000000 d:01 r:4E6BD419 m:96 A258 B4B4B4 1CE9F5 00FD
2014.02.20 09:31:33.715 0: HMLAN_Parse: HMLAN1 R:R4E6BD419 stat:0008 t:00000000 d:FF r:7FFF     m:96 A258 B4B4B4 1CE9F5 00FD
2014.02.20 09:31:33.717 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 09:31:39.686 1: VD-test B3B3B301 c:201 t:1392885267676 x:168000
2014.02.20 09:31:43.142 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_3
2014.02.20 09:32:42.073 1: VD-test B1B1B101 c:28 t:1392885299812 x:137750
2014.02.20 09:32:42.082 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.20 09:32:42.084 0: HMLAN_Send:  HMLAN1 S:S4E6CE1A4 stat:  00 t:00000000 d:01 r:4E6CE1A4 m:1C A258 B1B1B1 1BFC52 00FD
2014.02.20 09:32:42.691 0: HMLAN_Parse: HMLAN1 R:R4E6CE1A4 stat:0008 t:00000000 d:FF r:7FFF     m:1C A258 B1B1B1 1BFC52 00FD
2014.02.20 09:32:42.694 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 09:32:47.334 1: VD-test B2B2B201 c:196 t:1392885296824 x:129500
2014.02.20 09:32:47.342 0: HMLAN_Send:  HMLAN1 S:S4E6CF630 stat:  00 t:00000000 d:01 r:4E6CF630 m:C4 A258 B2B2B2 1DFC2F 030A
2014.02.20 09:32:47.949 0: HMLAN_Parse: HMLAN1 R:R4E6CF630 stat:0008 t:00000000 d:FF r:7FFF     m:C4 A258 B2B2B2 1DFC2F 030A
2014.02.20 09:32:47.952 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 09:32:52.142 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_3
2014.02.20 09:32:55.109 1: VD-test B5B5B501 c:18 t:1392885324349 x:149250
2014.02.20 09:32:55.118 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.20 09:32:55.121 0: HMLAN_Send:  HMLAN1 S:S4E6D1491 stat:  00 t:00000000 d:01 r:4E6D1491 m:12 A258 B5B5B5 1C4E25 0007
2014.02.20 09:32:55.728 0: HMLAN_Parse: HMLAN1 R:R4E6D1491 stat:0008 t:00000000 d:FF r:7FFF     m:12 A258 B5B5B5 1C4E25 0007
2014.02.20 09:32:55.731 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 09:32:57.460 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_3
2014.02.20 09:33:05.181 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_3
2014.02.20 09:34:27.686 1: VD-test B3B3B301 c:202 t:1392885421426 x:153750
2014.02.20 09:34:27.694 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.20 09:34:27.697 0: HMLAN_Send:  HMLAN1 S:S4E6E7E31 stat:  00 t:00000000 d:01 r:4E6E7E31 m:CA A258 B3B3B3 193A9A 003A
2014.02.20 09:34:27.883 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:11809155 d:FF r:FFB5     m:CA 8202 193A9A B3B3B3 01012C004A
2014.02.20 09:34:28.261 0: HMLAN_Parse: HMLAN1 R:R4E6E7E31 stat:0008 t:00000000 d:FF r:7FFF     m:CA A258 B3B3B3 193A9A 003A
2014.02.20 09:34:28.264 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 09:34:35.571 1: VD-test B4B4B401 c:151 t:1392885443811 x:168250
2014.02.20 09:34:35.579 0: HMLAN_Send:  HMLAN1 S:S4E6E9CFE stat:  00 t:00000000 d:01 r:4E6E9CFE m:97 A258 B4B4B4 1CE9F5 00FD
2014.02.20 09:34:36.187 0: HMLAN_Parse: HMLAN1 R:R4E6E9CFE stat:0008 t:00000000 d:FF r:7FFF     m:97 A258 B4B4B4 1CE9F5 00FD
2014.02.20 09:34:36.189 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 09:34:45.640 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_4
2014.02.20 09:34:56.838 1: VD-test B2B2B201 c:197 t:1392885475824 x:179000
2014.02.20 09:34:56.851 0: HMLAN_Send:  HMLAN1 S:S4E6EF013 stat:  00 t:00000000 d:01 r:4E6EF013 m:C5 A258 B2B2B2 1DFC2F 0311
2014.02.20 09:34:57.459 0: HMLAN_Parse: HMLAN1 R:R4E6EF013 stat:0008 t:00000000 d:FF r:7FFF     m:C5 A258 B2B2B2 1DFC2F 0311
2014.02.20 09:34:57.461 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 09:34:59.901 1: VD-test B1B1B101 c:29 t:1392885423062 x:123250
2014.02.20 09:34:59.916 0: HMLAN_Send:  HMLAN1 S:S4E6EFC0E stat:  00 t:00000000 d:01 r:4E6EFC0E m:1D A258 B1B1B1 1BFC52 00FD
2014.02.20 09:35:00.524 0: HMLAN_Parse: HMLAN1 R:R4E6EFC0E stat:0008 t:00000000 d:FF r:7FFF     m:1D A258 B1B1B1 1BFC52 00FD
2014.02.20 09:35:00.527 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 09:35:06.962 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_4
2014.02.20 09:35:09.971 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_4
2014.02.20 09:35:24.361 1: VD-test B5B5B501 c:19 t:1392885459349 x:135000
2014.02.20 09:35:24.370 0: HMLAN_Send:  HMLAN1 S:S4E6F5B93 stat:  00 t:00000000 d:01 r:4E6F5B93 m:13 A258 B5B5B5 1C4E25 0007
2014.02.20 09:35:25.372 0: HMLAN_Parse: HMLAN1 R:R4E6F5B93 stat:0008 t:00000000 d:FF r:7FFF     m:13 A258 B5B5B5 1C4E25 0007
2014.02.20 09:35:25.375 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 09:35:34.512 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_4

bei meinen log zeilen init und reset hatte ich umgestellt und wollte $hash->{helper}{vd}{id} anzeigen lassen. bei init wirds angezeigt bei restart nicht?

ist verbose 5 auch noch nötig? habe ich gerade erst gesehen.

gruss frank

frank · 20 Februar 2014, 11:38:38

hallo martin,

ich sehe gerade das du das speichern umgestellt hast. dann war der gerettete vd von heute nacht auf alle fälle zufall.

asserdem speicherst du nun einen zukünftiges meeting und kein verifiziertes. mit viel glück kann man so einen shutdown restart überleben, aber bestimmt keinen strom ausfall mehr.

gruss frank

martinp876 · 20 Februar 2014, 12:18:31

Hi Frank,

Zitatmit viel glück kann man so einen shutdown

verstehe ich nicht. gespeichert wird der naechste nach einem erfolgreichen - und nur der. Der wird in jedem Fall als naechster errechnet werden.
Der Log ist offensichtlich nicht der aus dem aktuellen File - verbose 5 der VTCs. warum nicht?

Im normal Fall wird in CUL_HM_valvePosUpdt mit $hashVd->{msgCnt} und$hashVd->{nextM} gerechnet. Im Fehlerfall muss auf die idenitschen Werte aufgesetzt werden.

hm - warum klappt es immer bei dir nicht, wenn es bei mir gut geht.
Fuer die Uebersicht ist gerade grenzwertig, dass dein System 2min30 bis 3min zum booten braucht. faellt genau in den Zeitraum einer Periode...

Gruss Martin

frank · 20 Februar 2014, 13:16:33

hallo martin,

hier nochmal verbose5. 2 vd genau beim 1.echten versuch reannimiert.

es sieht ganz danach aus, dass nur die vd überleben, bei denen durch shutdown kein vd-fenster ausgelassen worden ist.

Code Auswählen

2014.02.20 12:22:07.304 5: CUL_HM VentilControler.Kueche_Btn1 m:94 ->95 t:1392895327.29249->1392895458.54249  M:1392895327.30165 :131.25
2014.02.20 12:22:07.311 0: HMLAN_Send:  HMLAN1 S:S4F07FD92 stat:  00 t:00000000 d:01 r:4F07FD92 m:5F A258 B1B1B1 1BFC52 00FD
2014.02.20 12:22:07.496 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:121A15F7 d:FF r:FFB8     m:5F 8202 1BFC52 B1B1B1 0101C60047
2014.02.20 12:22:07.876 0: HMLAN_Parse: HMLAN1 R:R4F07FD92 stat:0008 t:00000000 d:FF r:7FFF     m:5F A258 B1B1B1 1BFC52 00FD
2014.02.20 12:22:07.879 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 12:22:24.560 5: CUL_HM VentilControler.SZ_Btn1 m:6 ->7 t:1392895344.54782->1392895512.04782  M:1392895344.55771 :167.5
2014.02.20 12:22:24.567 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.20 12:22:24.569 0: HMLAN_Send:  HMLAN1 S:S4F0840F9 stat:  00 t:00000000 d:01 r:4F0840F9 m:07 A258 B2B2B2 1DFC2F 0007
2014.02.20 12:22:24.755 0: HMLAN_Parse: HMLAN1 R:E1DFC2F   stat:0000 t:121A5964 d:FF r:FFC8     m:07 8202 1DFC2F B2B2B2 0101060035
2014.02.20 12:22:25.135 0: HMLAN_Parse: HMLAN1 R:R4F0840F9 stat:0008 t:00000000 d:FF r:7FFF     m:07 A258 B2B2B2 1DFC2F 0007
2014.02.20 12:22:25.138 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 12:22:33.324 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:84 ->85 t:1392895353.3124->1392895496.3124  M:1392895353.32147 :143
2014.02.20 12:22:33.331 0: HMLAN_Send:  HMLAN1 S:S4F086335 stat:  00 t:00000000 d:01 r:4F086335 m:55 A258 B5B5B5 1C4E25 0000
2014.02.20 12:22:33.513 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:121A7B9B d:FF r:FFB3     m:55 8202 1C4E25 B5B5B5 010100004D
2014.02.20 12:22:33.893 0: HMLAN_Parse: HMLAN1 R:R4F086335 stat:0008 t:00000000 d:FF r:7FFF     m:55 A258 B5B5B5 1C4E25 0000
2014.02.20 12:22:33.896 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 12:23:41.003 5: CUL_HM VentilControler.Bad_Btn1 m:12 ->13 t:1392895420.98777->1392895548.73777  M:1392895421.00124 :127.75
2014.02.20 12:23:51.557 2: CUL_HM set VentilControler.WZ_Btn1 valvePos 54
2014.02.20 12:23:55.835 5: CUL_HM VentilControler.WZ_Btn1 m:217 ->218 t:1392895435.8224->1392895597.5724  M:1392895435.83237 :161.75
2014.02.20 12:23:55.842 0: HMLAN_Send:  HMLAN1 S:S4F09A584 stat:  00 t:00000000 d:01 r:4F09A584 m:DA A258 B4B4B4 1CE9F5 038A
2014.02.20 12:23:56.026 0: HMLAN_Parse: HMLAN1 R:E1CE9F5   stat:0000 t:121BBDF7 d:FF r:FFBF     m:DA 8202 1CE9F5 B4B4B4 0101742042
2014.02.20 12:23:56.406 0: HMLAN_Parse: HMLAN1 R:R4F09A584 stat:0008 t:00000000 d:FF r:7FFF     m:DA A258 B4B4B4 1CE9F5 038A
2014.02.20 12:23:56.409 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 12:24:18.575 5: CUL_HM VentilControler.Kueche_Btn1 m:95 ->96 t:1392895458.55165->1392895639.30165  M:1392895458.56897 :180.75
2014.02.20 12:24:56.333 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:85 ->86 t:1392895496.32147->1392895624.82147  M:1392895496.3308 :128.5
2014.02.20 12:25:12.070 5: CUL_HM VentilControler.SZ_Btn1 m:7 ->8 t:1392895512.05771->1392895665.05771  M:1392895512.06692 :153
2014.02.20 12:25:48.754 5: CUL_HM VentilControler.Bad_Btn1 m:13 ->14 t:1392895548.73777->1392895725.98777  M:1392895548.75064 :177.25
2014.02.20 12:25:48.764 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.20 12:25:48.768 0: HMLAN_Send:  HMLAN1 S:S4F0B5E9D stat:  00 t:00000000 d:01 r:4F0B5E9D m:0E A258 B3B3B3 193A9A 004F
2014.02.20 12:25:48.954 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:121D7726 d:FF r:FFB5     m:0E 8202 193A9A B3B3B3 01013E0048
2014.02.20 12:25:49.334 0: HMLAN_Parse: HMLAN1 R:R4F0B5E9D stat:0008 t:00000000 d:FF r:7FFF     m:0E A258 B3B3B3 193A9A 004F
2014.02.20 12:25:49.337 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 12:26:37.595 5: CUL_HM VentilControler.WZ_Btn1 m:218 ->219 t:1392895597.58237->1392895744.83237  M:1392895597.59243 :147.25
2014.02.20 12:26:52.333 2: CUL_HM set VentilControler.WZ_Btn1 valvePos 58
2014.02.20 12:27:04.834 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:86 ->87 t:1392895624.82147->1392895802.82147  M:1392895624.83163 :178
2014.02.20 12:27:04.842 0: HMLAN_Send:  HMLAN1 S:S4F0C87CC stat:  00 t:00000000 d:01 r:4F0C87CC m:57 A258 B5B5B5 1C4E25 0000
2014.02.20 12:27:05.024 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:121EA058 d:FF r:FFB3     m:57 8202 1C4E25 B5B5B5 010100004D
2014.02.20 12:27:05.405 0: HMLAN_Parse: HMLAN1 R:R4F0C87CC stat:0008 t:00000000 d:FF r:7FFF     m:57 A258 B5B5B5 1C4E25 0000
2014.02.20 12:27:05.408 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25


2014.02.20 12:27:18.102 0: Server shutdown
2014.02.20 12:27:18.145 4: CUL_send:  cul868X0 0     
2014.02.20 12:27:23.203 1: Including fhem.cfg
2014.02.20 12:27:28.097 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.20 12:27:28.153 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.20 12:27:39.071 1: Including ./log/fhem.save
2014.02.20 12:27:41.370 1: HCS BROETJE monitoring of devices started
2014.02.20 12:27:41.822 0: Server started with 299 defined entities (version $Id: fhem.pl 4935 2014-02-15 08:34:09Z rudolfkoenig $, os linux, user root, pid 7920)
2014.02.20 12:27:41.833 1: Perfmon: possible freeze starting at 12:27:25, delay is 16.833
2014.02.20 12:27:42.247 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.20 12:27:47.143 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: restart
2014.02.20 12:27:47.311 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: restart
2014.02.20 12:27:47.483 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: restart
2014.02.20 12:27:47.652 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: restart
2014.02.20 12:27:47.819 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: restart
2014.02.20 12:27:47.847 1: Perfmon: possible freeze starting at 12:27:43, delay is 4.847
2014.02.20 12:27:47.874 5: CUL_HM VentilControler.SZ_Btn1 m:7 ->9 t:1392895512.05771->1392895826.55771  M:1392895667.86996 :182
2014.02.20 12:27:47.914 5: CUL_HM VentilControler.Bad_Btn1 m:14 ->14 t:1392895726.00064->1392895726.00064  M:1392895667.91365 :
2014.02.20 12:27:47.953 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:87 ->87 t:1392895802.83163->1392895802.83163  M:1392895667.95267 :
2014.02.20 12:27:47.996 5: CUL_HM VentilControler.Kueche_Btn1 m:95 ->97 t:1392895458.55165->1392895730.55165  M:1392895667.99236 :128.75
2014.02.20 12:27:48.051 5: CUL_HM VentilControler.WZ_Btn1 m:218 ->219 t:1392895597.58237->1392895758.83237  M:1392895668.04884 :161.25
2014.02.20 12:27:48.210 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: init
2014.02.20 12:27:48.223 5: CUL_HM VentilControler.SZ_Btn1 m:9 ->9 t:1392895826.55771->1392895826.55771  M:1392895668.22316 :
2014.02.20 12:27:48.234 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.20 12:27:48.236 0: HMLAN_Send:  HMLAN1 S:S4F0D314C stat:  00 t:00000000 d:01 r:4F0D314C m:09 A258 B2B2B2 1DFC2F 0307
2014.02.20 12:27:48.360 2: CUL_HM set VentilControler.SZ_Btn1 valvePos 3
2014.02.20 12:27:48.549 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: init
2014.02.20 12:27:48.565 5: CUL_HM VentilControler.Bad_Btn1 m:14 ->14 t:1392895726.00064->1392895726.00064  M:1392895668.56422 :
2014.02.20 12:27:48.575 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.20 12:27:48.577 0: HMLAN_Send:  HMLAN1 S:S4F0D32A2 stat:  00 t:00000000 d:01 r:4F0D32A2 m:0E A258 B3B3B3 193A9A 034F
2014.02.20 12:27:48.761 2: CUL_HM set VentilControler.Bad_Btn1 valvePos 31
2014.02.20 12:27:48.979 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: init
2014.02.20 12:27:49.002 5: CUL_HM VentilControler.WZ_Btn1 m:219 ->219 t:1392895758.83237->1392895758.83237  M:1392895669.0016 :
2014.02.20 12:27:49.012 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.20 12:27:49.015 0: HMLAN_Send:  HMLAN1 S:S4F0D3456 stat:  00 t:00000000 d:01 r:4F0D3456 m:DB A258 B4B4B4 1CE9F5 0394
2014.02.20 12:27:49.186 2: CUL_HM set VentilControler.WZ_Btn1 valvePos 58
2014.02.20 12:27:49.415 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: init
2014.02.20 12:27:49.430 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:87 ->87 t:1392895802.83163->1392895802.83163  M:1392895669.42955 :
2014.02.20 12:27:49.441 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.20 12:27:49.443 0: HMLAN_Send:  HMLAN1 S:S4F0D3603 stat:  00 t:00000000 d:01 r:4F0D3603 m:57 A258 B5B5B5 1C4E25 0300
2014.02.20 12:27:49.585 2: CUL_HM set VentilControler.AZ.Nord_Btn1 valvePos 0
2014.02.20 12:27:49.674 0: HMLAN_Parse: HMLAN1 R:R4F0D314C stat:0008 t:00000000 d:FF r:7FFF     m:09 A258 B2B2B2 1DFC2F 0307
2014.02.20 12:27:49.677 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 12:27:49.680 0: HMLAN_Parse: HMLAN1 R:R4F0D32A2 stat:0008 t:00000000 d:FF r:7FFF     m:0E A258 B3B3B3 193A9A 034F
2014.02.20 12:27:49.683 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 12:27:49.686 0: HMLAN_Parse: HMLAN1 R:R4F0D3456 stat:0008 t:00000000 d:FF r:7FFF     m:DB A258 B4B4B4 1CE9F5 0394
2014.02.20 12:27:49.689 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 12:27:49.709 1: Perfmon: possible freeze starting at 12:27:48, delay is 1.708
2014.02.20 12:27:50.052 0: HMLAN_Parse: HMLAN1 R:R4F0D3603 stat:0008 t:00000000 d:FF r:7FFF     m:57 A258 B5B5B5 1C4E25 0300
2014.02.20 12:27:50.055 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 12:27:58.233 5: CUL_HM VentilControler.SZ_Btn1 virtualTC use fail-timer
2014.02.20 12:27:58.293 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_1
2014.02.20 12:27:58.583 5: CUL_HM VentilControler.Bad_Btn1 virtualTC use fail-timer
2014.02.20 12:27:58.694 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_1
2014.02.20 12:27:59.015 5: CUL_HM VentilControler.WZ_Btn1 virtualTC use fail-timer
2014.02.20 12:27:59.111 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.20 12:27:59.439 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2014.02.20 12:27:59.501 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_1
2014.02.20 12:28:42.073 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: init
2014.02.20 12:28:42.087 5: CUL_HM VentilControler.Kueche_Btn1 m:97 ->97 t:1392895730.55165->1392895730.55165  M:1392895722.08654 :
2014.02.20 12:28:42.097 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.20 12:28:42.099 0: HMLAN_Send:  HMLAN1 S:S4F0E03B3 stat:  00 t:00000000 d:01 r:4F0E03B3 m:61 A258 B1B1B1 1BFC52 03FD
2014.02.20 12:28:42.230 2: CUL_HM set VentilControler.Kueche_Btn1 valvePos 99
2014.02.20 12:28:42.707 0: HMLAN_Parse: HMLAN1 R:R4F0E03B3 stat:0008 t:00000000 d:FF r:7FFF     m:61 A258 B1B1B1 1BFC52 03FD
2014.02.20 12:28:42.710 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 12:28:46.014 5: CUL_HM VentilControler.Bad_Btn1 m:14 ->15 t:1392895726.00064->1392895888.75064  M:1392895726.01125 :162.75
2014.02.20 12:28:46.032 0: HMLAN_Send:  HMLAN1 S:S4F0E1307 stat:  00 t:00000000 d:01 r:4F0E1307 m:0F A258 B3B3B3 193A9A 034F
2014.02.20 12:28:46.216 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:12202BAE d:FF r:FFB5     m:0F 8202 193A9A B3B3B3 01013E204A
2014.02.20 12:28:46.596 0: HMLAN_Parse: HMLAN1 R:R4F0E1307 stat:0008 t:00000000 d:FF r:7FFF     m:0F A258 B3B3B3 193A9A 034F
2014.02.20 12:28:46.599 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 12:28:49.412 2: CUL_HM set VentilControler.WZ_Btn1 valvePos 54
2014.02.20 12:28:52.096 5: CUL_HM VentilControler.Kueche_Btn1 virtualTC use fail-timer
2014.02.20 12:28:52.164 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_1
2014.02.20 12:28:52.199 5: CUL_HM VentilControler.Kueche_Btn1 m:97 ->98 t:1392895730.55165->1392895882.55165  M:1392895732.19775 :152
2014.02.20 12:28:52.207 0: HMLAN_Send:  HMLAN1 S:S4F0E2B31 stat:  00 t:00000000 d:01 r:4F0E2B31 m:62 A258 B1B1B1 1BFC52 03FD
2014.02.20 12:28:52.814 0: HMLAN_Parse: HMLAN1 R:R4F0E2B31 stat:0008 t:00000000 d:FF r:7FFF     m:62 A258 B1B1B1 1BFC52 03FD
2014.02.20 12:28:52.817 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 12:28:56.073 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: ok
2014.02.20 12:29:02.207 5: CUL_HM VentilControler.Kueche_Btn1 virtualTC use fail-timer
2014.02.20 12:29:02.269 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_2
2014.02.20 12:29:18.849 5: CUL_HM VentilControler.WZ_Btn1 m:219 ->220 t:1392895758.83237->1392895891.83237  M:1392895758.84648 :133
2014.02.20 12:29:18.860 0: HMLAN_Send:  HMLAN1 S:S4F0E934D stat:  00 t:00000000 d:01 r:4F0E934D m:DC A258 B4B4B4 1CE9F5 038A
2014.02.20 12:29:19.467 0: HMLAN_Parse: HMLAN1 R:R4F0E934D stat:0008 t:00000000 d:FF r:7FFF     m:DC A258 B4B4B4 1CE9F5 038A
2014.02.20 12:29:19.470 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 12:29:28.862 5: CUL_HM VentilControler.WZ_Btn1 virtualTC use fail-timer
2014.02.20 12:29:28.972 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_2
2014.02.20 12:30:02.843 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:87 ->88 t:1392895802.83163->1392895966.58163  M:1392895802.84134 :163.75
2014.02.20 12:30:02.851 0: HMLAN_Send:  HMLAN1 S:S4F0F3F25 stat:  00 t:00000000 d:01 r:4F0F3F25 m:58 A258 B5B5B5 1C4E25 0300
2014.02.20 12:30:03.033 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:122157C9 d:FF r:FFB3     m:58 8202 1C4E25 B5B5B5 010100004D
2014.02.20 12:30:03.413 0: HMLAN_Parse: HMLAN1 R:R4F0F3F25 stat:0008 t:00000000 d:FF r:7FFF     m:58 A258 B5B5B5 1C4E25 0300
2014.02.20 12:30:03.416 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 12:30:12.915 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: ok
2014.02.20 12:30:26.569 5: CUL_HM VentilControler.SZ_Btn1 m:9 ->10 t:1392895826.55771->1392895950.80771  M:1392895826.5674 :124.25
2014.02.20 12:30:26.577 0: HMLAN_Send:  HMLAN1 S:S4F0F9BD3 stat:  00 t:00000000 d:01 r:4F0F9BD3 m:0A A258 B2B2B2 1DFC2F 0307
2014.02.20 12:30:27.185 0: HMLAN_Parse: HMLAN1 R:R4F0F9BD3 stat:0008 t:00000000 d:FF r:7FFF     m:0A A258 B2B2B2 1DFC2F 0307
2014.02.20 12:30:27.188 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 12:30:36.578 5: CUL_HM VentilControler.SZ_Btn1 virtualTC use fail-timer
2014.02.20 12:30:36.690 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_2
2014.02.20 12:30:48.781 2: CUL_HM set VentilControler.SZ_Btn1 valvePos 7
2014.02.20 12:31:22.563 5: CUL_HM VentilControler.Kueche_Btn1 m:98 ->99 t:1392895882.55165->1392896020.05165  M:1392895882.56112 :137.5
2014.02.20 12:31:22.570 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.20 12:31:22.573 0: HMLAN_Send:  HMLAN1 S:S4F10768D stat:  00 t:00000000 d:01 r:4F10768D m:63 A258 B1B1B1 1BFC52 00FD
2014.02.20 12:31:23.180 0: HMLAN_Parse: HMLAN1 R:R4F10768D stat:0008 t:00000000 d:FF r:7FFF     m:63 A258 B1B1B1 1BFC52 00FD
2014.02.20 12:31:23.183 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 12:31:28.781 5: CUL_HM VentilControler.Bad_Btn1 m:15 ->16 t:1392895888.76125->1392896037.26125  M:1392895888.77627 :148.5
2014.02.20 12:31:31.843 5: CUL_HM VentilControler.WZ_Btn1 m:220 ->221 t:1392895891.83237->1392896074.33237  M:1392895891.84125 :182.5
2014.02.20 12:31:31.850 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.20 12:31:31.853 0: HMLAN_Send:  HMLAN1 S:S4F109ACD stat:  00 t:00000000 d:01 r:4F109ACD m:DD A258 B4B4B4 1CE9F5 008A
2014.02.20 12:31:32.460 0: HMLAN_Parse: HMLAN1 R:R4F109ACD stat:0008 t:00000000 d:FF r:7FFF     m:DD A258 B4B4B4 1CE9F5 008A
2014.02.20 12:31:32.463 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 12:31:32.570 5: CUL_HM VentilControler.Kueche_Btn1 virtualTC use fail-timer
2014.02.20 12:31:32.632 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_3
2014.02.20 12:31:41.850 5: CUL_HM VentilControler.WZ_Btn1 virtualTC use fail-timer
2014.02.20 12:31:41.912 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_3
2014.02.20 12:32:30.832 5: CUL_HM VentilControler.SZ_Btn1 m:10 ->11 t:1392895950.80771->1392896124.55771  M:1392895950.82683 :173.75
2014.02.20 12:32:30.839 0: HMLAN_Send:  HMLAN1 S:S4F118139 stat:  00 t:00000000 d:01 r:4F118139 m:0B A258 B2B2B2 1DFC2F 0311
2014.02.20 12:32:31.452 0: HMLAN_Parse: HMLAN1 R:R4F118139 stat:0008 t:00000000 d:FF r:7FFF     m:0B A258 B2B2B2 1DFC2F 0311
2014.02.20 12:32:31.466 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 12:32:40.847 5: CUL_HM VentilControler.SZ_Btn1 virtualTC use fail-timer
2014.02.20 12:32:40.958 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_3
2014.02.20 12:32:46.603 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:88 ->89 t:1392895966.59134->1392896115.84134  M:1392895966.60057 :149.25
2014.02.20 12:33:40.064 5: CUL_HM VentilControler.Kueche_Btn1 m:99 ->100 t:1392896020.05165->1392896143.30165  M:1392896020.06147 :123.25
2014.02.20 12:33:40.072 0: HMLAN_Send:  HMLAN1 S:S4F128FAA stat:  00 t:00000000 d:01 r:4F128FAA m:64 A258 B1B1B1 1BFC52 00FD
2014.02.20 12:33:40.679 0: HMLAN_Parse: HMLAN1 R:R4F128FAA stat:0008 t:00000000 d:FF r:7FFF     m:64 A258 B1B1B1 1BFC52 00FD
2014.02.20 12:33:40.688 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.20 12:33:49.364 2: CUL_HM set VentilControler.Bad_Btn1 valvePos 28
2014.02.20 12:33:50.071 5: CUL_HM VentilControler.Kueche_Btn1 virtualTC use fail-timer
2014.02.20 12:33:50.135 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_4
2014.02.20 12:33:57.275 5: CUL_HM VentilControler.Bad_Btn1 m:16 ->17 t:1392896037.26125->1392896171.26125  M:1392896037.27297 :134
2014.02.20 12:33:57.283 0: HMLAN_Send:  HMLAN1 S:S4F12D2E5 stat:  00 t:00000000 d:01 r:4F12D2E5 m:11 A258 B3B3B3 193A9A 0347
2014.02.20 12:33:57.468 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:1224EBAD d:FF r:FFB5     m:11 8202 193A9A B3B3B3 01013E2048
2014.02.20 12:33:57.848 0: HMLAN_Parse: HMLAN1 R:R4F12D2E5 stat:0008 t:00000000 d:FF r:7FFF     m:11 A258 B3B3B3 193A9A 0347
2014.02.20 12:33:57.851 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.20 12:34:34.344 5: CUL_HM VentilControler.WZ_Btn1 m:221 ->222 t:1392896074.33237->1392896242.33237  M:1392896074.34161 :168
2014.02.20 12:34:34.352 0: HMLAN_Send:  HMLAN1 S:S4F1363B2 stat:  00 t:00000000 d:01 r:4F1363B2 m:DE A258 B4B4B4 1CE9F5 008A
2014.02.20 12:34:34.959 0: HMLAN_Parse: HMLAN1 R:R4F1363B2 stat:0008 t:00000000 d:FF r:7FFF     m:DE A258 B4B4B4 1CE9F5 008A
2014.02.20 12:34:34.963 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.20 12:34:44.351 5: CUL_HM VentilControler.WZ_Btn1 virtualTC use fail-timer
2014.02.20 12:34:44.414 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_4
2014.02.20 12:35:15.853 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:89 ->90 t:1392896115.84134->1392896250.59134  M:1392896115.85072 :134.75
2014.02.20 12:35:15.860 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.20 12:35:15.863 0: HMLAN_Send:  HMLAN1 S:S4F1405D6 stat:  00 t:00000000 d:01 r:4F1405D6 m:5A A258 B5B5B5 1C4E25 0000
2014.02.20 12:35:16.043 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:12261EA7 d:FF r:FFB3     m:5A 8202 1C4E25 B5B5B5 010100004D
2014.02.20 12:35:16.424 0: HMLAN_Parse: HMLAN1 R:R4F1405D6 stat:0008 t:00000000 d:FF r:7FFF     m:5A A258 B5B5B5 1C4E25 0000
2014.02.20 12:35:16.426 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.20 12:35:24.569 5: CUL_HM VentilControler.SZ_Btn1 m:11 ->12 t:1392896124.55771->1392896283.80771  M:1392896124.56674 :159.25
2014.02.20 12:35:24.576 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.20 12:35:24.578 0: HMLAN_Send:  HMLAN1 S:S4F1427E2 stat:  00 t:00000000 d:01 r:4F1427E2 m:0C A258 B2B2B2 1DFC2F 0011
2014.02.20 12:35:25.186 0: HMLAN_Parse: HMLAN1 R:R4F1427E2 stat:0008 t:00000000 d:FF r:7FFF     m:0C A258 B2B2B2 1DFC2F 0011
2014.02.20 12:35:25.188 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.20 12:35:34.577 5: CUL_HM VentilControler.SZ_Btn1 virtualTC use fail-timer
2014.02.20 12:35:34.638 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_4

gruss frank

martinp876 · 20 Februar 2014, 14:46:43

Hi Frank,

hier muss das Problem liegen:
vor restart ist
$hashVd->{msgCnt} = 95, $hashVd->{next} = xx458.55 => $nextTimer=180.75
12:24:18.575 CUL_HM B1B1B1 m:95 ->96 t:xx458.55->xx639.30 M:xx458.56 :180.75

nach dem restart muss 2 Schritte regechnet werden um das zeitfenster zu erreichen:
mit msgCnt+95 wird begonnen, bassierend auf next=xx458.55
step 1 muss zum gleichen ergebniss wie vorreboot kommen, $nextTimer=180.75
Step 2 wird angezeigt als 128.75
=>$hashVd->{next} + step1 + step2 = nextF =>768,05.
Der log zeigt aber 730.55
12:27:47.996 CUL_HM B1B1B1 m:95 ->97 t:xx458.55->xx730.55 M:xx667.99 :128.75

Da alle Inputparameter identisch sind kann es nicht sein.
Wir haben aber noch
$hashVd->{idh} und $hashVd->{idl}

Ich gehe einmal davon aus, dass diese Werte nicht oder falsch initialisiert sind, da sonst keine Variablen mehr in der Berechnung vorkommen.
Sobald die Berechnung neu angestellt wurde ging sie schief. Koennte man jetzt loggen...
Im Update sollte ein set valvePos eigentlich das noetige tun...
Gruss Martin

frank · 20 Februar 2014, 15:00:13

hallo martin,

ZitatIch gehe einmal davon aus, dass diese Werte nicht oder falsch initialisiert sind, da sonst keine Variablen mehr in der Berechnung vorkommen.

Zitatbei meinen log zeilen init und reset hatte ich umgestellt und wollte $hash->{helper}{vd}{id} anzeigen lassen. bei init wirds angezeigt bei restart nicht?

gruss frank

martinp876 · 20 Februar 2014, 15:36:41

Hi Frank,

habe es einmal nachgerechnet. Das genau ist der Fehler - die beiden Werte sind offensichtlich '0'.

Wie gesagt ist die Idee, dass vor dem ersten Timer ein valvePos kommando kommt, dann wird es initialisiert und alles sollte passen.
Das wird auch der Unterschied zu meinem Setup sein, da kommt ein solches Kommando im config vor, wie erwaehnt (ist noch historisch... egal).
Es ist also zu untersuchen, wie es sein kann, dass in deinem Setup der Timer vor einem valvePos aufgerufen wird.
Ein start kann erfolgen nach
- nach CUL_HM_updateConfig => hier wird ein entsprechendes Kommando aufgerufen - wichtig waere, dass das Reading "valvePosTC" schon gelesen ist => log an dieser Stelle einbauen
- durch ein external kommando - hier sollte alles ok sein-> kann aber nicht uunder falls ein, da sonst msgCnt = 0 waere
- durch eine timer, der beim restart nicht gestoppt wird - keine Ahnung, ob FHEM hier vorkehrungen trifft

Waere also gut, logs einzubauen im Update und im set. Es muss das set aus dem Update kommen und vorher kein Aufruf.

Gruss Martin

frank · 20 Februar 2014, 16:07:50

hallo martin,

ich glaube ich kann dir nicht folgen.

ZitatWie gesagt ist die Idee, dass vor dem ersten Timer ein valvePos kommando kommt, dann wird es initialisiert und alles sollte passen.

den ersten timer initilisierst du doch aber bereits in cul_hm_config. und wie meine logeinträge gezeigt haben "gewinnt" dieser einsprung nach cul_hm_valveposupdt vor dem set einsprung. und hier scheint $hash->{helper}{vd}{id} nicht definiert zu sein. siehe meine logs heute morgen ohne verbose5.

Code Auswählen

    elsif ($st eq "virtual" ) {#setup virtuals
      $hash->{helper}{role}{vrt} = 1;
      if (   $hash->{helper}{fkt} 
          && $hash->{helper}{fkt} =~ m/^(vdCtrl|virtThSens)$/){
        my $vId = substr($id."01",0,8);
        $hash->{helper}{virtTC} = "00";
        $hash->{helper}{vd}{msgRed}= 0                                        if(!defined $hash->{helper}{vd}{msgRed});
        $hash->{helper}{vd}{msgCnt}= ReadingsVal($name,".msgCnt",0)           if(!defined $hash->{helper}{vd}{msgCnt});
        $hash->{helper}{vd}{next}  = ReadingsVal($name,".next",gettimeofday())if(!defined $hash->{helper}{vd}{next});

        CUL_HM_Set($hash,$name,"valvePos",ReadingsVal($name,"valvePosTC",""));
        CUL_HM_Set($hash,$name,"virtTemp",ReadingsVal($name,"temperature",""));
        CUL_HM_Set($hash,$name,"virtHum" ,ReadingsVal($name,"humidity",""));
        RemoveInternalTimer("valvePos:$vId");
        RemoveInternalTimer("valveTmr:$vId");
        CUL_HM_UpdtReadSingle($hash,"valveCtrl","restart",1) if (ReadingsVal($name,"valvePosTC",""));
				Log 1,"##### restart ##### n:".$hash->{helper}{vd}{id}." c:".$hash->{helper}{vd}{msgCnt}." t:".int($hash->{helper}{vd}{next}*1000);
{vd}{msgCnt}." t:".int($hash->{helper}{vd}{next}*1000);
        InternalTimer(ReadingsVal($name,".next",1)
                     ,"CUL_HM_valvePosUpdt","valvePos:$vId",0);
      }
    }

gruss frank

martinp876 · 20 Februar 2014, 19:03:27

Hi Frank,

habe gerade eingecheckt.
Das Problem war wie vermutet nicht bei den Zaehlern. Beim set im update war das Prozent des 40% schuld, dass es nicht ausgefuehrt und die fehlenden Variablen die Berechnung korumpierten. MsgCnt und Next waren ok, wie die Logs schon gezeigt hatten.

Gruss Martin

frank · 20 Februar 2014, 21:50:49

hallo martin,

ich bin ... begeistert

hoffentlich kann ich die tage noch mit "restlos" ergänzen.

nach dem 1. shutdown, direkt nach einspielen der v5001, sind erst alle vd reannimiert worden. doch kurze zeit später sind 2 vd über miss2, ... , miss5, lost eingeschlafen! so ein verhalten hat es nach beseitigung des moulo256-bugs nicht mehr gegeben.
im moment hoffe ich, dass es am wechsel der version lag. vielleicht musste sich erstmal etwas "einlaufen". seit einem erneuten shutdown restart, mit 100% trefferquote im ersten versuch, gibt es in meinem "miss-plot" nichts mehr zu sehen (wie langweilig).

gruss frank

frank · 20 Februar 2014, 21:56:52

der timer in cul_hm_config sollte wegen dem neuen ".next" wohl noch angepasst werden.

Code Auswählen

        InternalTimer(ReadingsVal($name,".next",1)
                     ,"CUL_HM_valvePosUpdt","valvePos:$vId",0);

gruss frank

martinp876 · 21 Februar 2014, 06:38:23

richtig, danke done

frank · 21 Februar 2014, 10:53:39

hallo martin,

ein vd ist gerade eingeschlafen.

in zeile 7 wird msgctr nicht hochgezählt. dann stimmen die meetings nicht mehr

Code Auswählen

2014.02.21 09:37:05.011 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:48 ->49 t:1392971825.00149->1392971975.25149  M:1392971825.01123 :150.25
2014.02.21 09:37:05.019 0: HMLAN_Send:  HMLAN1 S:S539740BD stat:  00 t:00000000 d:01 r:539740BD m:31 A258 B5B5B5 1C4E25 000C
2014.02.21 09:37:05.202 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:020EFFD8 d:FF r:FFB1     m:31 8202 1C4E25 B5B5B5 01010A004D
2014.02.21 09:37:05.582 0: HMLAN_Parse: HMLAN1 R:R539740BD stat:0008 t:00000000 d:FF r:7FFF     m:31 A258 B5B5B5 1C4E25 000C
2014.02.21 09:37:05.585 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:39:35.271 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:49 ->50 t:1392971975.26123->1392972111.01122  M:1392971975.27105 :135.75

2014.02.21 09:41:51.010 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:50 ->50 t:1392972111.01122->1392972111.01122  M:1392972111.00978 :

2014.02.21 09:41:51.018 0: HMLAN_Send:  HMLAN1 S:S539B9DEC stat:  00 t:00000000 d:01 r:539B9DEC m:32 A258 B5B5B5 1C4E25 000C
2014.02.21 09:41:51.199 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:02135D2D d:FF r:FFB2     m:32 8202 1C4E25 B5B5B5 01010A004E
2014.02.21 09:41:51.580 0: HMLAN_Parse: HMLAN1 R:R539B9DEC stat:0008 t:00000000 d:FF r:7FFF     m:32 A258 B5B5B5 1C4E25 000C
2014.02.21 09:41:51.583 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:42:01.038 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:50 ->51 t:1392972111.00978->1392972232.25978  M:1392972121.03809 :121.25
2014.02.21 09:43:52.270 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:51 ->52 t:1392972232.25978->1392972403.25978  M:1392972232.26947 :171
2014.02.21 09:43:52.278 0: HMLAN_Send:  HMLAN1 S:S539D7797 stat:  00 t:00000000 d:01 r:539D7797 m:34 A258 B5B5B5 1C4E25 000C
2014.02.21 09:43:52.885 0: HMLAN_Parse: HMLAN1 R:R539D7797 stat:0008 t:00000000 d:FF r:7FFF     m:34 A258 B5B5B5 1C4E25 000C
2014.02.21 09:43:52.889 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:44:02.279 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2014.02.21 09:44:02.346 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_2
2014.02.21 09:46:43.270 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:52 ->53 t:1392972403.25978->1392972559.75978  M:1392972403.26953 :156.5
2014.02.21 09:46:43.277 0: HMLAN_Send:  HMLAN1 S:S53A0138F stat:  00 t:00000000 d:01 r:53A0138F m:35 A258 B5B5B5 1C4E25 000C
2014.02.21 09:46:43.885 0: HMLAN_Parse: HMLAN1 R:R53A0138F stat:0008 t:00000000 d:FF r:7FFF     m:35 A258 B5B5B5 1C4E25 000C
2014.02.21 09:46:43.888 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:46:53.280 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2014.02.21 09:46:53.347 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_3
2014.02.21 09:49:19.770 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:53 ->54 t:1392972559.75978->1392972701.75978  M:1392972559.7696 :142
2014.02.21 09:49:19.782 0: HMLAN_Send:  HMLAN1 S:S53A276E6 stat:  00 t:00000000 d:01 r:53A276E6 m:36 A258 B5B5B5 1C4E25 000C
2014.02.21 09:49:20.391 0: HMLAN_Parse: HMLAN1 R:R53A276E6 stat:0008 t:00000000 d:FF r:7FFF     m:36 A258 B5B5B5 1C4E25 000C
2014.02.21 09:49:20.394 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:49:29.781 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2014.02.21 09:49:29.874 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_4
2014.02.21 09:51:41.771 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:54 ->55 t:1392972701.75978->1392972829.50978  M:1392972701.77114 :127.75
2014.02.21 09:51:41.779 0: HMLAN_Send:  HMLAN1 S:S53A4A195 stat:  00 t:00000000 d:01 r:53A4A195 m:37 A258 B5B5B5 1C4E25 000C
2014.02.21 09:51:42.387 0: HMLAN_Parse: HMLAN1 R:R53A4A195 stat:0008 t:00000000 d:FF r:7FFF     m:37 A258 B5B5B5 1C4E25 000C
2014.02.21 09:51:42.389 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:51:51.781 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2014.02.21 09:51:51.853 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_5
2014.02.21 09:51:53.455 1: Perfmon: possible freeze starting at 09:51:52, delay is 1.454
2014.02.21 09:53:49.520 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:55 ->56 t:1392972829.50978->1392973006.75978  M:1392972829.5201 :177.25
2014.02.21 09:53:49.528 0: HMLAN_Send:  HMLAN1 S:S53A6949A stat:  00 t:00000000 d:01 r:53A6949A m:38 A258 B5B5B5 1C4E25 000C
2014.02.21 09:53:50.136 0: HMLAN_Parse: HMLAN1 R:R53A6949A stat:0008 t:00000000 d:FF r:7FFF     m:38 A258 B5B5B5 1C4E25 000C
2014.02.21 09:53:50.139 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:53:59.530 5: CUL_HM VentilControler.AZ.Nord_Btn1 virtualTC use fail-timer
2014.02.21 09:53:59.598 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: lost
2014.02.21 09:56:46.771 5: CUL_HM VentilControler.AZ.Nord_Btn1 m:56 ->57 t:1392973006.75978->1392973169.50978  M:1392973006.77056 :162.75
2014.02.21 09:56:46.779 0: HMLAN_Send:  HMLAN1 S:S53A948FD stat:  00 t:00000000 d:01 r:53A948FD m:39 A258 B5B5B5 1C4E25 000C
2014.02.21 09:56:46.961 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:022108B7 d:FF r:FFB1     m:39 8202 1C4E25 B5B5B5 01011E004F
2014.02.21 09:56:47.296 0: HMLAN_Parse: HMLAN1 R:R53A948FD stat:0008 t:00000000 d:FF r:7FFF     m:39 A258 B5B5B5 1C4E25 000C
2014.02.21 09:56:47.299 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.21 09:56:47.305 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:02210A19 d:FF r:FFB1     m:39 8202 1C4E25 B5B5B5 01011E004E
2014.02.21 09:56:56.853 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: ok
2014.02.21 09:56:58.176 1: Perfmon: possible freeze starting at 09:56:57, delay is 1.175

ist noch v5001 ohne den fix von heute morgen. sollte doch aber keinen einfluss auf diesen fehler haben?

gruss frank

frank · 21 Februar 2014, 12:02:35

hallo martin,

ich vermute das liegt hier begraben:

Code Auswählen

while ($nextF < $tn) {# calculate next time from last successful

es lief ja 12 std alles normal. nur 2 mal miss2 und weiter.
im normal fall ist $nextF ~ $tn. sollte nun durch rundungsfehler oder ähnliches nextF ein hauch grösser werden als tn, gibts ärger.

ich würde folgendes vorschlagen. dann berücksichtigt man zusätzlich bei restart die 10sec verzögerung und ist auf alle fälle auf der richtigen seite.

Code Auswählen

while ($nextF < ($tn + 12)) {# calculate next time from last successful

gruss frank

martinp876 · 21 Februar 2014, 12:37:25

Hi Frank,

ja, da hast du recht. Der Timer kommt 1,4ms zu frueh.
12sec ist daher uebertrieben - wir muessen nur die Genauigkeit der internen Berechnung beruecksichtigen - mit voreileng hatte ich nicht gerechnet. Ein Select hat nach meinen Messungen Ungenauigkeiten ab kleiner 10ms. Wenn wir also diese Tolleranz mit Faktor 5 (oder 10 wenn du willst) beruecksichtigen sollte alles laufen. Also nicht meht also 100ms, besser 50ms

Es koennte auf der anderen Seite Probleme geben, wenn du waehren dieser Tolleranz ein die Funktion aufrufst, also ein set kommt. daher das Fenster so klein als moeglich.

Code Auswählen

while ($nextF < ($tn + 0.05)) {# calculate next time from last successful
Ansonsten hast du absolut recht.

p.s. das Problem tritt nicht auf, wenn FHEM verzeogert wird, sondern ausschliesslich wenn der timer zu schnell ist. Das ist mit sicherheit selten und nur in sehr kleinen Einheiten
Gruss Martin