TC emulieren

martinp876 · 17 Februar 2014, 19:19:41

Hi Frank

shutdown & restart? Ich nehmen an shutdown restart.

Zitatseit ein paar tagen klappt aber das restart nicht mehr.

da lässt du mich aber ganz schönauflaufen. Da musst du erst einmal dein System in ordnung bringen bevor du mir sagst, dass ein abgeleitetes Details nicht kappt. Leicht unfair, nachdem du dies schon Tage weisst!

"-rwxrwxrwx" sollte ok sein. Ist das Dirctory auch freigegeben? Kannst du das Lofile löschen?

Zitataber egal

sehe ich garnicht so. Sind die andere Files schreibbar?

frank · 17 Februar 2014, 20:27:42

hallo martin,

ZitatIch nehmen an shutdown restart.

ja.

ZitatIst das Dirctory auch freigegeben?

der pfad zum logfile: var/InternerSpeicher/USB-FlashDisk-00/fhem/log/fhem.log

beide ordner auf dem usbstick haben: drwxrwxrwx

ZitatKannst du das Lofile löschen?

das ist ein problem!

vom laptop -> löscht es , bleibt aber erhalten
über weboberfläche fritzbox -> fehler
über telnet -> muss ich erst mal schauen wie das geht

das ging auf alle fälle schon mal.

ZitatSind die andere Files schreibbar?

über laptop kann ich nicht direkt 10_cul_hm verändern, nur über umweg fritzboxoberfläche ein neues drüberkopieren. war schon immer so.

frank · 17 Februar 2014, 20:39:22

also über telnet: "rm fhem.log" -> no, read-only file system
auch bei einem alten logfile.

auch nach shutdown.

frank · 17 Februar 2014, 20:42:39

mir fällt gerade ein, dass es vor ein paar tagen neue firmware für die fritzbox gab. könnte das ein hinweis sein?

gruss frank

frank · 17 Februar 2014, 21:56:29

hallo martin,

shutdown restart hat nun geklappt.
aber erst nachdem ich den global log-path vom stick weg zurück auf standard gesetzt habe.

ein komplettes fhem update hat beim backupversuch (ebenfals richtung usb-stick) zwischenzeitlich auch versagt. auch hier hat jetzt das umlenken des path auf standard abhilfe geschaffen.

ich habe den starken verdacht, dass avm irgend etwas geändert hat. die fixe änderung gab es, weil die bösen hacker kostenlos über den telefonanschluss telefonieren konnten.

nach dem update schau ich mal weiter.

gruss frank

frank · 17 Februar 2014, 23:33:31

hallo martin,

nun mit frischem fhem update, frizbox reset, 4 vd manuell reannimiert, alle vtc haben msgreduce=0, eine kleine pause eingelegt, dann statefile gesichert, dann shutdown restart.

restart fhem hat funktioniert

.

aber:
alle vd eingeschlafen und laut log zählt der msgcounter bei 2 vtc nach restart falsch weiter. B2B2B2 und B4B4B4. zufälligerweise hatten diese beiden kurz vor shutdown ein miss.

Code Auswählen

2014.02.17 22:50:51.435 0: HMLAN_Send:  HMLAN1 S:S41D4896D stat:  00 t:00000000 d:01 r:41D4896D m:4F A258 B4B4B4 1CE9F5 0000
2014.02.17 22:50:52.043 0: HMLAN_Parse: HMLAN1 R:R41D4896D stat:0008 t:00000000 d:FF r:7FFF     m:4F A258 B4B4B4 1CE9F5 0000
2014.02.17 22:50:52.046 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.17 22:51:01.196 0: HMLAN_Send:  HMLAN1 S:S41D4AF8E stat:  00 t:00000000 d:01 r:41D4AF8E m:52 A258 B2B2B2 1DFC2F 0000
2014.02.17 22:51:01.503 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.17 22:51:01.804 0: HMLAN_Parse: HMLAN1 R:R41D4AF8E stat:0008 t:00000000 d:FF r:7FFF     m:52 A258 B2B2B2 1DFC2F 0000
2014.02.17 22:51:01.807 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.17 22:51:09.636 1: ----- NOTIFY ----- TCControler.Bad state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:51:26.435 1: ----- NOTIFY ----- TCControler.SZ state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:51:52.081 1: ----- NOTIFY ----- TCControler.WZ state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:51:57.280 0: HMLAN_Send:  HMLAN1 S:S41D58AA1 stat:  00 t:00000000 d:01 r:41D58AA1 m:50 A258 B5B5B5 1C4E25 0000
2014.02.17 22:51:57.842 0: HMLAN_Parse: HMLAN1 R:R41D58AA1 stat:0008 t:00000000 d:FF r:7FFF     m:50 A258 B5B5B5 1C4E25 0000
2014.02.17 22:51:57.845 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.17 22:51:57.851 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:04E7303F d:FF r:FFB1     m:50 8202 1C4E25 B5B5B5 010100004E
2014.02.17 22:52:27.150 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.17 22:52:27.154 0: HMLAN_Send:  HMLAN1 S:S41D5FF4E stat:  00 t:00000000 d:01 r:41D5FF4E m:51 A258 B3B3B3 193A9A 002E
2014.02.17 22:52:27.721 0: HMLAN_Parse: HMLAN1 R:R41D5FF4E stat:0008 t:00000000 d:FF r:7FFF     m:51 A258 B3B3B3 193A9A 002E
2014.02.17 22:52:27.724 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.17 22:52:27.730 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:04E7A4FA d:FF r:FFB5     m:51 8202 193A9A B3B3B3 0101240049
2014.02.17 22:52:57.587 1: ----- NOTIFY ----- TCControler.Kueche state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:53:03.391 0: HMLAN_Send:  HMLAN1 S:S41D68CE2 stat:  00 t:00000000 d:01 r:41D68CE2 m:2F A258 B1B1B1 1BFC52 00FD
2014.02.17 22:53:03.957 0: HMLAN_Parse: HMLAN1 R:R41D68CE2 stat:0008 t:00000000 d:FF r:7FFF     m:2F A258 B1B1B1 1BFC52 00FD
2014.02.17 22:53:03.960 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.17 22:53:03.966 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:04E8328B d:FF r:FFB0     m:2F 8202 1BFC52 B1B1B1 0101C6004E
2014.02.17 22:53:20.555 0: Server shutdown
2014.02.17 22:53:20.594 4: CUL_send:  cul868X0 0     
2014.02.17 22:53:24.779 1: Including fhem.cfg
2014.02.17 22:53:30.011 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.17 22:53:30.067 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.17 22:53:41.644 1: Including ./log/fhem.save
2014.02.17 22:53:44.336 1: HCS BROETJE monitoring of devices started
2014.02.17 22:53:45.145 0: Server started with 362 defined entities (version $Id: fhem.pl 4935 2014-02-15 08:34:09Z rudolfkoenig $, os linux, user root, pid 2814)
2014.02.17 22:53:45.154 1: Perfmon: possible freeze starting at 22:53:27, delay is 18.153
2014.02.17 22:53:46.478 1: ----- NOTIFY ----- TCControler.SZ state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:53:46.568 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.17 22:53:51.541 1: Perfmon: possible freeze starting at 22:53:47, delay is 4.54
2014.02.17 22:53:51.654 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: init
2014.02.17 22:53:51.675 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.17 22:53:51.678 0: HMLAN_Send:  HMLAN1 S:S41D7497D stat:  00 t:00000000 d:01 r:41D7497D m:5C A258 B2B2B2 1DFC2F 0300
2014.02.17 22:53:51.981 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: init
2014.02.17 22:53:52.000 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.17 22:53:52.002 0: HMLAN_Send:  HMLAN1 S:S41D74AC2 stat:  00 t:00000000 d:01 r:41D74AC2 m:52 A258 B3B3B3 193A9A 032E
2014.02.17 22:53:52.313 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: init
2014.02.17 22:53:52.334 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.17 22:53:52.336 0: HMLAN_Send:  HMLAN1 S:S41D74C10 stat:  00 t:00000000 d:01 r:41D74C10 m:53 A258 B4B4B4 1CE9F5 0300
2014.02.17 22:53:52.655 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: init
2014.02.17 22:53:52.676 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.17 22:53:52.678 0: HMLAN_Send:  HMLAN1 S:S41D74D66 stat:  00 t:00000000 d:01 r:41D74D66 m:51 A258 B5B5B5 1C4E25 0300
2014.02.17 22:53:53.128 0: HMLAN_Parse: HMLAN1 R:R41D7497D stat:0008 t:00000000 d:FF r:7FFF     m:5C A258 B2B2B2 1DFC2F 0300
2014.02.17 22:53:53.131 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.17 22:53:53.134 0: HMLAN_Parse: HMLAN1 R:R41D74AC2 stat:0008 t:00000000 d:FF r:7FFF     m:52 A258 B3B3B3 193A9A 032E
2014.02.17 22:53:53.136 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.17 22:53:53.149 1: Perfmon: possible freeze starting at 22:53:52, delay is 1.148
2014.02.17 22:53:53.161 0: HMLAN_Parse: HMLAN1 R:R41D74C10 stat:0008 t:00000000 d:FF r:7FFF     m:53 A258 B4B4B4 1CE9F5 0300
2014.02.17 22:53:53.163 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.17 22:53:53.294 0: HMLAN_Parse: HMLAN1 R:R41D74D66 stat:0008 t:00000000 d:FF r:7FFF     m:51 A258 B5B5B5 1C4E25 0300
2014.02.17 22:53:53.296 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.17 22:54:01.734 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_1
2014.02.17 22:54:02.059 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_1
2014.02.17 22:54:02.393 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.17 22:54:02.735 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_1
2014.02.17 22:54:12.439 1: ----- NOTIFY ----- TCControler.Bad state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:54:14.037 0: HMLAN_Send:  HMLAN1 S:S41D7A0D7 stat:  00 t:00000000 d:01 r:41D7A0D7 m:52 A258 B5B5B5 1C4E25 0300
2014.02.17 22:54:15.047 1: ----- NOTIFY ----- TCControler.WZ state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:54:15.191 0: HMLAN_Parse: HMLAN1 R:R41D7A0D7 stat:0008 t:00000000 d:FF r:7FFF     m:52 A258 B5B5B5 1C4E25 0300
2014.02.17 22:54:15.195 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.17 22:54:24.099 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_2
2014.02.17 22:54:34.062 1: Perfmon: possible freeze starting at 22:54:33, delay is 1.062
2014.02.17 22:54:45.525 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: init
2014.02.17 22:54:45.562 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.17 22:54:45.564 0: HMLAN_Send:  HMLAN1 S:S41D81BFC stat:  00 t:00000000 d:01 r:41D81BFC m:30 A258 B1B1B1 1BFC52 03FD
2014.02.17 22:54:46.172 0: HMLAN_Parse: HMLAN1 R:R41D81BFC stat:0008 t:00000000 d:FF r:7FFF     m:30 A258 B1B1B1 1BFC52 03FD
2014.02.17 22:54:46.175 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.17 22:54:55.623 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_1
2014.02.17 22:55:03.649 0: HMLAN_Send:  HMLAN1 S:S41D862A3 stat:  00 t:00000000 d:01 r:41D862A3 m:53 A258 B3B3B3 193A9A 0335
2014.02.17 22:55:04.256 0: HMLAN_Parse: HMLAN1 R:R41D862A3 stat:0008 t:00000000 d:FF r:7FFF     m:53 A258 B3B3B3 193A9A 0335
2014.02.17 22:55:04.259 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.17 22:55:13.716 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_2
2014.02.17 22:55:17.378 1: ----- NOTIFY ----- TCControler.Kueche state:ready_to_send msgReduce:5 msgCtr:0
2014.02.17 22:55:36.423 0: HMLAN_Send:  HMLAN1 S:S41D8E2A9 stat:  00 t:00000000 d:01 r:41D8E2A9 m:54 A258 B4B4B4 1CE9F5 0300
2014.02.17 22:55:37.031 0: HMLAN_Parse: HMLAN1 R:R41D8E2A9 stat:0008 t:00000000 d:FF r:7FFF     m:54 A258 B4B4B4 1CE9F5 0300
2014.02.17 22:55:37.034 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.17 22:55:46.487 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_2
2014.02.17 22:55:52.642 0: HMLAN_Send:  HMLAN1 S:S41D92205 stat:  00 t:00000000 d:01 r:41D92205 m:31 A258 B1B1B1 1BFC52 03FD
2014.02.17 22:55:53.251 0: HMLAN_Parse: HMLAN1 R:R41D92205 stat:0008 t:00000000 d:FF r:7FFF     m:31 A258 B1B1B1 1BFC52 03FD
2014.02.17 22:55:53.254 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.17 22:56:02.706 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_2
2014.02.17 22:56:03.446 0: HMLAN_Send:  HMLAN1 S:S41D94C38 stat:  00 t:00000000 d:01 r:41D94C38 m:5D A258 B2B2B2 1DFC2F 0300
2014.02.17 22:56:04.196 0: HMLAN_Parse: HMLAN1 R:R41D94C38 stat:0008 t:00000000 d:FF r:7FFF     m:5D A258 B2B2B2 1DFC2F 0300
2014.02.17 22:56:04.199 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.17 22:56:13.510 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_2
2014.02.17 22:56:16.281 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.17 22:56:16.284 0: HMLAN_Send:  HMLAN1 S:S41D97E5C stat:  00 t:00000000 d:01 r:41D97E5C m:53 A258 B5B5B5 1C4E25 0000
2014.02.17 22:56:16.893 0: HMLAN_Parse: HMLAN1 R:R41D97E5C stat:0008 t:00000000 d:FF r:7FFF     m:53 A258 B5B5B5 1C4E25 0000
2014.02.17 22:56:16.898 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25

gruss frank

martinp876 · 18 Februar 2014, 10:20:01

Hallo Frank,

halten wir erst einmal fest, dass die Uebernahme der msgCnt aus dem statefile sauber funktioniert hat, so man ein arbeitsfaehiges Filesystem hat, und einen definierten reboot macht. Beruhigend.

So, jetzt hast du den log, der die do-schleife entfernt - hier werden sicher die Werte fuer B2 und B4 hochgerechnet. Es deuted also darauf hin, dass .msgCnt und .next bei den beiden ein Problem haben.
Koenntest du diese Logs wieder einbauen?
Evtl. liegt es an .next... mal sehen

Gruss Martin

frank · 18 Februar 2014, 10:59:47

hallo martin,

Zitathalten wir erst einmal fest, dass die Uebernahme der msgCnt aus dem statefile sauber funktioniert hat, so man ein arbeitsfaehiges Filesystem hat, und einen definierten reboot macht. Beruhigend.

eindeutiges jein! mit tendenz zu nein.

weil unterschiedliche versionen von 10culhm. die version war vom update-server. am nachmittag hatte ich 4939. ich habe dann noch diverse versionen rückwärts probiert, auch die vom nachmittag. und wahrscheinlich war da wieder das rücksetzen der zähler. aber egal.

jetzt bin ich bei svn von gestern abend. v4973. ubernahme aus statefile wohl ok. aber keine rettung.
konzentriere dich mal auf B1B1B101. die do-schleife wird 2 mal durchlaufen!!!
c195 müsste eigentlich schon der richtige wert sein. kurz vorm senden wird dann noch mal vorausberechnet???

Code Auswählen

2014.02.18 08:25:32.514 0: HMLAN_Send:  HMLAN1 S:S43E2AD22 stat:  00 t:00000000 d:01 r:43E2AD22 m:BD A258 B2B2B2 1DFC2F 030A
2014.02.18 08:25:32.898 0: HMLAN_Parse: HMLAN1 R:E1DFC2F   stat:0000 t:06F46411 d:FF r:FFC8     m:BD 8202 1DFC2F B2B2B2 0101001035
2014.02.18 08:25:33.078 0: HMLAN_Parse: HMLAN1 R:R43E2AD22 stat:0008 t:00000000 d:FF r:7FFF     m:BD A258 B2B2B2 1DFC2F 030A
2014.02.18 08:25:33.081 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.18 08:25:53.314 1: VD-test B4B4B401 c:194 t:1392708477303 x:124000
2014.02.18 08:25:53.320 0: HMLAN_Send:  HMLAN1 S:S43E2FE6A stat:  00 t:00000000 d:01 r:43E2FE6A m:C2 A258 B4B4B4 1CE9F5 0000
2014.02.18 08:25:53.504 0: HMLAN_Parse: HMLAN1 R:E1CE9F5   stat:0000 t:06F4B493 d:FF r:FFBA     m:C2 8202 1CE9F5 B4B4B4 0101000048
2014.02.18 08:25:53.885 0: HMLAN_Parse: HMLAN1 R:R43E2FE6A stat:0008 t:00000000 d:FF r:7FFF     m:C2 A258 B4B4B4 1CE9F5 0000
2014.02.18 08:25:53.887 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.18 08:25:55.120 1: ----- NOTIFY ----- TCControler.Bad state:send_disabled msgReduce:5 msgCtr:4
2014.02.18 08:26:46.563 1: VD-test B3B3B301 c:229 t:1392708554639 x:148250
2014.02.18 08:26:46.570 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.18 08:26:46.572 0: HMLAN_Send:  HMLAN1 S:S43E3CE6C stat:  00 t:00000000 d:01 r:43E3CE6C m:E5 A258 B3B3B3 193A9A 0042
2014.02.18 08:26:46.956 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:06F58565 d:FF r:FFB5     m:E5 8202 193A9A B3B3B3 0101340049
2014.02.18 08:26:47.136 0: HMLAN_Parse: HMLAN1 R:R43E3CE6C stat:0008 t:00000000 d:FF r:7FFF     m:E5 A258 B3B3B3 193A9A 0042
2014.02.18 08:26:47.139 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.18 08:26:52.805 1: VD-test B1B1B101 c:193 t:1392708538543 x:125750
2014.02.18 08:26:52.810 0: HMLAN_Send:  HMLAN1 S:S43E3E6CC stat:  00 t:00000000 d:01 r:43E3E6CC m:C1 A258 B1B1B1 1BFC52 00FD
2014.02.18 08:26:53.376 0: HMLAN_Parse: HMLAN1 R:R43E3E6CC stat:0008 t:00000000 d:FF r:7FFF     m:C1 A258 B1B1B1 1BFC52 00FD
2014.02.18 08:26:53.379 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.18 08:26:53.385 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:06F59E8E d:FF r:FFB5     m:C1 8202 1BFC52 B1B1B1 0101C6004A
2014.02.18 08:27:00.683 1: VD-test B5B5B501 c:41 t:1392708558163 x:137500
2014.02.18 08:27:00.696 0: HMLAN_Send:  HMLAN1 S:S43E4059A stat:  00 t:00000000 d:01 r:43E4059A m:29 A258 B5B5B5 1C4E25 0000
2014.02.18 08:27:00.962 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:06F5BBDA d:FF r:FFB3     m:29 8202 1C4E25 B5B5B5 010100004C
2014.02.18 08:27:01.276 0: HMLAN_Parse: HMLAN1 R:R43E4059A stat:0008 t:00000000 d:FF r:7FFF     m:29 A258 B5B5B5 1C4E25 0000
2014.02.18 08:27:01.280 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.18 08:27:31.624 1: ----- NOTIFY ----- TCControler.SZ state:send_disabled msgReduce:5 msgCtr:5
2014.02.18 08:27:53.125 1: Perfmon: possible freeze starting at 08:27:47, delay is 6.124
2014.02.18 08:27:55.141 1: ----- NOTIFY ----- TCControler.Kueche state:send_disabled msgReduce:5 msgCtr:5
2014.02.18 08:27:56.424 1: Perfmon: possible freeze starting at 08:27:54, delay is 2.423
2014.02.18 08:27:57.667 1: VD-test B4B4B401 c:195 t:1392708650813 x:173500
2014.02.18 08:27:57.688 0: HMLAN_Send:  HMLAN1 S:S43E4E439 stat:  00 t:00000000 d:01 r:43E4E439 m:C3 A258 B4B4B4 1CE9F5 0000
2014.02.18 08:27:58.370 0: HMLAN_Parse: HMLAN1 R:R43E4E439 stat:0008 t:00000000 d:FF r:7FFF     m:C3 A258 B4B4B4 1CE9F5 0000
2014.02.18 08:27:58.419 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.18 08:28:08.219 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.18 08:28:16.107 1: ----- NOTIFY ----- TCControler.WZ state:ready_to_send msgReduce:5 msgCtr:0
2014.02.18 08:28:16.854 1: Perfmon: possible freeze starting at 08:28:15, delay is 1.853
2014.02.18 08:28:18.765 1: VD-test B2B2B201 c:190 t:1392708650753 x:152000
2014.02.18 08:28:18.848 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.18 08:28:18.851 0: HMLAN_Send:  HMLAN1 S:S43E536E2 stat:  00 t:00000000 d:01 r:43E536E2 m:BE A258 B2B2B2 1DFC2F 000A
2014.02.18 08:28:19.424 0: HMLAN_Parse: HMLAN1 R:E1DFC2F   stat:0000 t:06F6EDEA d:FF r:FFC8     m:BE 8202 1DFC2F B2B2B2 0101080035
2014.02.18 08:28:20.680 0: HMLAN_Parse: HMLAN1 R:R43E536E2 stat:0008 t:00000000 d:FF r:7FFF     m:BE A258 B2B2B2 1DFC2F 000A
2014.02.18 08:28:20.730 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.18 08:28:20.826 1: Perfmon: possible freeze starting at 08:28:19, delay is 1.826
2014.02.18 08:28:24.308 1: Perfmon: possible freeze starting at 08:28:23, delay is 1.307
2014.02.18 08:28:36.169 1: Perfmon: possible freeze starting at 08:28:35, delay is 1.168
2014.02.18 08:28:40.032 1: Perfmon: possible freeze starting at 08:28:37, delay is 2.979
2014.02.18 08:32:50.438 1: Including fhem.cfg
2014.02.18 08:32:55.368 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.18 08:32:55.430 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.18 08:33:06.888 1: Including ./log/fhem.save
2014.02.18 08:33:09.427 1: HCS BROETJE monitoring of devices started
2014.02.18 08:33:09.825 0: Server started with 362 defined entities (version $Id: fhem.pl 4935 2014-02-15 08:34:09Z rudolfkoenig $, os linux, user root, pid 1751)
2014.02.18 08:33:09.832 1: Perfmon: possible freeze starting at 08:32:52, delay is 17.832
2014.02.18 08:33:10.674 1: ----- NOTIFY ----- TCControler.SZ state:ready_to_send msgReduce:5 msgCtr:0
2014.02.18 08:33:11.115 1: Perfmon: possible freeze starting at 08:33:10, delay is 1.114
2014.02.18 08:33:11.619 1: ----- NOTIFY ----- TCControler.Kueche state:ready_to_send msgReduce:5 msgCtr:0
2014.02.18 08:33:12.118 1: ----- NOTIFY ----- TCControler.WZ state:send_disabled msgReduce:5 msgCtr:1
2014.02.18 08:33:12.427 1: ----- NOTIFY ----- TCControler.Bad state:ready_to_send msgReduce:5 msgCtr:0
2014.02.18 08:33:14.492 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: restart
2014.02.18 08:33:14.696 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: restart
2014.02.18 08:33:15.040 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: restart
2014.02.18 08:33:15.244 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: restart
2014.02.18 08:33:15.413 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: restart
2014.02.18 08:33:15.440 1: Perfmon: possible freeze starting at 08:33:12, delay is 3.439
2014.02.18 08:33:15.957 1: ----- NOTIFY ----- TCControler.SZ state:send_disabled msgReduce:5 msgCtr:1
2014.02.18 08:33:16.042 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.18 08:33:16.426 1: VD-test B2B2B201 c:184 t:1392707694451 x:154000
2014.02.18 08:33:16.429 1: VD-test B2B2B201 c:185 t:1392707833951 x:139500
2014.02.18 08:33:16.431 1: VD-test B2B2B201 c:186 t:1392707959201 x:125250
2014.02.18 08:33:16.432 1: VD-test B2B2B201 c:187 t:1392708133951 x:174750
2014.02.18 08:33:16.435 1: VD-test B2B2B201 c:188 t:1392708294201 x:160250
2014.02.18 08:33:16.436 1: VD-test B2B2B201 c:189 t:1392708440201 x:146000
2014.02.18 08:33:16.438 1: VD-test B2B2B201 c:190 t:1392708571701 x:131500
2014.02.18 08:33:16.440 1: VD-test B2B2B201 c:191 t:1392708752701 x:181000
2014.02.18 08:33:16.442 1: VD-test B2B2B201 c:192 t:1392708919451 x:166750
2014.02.18 08:33:16.481 1: VD-test B4B4B401 c:189 t:1392707727250 x:146000
2014.02.18 08:33:16.483 1: VD-test B4B4B401 c:190 t:1392707858750 x:131500
2014.02.18 08:33:16.485 1: VD-test B4B4B401 c:191 t:1392708039750 x:181000
2014.02.18 08:33:16.487 1: VD-test B4B4B401 c:192 t:1392708206500 x:166750
2014.02.18 08:33:16.488 1: VD-test B4B4B401 c:193 t:1392708358750 x:152250
2014.02.18 08:33:16.490 1: VD-test B4B4B401 c:194 t:1392708496500 x:137750
2014.02.18 08:33:16.492 1: VD-test B4B4B401 c:195 t:1392708619750 x:123250
2014.02.18 08:33:16.495 1: VD-test B4B4B401 c:196 t:1392708792750 x:173000
2014.02.18 08:33:16.496 1: VD-test B4B4B401 c:197 t:1392708951250 x:158500
2014.02.18 08:33:16.535 1: VD-test B1B1B101 c:188 t:1392707791485 x:160250
2014.02.18 08:33:16.536 1: VD-test B1B1B101 c:189 t:1392707937485 x:146000
2014.02.18 08:33:16.538 1: VD-test B1B1B101 c:190 t:1392708068985 x:131500
2014.02.18 08:33:16.540 1: VD-test B1B1B101 c:191 t:1392708249985 x:181000
2014.02.18 08:33:16.542 1: VD-test B1B1B101 c:192 t:1392708416735 x:166750
2014.02.18 08:33:16.544 1: VD-test B1B1B101 c:193 t:1392708568985 x:152250
2014.02.18 08:33:16.546 1: VD-test B1B1B101 c:194 t:1392708706735 x:137750
2014.02.18 08:33:16.548 1: VD-test B1B1B101 c:195 t:1392708829985 x:123250
2014.02.18 08:33:16.588 1: VD-test B3B3B301 c:224 t:1392707793587 x:153000
2014.02.18 08:33:16.590 1: VD-test B3B3B301 c:225 t:1392707932337 x:138750
2014.02.18 08:33:16.593 1: VD-test B3B3B301 c:226 t:1392708056587 x:124250
2014.02.18 08:33:16.595 1: VD-test B3B3B301 c:227 t:1392708230337 x:173750
2014.02.18 08:33:16.597 1: VD-test B3B3B301 c:228 t:1392708389837 x:159500
2014.02.18 08:33:16.599 1: VD-test B3B3B301 c:229 t:1392708534837 x:145000
2014.02.18 08:33:16.601 1: VD-test B3B3B301 c:230 t:1392708665337 x:130500
2014.02.18 08:33:16.603 1: VD-test B3B3B301 c:231 t:1392708845337 x:180000
2014.02.18 08:33:16.643 1: VD-test B5B5B501 c:36 t:1392707821102 x:176750
2014.02.18 08:33:16.645 1: VD-test B5B5B501 c:37 t:1392707983352 x:162250
2014.02.18 08:33:16.647 1: VD-test B5B5B501 c:38 t:1392708131102 x:147750
2014.02.18 08:33:16.650 1: VD-test B5B5B501 c:39 t:1392708264602 x:133500
2014.02.18 08:33:16.651 1: VD-test B5B5B501 c:40 t:1392708447602 x:183000
2014.02.18 08:33:16.653 1: VD-test B5B5B501 c:41 t:1392708616102 x:168500
2014.02.18 08:33:16.655 1: VD-test B5B5B501 c:42 t:1392708770352 x:154250
2014.02.18 08:33:16.657 1: VD-test B5B5B501 c:43 t:1392708910102 x:139750
2014.02.18 08:33:16.819 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: init
2014.02.18 08:33:16.830 1: VD-test B2B2B201 c:193 t:1392707713201 x:172750
2014.02.18 08:33:16.832 1: VD-test B2B2B201 c:194 t:1392707871451 x:158250
2014.02.18 08:33:16.834 1: VD-test B2B2B201 c:195 t:1392708015201 x:143750
2014.02.18 08:33:16.835 1: VD-test B2B2B201 c:196 t:1392708144701 x:129500
2014.02.18 08:33:16.837 1: VD-test B2B2B201 c:197 t:1392708323701 x:179000
2014.02.18 08:33:16.839 1: VD-test B2B2B201 c:198 t:1392708488201 x:164500
2014.02.18 08:33:16.840 1: VD-test B2B2B201 c:199 t:1392708638201 x:150000
2014.02.18 08:33:16.842 1: VD-test B2B2B201 c:200 t:1392708773951 x:135750
2014.02.18 08:33:16.843 1: VD-test B2B2B201 c:201 t:1392708895201 x:121250
2014.02.18 08:33:17.123 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: init
2014.02.18 08:33:17.134 1: VD-test B3B3B301 c:232 t:1392707809587 x:169000
2014.02.18 08:33:17.135 1: VD-test B3B3B301 c:233 t:1392707964087 x:154500
2014.02.18 08:33:17.137 1: VD-test B3B3B301 c:234 t:1392708104337 x:140250
2014.02.18 08:33:17.139 1: VD-test B3B3B301 c:235 t:1392708230087 x:125750
2014.02.18 08:33:17.140 1: VD-test B3B3B301 c:236 t:1392708405337 x:175250
2014.02.18 08:33:17.142 1: VD-test B3B3B301 c:237 t:1392708566337 x:161000
2014.02.18 08:33:17.144 1: VD-test B3B3B301 c:238 t:1392708712837 x:146500
2014.02.18 08:33:17.145 1: VD-test B3B3B301 c:239 t:1392708844837 x:132000
2014.02.18 08:33:17.468 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: init
2014.02.18 08:33:17.481 1: VD-test B4B4B401 c:198 t:1392707711500 x:130250
2014.02.18 08:33:17.483 1: VD-test B4B4B401 c:199 t:1392707891250 x:179750
2014.02.18 08:33:17.484 1: VD-test B4B4B401 c:200 t:1392708056750 x:165500
2014.02.18 08:33:17.486 1: VD-test B4B4B401 c:201 t:1392708207750 x:151000
2014.02.18 08:33:17.488 1: VD-test B4B4B401 c:202 t:1392708344250 x:136500
2014.02.18 08:33:17.489 1: VD-test B4B4B401 c:203 t:1392708466500 x:122250
2014.02.18 08:33:17.491 1: VD-test B4B4B401 c:204 t:1392708638250 x:171750
2014.02.18 08:33:17.492 1: VD-test B4B4B401 c:205 t:1392708795500 x:157250
2014.02.18 08:33:17.494 1: VD-test B4B4B401 c:206 t:1392708938250 x:142750
2014.02.18 08:33:17.811 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: init
2014.02.18 08:33:17.823 1: VD-test B5B5B501 c:44 t:1392707802602 x:158250
2014.02.18 08:33:17.824 1: VD-test B5B5B501 c:45 t:1392707946602 x:144000
2014.02.18 08:33:17.826 1: VD-test B5B5B501 c:46 t:1392708076102 x:129500
2014.02.18 08:33:17.828 1: VD-test B5B5B501 c:47 t:1392708255102 x:179000
2014.02.18 08:33:17.829 1: VD-test B5B5B501 c:48 t:1392708419602 x:164500
2014.02.18 08:33:17.831 1: VD-test B5B5B501 c:49 t:1392708569852 x:150250
2014.02.18 08:33:17.832 1: VD-test B5B5B501 c:50 t:1392708705602 x:135750
2014.02.18 08:33:17.834 1: VD-test B5B5B501 c:51 t:1392708826852 x:121250
2014.02.18 08:33:17.992 1: Perfmon: possible freeze starting at 08:33:16, delay is 1.991
2014.02.18 08:33:19.344 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.18 08:33:19.347 0: HMLAN_Send:  HMLAN1 S:S43E9CCB0 stat:  00 t:00000000 d:01 r:43E9CCB0 m:33 A258 B5B5B5 1C4E25 0300
2014.02.18 08:33:19.633 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.18 08:33:19.636 0: HMLAN_Send:  HMLAN1 S:S43E9CDD3 stat:  00 t:00000000 d:01 r:43E9CDD3 m:EF A258 B3B3B3 193A9A 034C
2014.02.18 08:33:19.899 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.18 08:33:19.901 0: HMLAN_Send:  HMLAN1 S:S43E9CEDE stat:  00 t:00000000 d:01 r:43E9CEDE m:C9 A258 B2B2B2 1DFC2F 030A
2014.02.18 08:33:19.956 0: HMLAN_Parse: HMLAN1 R:R43E9CCB0 stat:0008 t:00000000 d:FF r:7FFF     m:33 A258 B5B5B5 1C4E25 0300
2014.02.18 08:33:19.958 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.18 08:33:20.164 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.18 08:33:20.167 0: HMLAN_Send:  HMLAN1 S:S43E9CFE6 stat:  00 t:00000000 d:01 r:43E9CFE6 m:CE A258 B4B4B4 1CE9F5 0300
2014.02.18 08:33:20.251 0: HMLAN_Parse: HMLAN1 R:R43E9CDD3 stat:0008 t:00000000 d:FF r:7FFF     m:EF A258 B3B3B3 193A9A 034C
2014.02.18 08:33:20.254 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.18 08:33:20.510 0: HMLAN_Parse: HMLAN1 R:R43E9CEDE stat:0008 t:00000000 d:FF r:7FFF     m:C9 A258 B2B2B2 1DFC2F 030A
2014.02.18 08:33:20.512 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.18 08:33:20.775 0: HMLAN_Parse: HMLAN1 R:R43E9CFE6 stat:0008 t:00000000 d:FF r:7FFF     m:CE A258 B4B4B4 1CE9F5 0300
2014.02.18 08:33:20.907 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.18 08:33:25.283 1: ----- NOTIFY ----- TCControler.WZ state:send_disabled msgReduce:5 msgCtr:2
2014.02.18 08:33:25.838 1: ----- NOTIFY ----- TCControler.Kueche state:send_disabled msgReduce:5 msgCtr:1
2014.02.18 08:33:26.899 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_1
2014.02.18 08:33:27.639 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_1
2014.02.18 08:33:27.715 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.18 08:33:27.892 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_1
2014.02.18 08:33:34.431 1: ----- NOTIFY ----- TCControler.Bad state:send_disabled msgReduce:5 msgCtr:1
2014.02.18 08:33:46.862 1: VD-test B5B5B501 c:52 t:1392708997852 x:171000
2014.02.18 08:33:46.870 0: HMLAN_Send:  HMLAN1 S:S43EA3838 stat:  00 t:00000000 d:01 r:43EA3838 m:34 A258 B5B5B5 1C4E25 0300
2014.02.18 08:33:47.478 0: HMLAN_Parse: HMLAN1 R:R43EA3838 stat:0008 t:00000000 d:FF r:7FFF     m:34 A258 B5B5B5 1C4E25 0300
2014.02.18 08:33:47.480 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.18 08:33:56.932 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_2
2014.02.18 08:34:04.846 1: VD-test B3B3B301 c:240 t:1392709026337 x:181500
2014.02.18 08:34:04.855 0: HMLAN_Send:  HMLAN1 S:S43EA7E79 stat:  00 t:00000000 d:01 r:43EA7E79 m:F0 A258 B3B3B3 193A9A 034C
2014.02.18 08:34:05.462 0: HMLAN_Parse: HMLAN1 R:R43EA7E79 stat:0008 t:00000000 d:FF r:7FFF     m:F0 A258 B3B3B3 193A9A 034C
2014.02.18 08:34:05.465 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.18 08:34:10.054 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: init
2014.02.18 08:34:10.066 1: VD-test B1B1B101 c:196 t:1392707777735 x:146500
2014.02.18 08:34:10.067 1: VD-test B1B1B101 c:197 t:1392707909735 x:132000
2014.02.18 08:34:10.069 1: VD-test B1B1B101 c:198 t:1392708091485 x:181750
2014.02.18 08:34:10.070 1: VD-test B1B1B101 c:199 t:1392708258735 x:167250
2014.02.18 08:34:10.072 1: VD-test B1B1B101 c:200 t:1392708411485 x:152750
2014.02.18 08:34:10.074 1: VD-test B1B1B101 c:201 t:1392708549985 x:138500
2014.02.18 08:34:10.076 1: VD-test B1B1B101 c:202 t:1392708673985 x:124000
2014.02.18 08:34:10.078 1: VD-test B1B1B101 c:203 t:1392708847485 x:173500
2014.02.18 08:34:10.079 1: VD-test B1B1B101 c:204 t:1392709006735 x:159250
2014.02.18 08:34:10.089 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.18 08:34:10.092 0: HMLAN_Send:  HMLAN1 S:S43EA92EB stat:  00 t:00000000 d:01 r:43EA92EB m:CC A258 B1B1B1 1BFC52 03FD
2014.02.18 08:34:11.343 0: HMLAN_Parse: HMLAN1 R:R43EA92EB stat:0008 t:00000000 d:FF r:7FFF     m:CC A258 B1B1B1 1BFC52 03FD
2014.02.18 08:34:11.348 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.18 08:34:11.366 1: Perfmon: possible freeze starting at 08:34:10, delay is 1.362
2014.02.18 08:34:14.916 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_2
2014.02.18 08:34:20.136 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_1
2014.02.18 08:34:49.613 1: ----- NOTIFY ----- TCControler.SZ state:send_disabled msgReduce:5 msgCtr:2
2014.02.18 08:34:55.211 1: VD-test B2B2B201 c:202 t:1392709065951 x:170750
2014.02.18 08:34:55.219 0: HMLAN_Send:  HMLAN1 S:S43EB4334 stat:  00 t:00000000 d:01 r:43EB4334 m:CA A258 B2B2B2 1DFC2F 030A
2014.02.18 08:34:55.826 0: HMLAN_Parse: HMLAN1 R:R43EB4334 stat:0008 t:00000000 d:FF r:7FFF     m:CA A258 B2B2B2 1DFC2F 030A
2014.02.18 08:34:55.829 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.18 08:35:05.279 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_2
2014.02.18 08:35:38.260 1: VD-test B4B4B401 c:207 t:1392709066750 x:128500
2014.02.18 08:35:38.268 0: HMLAN_Send:  HMLAN1 S:S43EBEB5E stat:  00 t:00000000 d:01 r:43EBEB5E m:CF A258 B4B4B4 1CE9F5 0300
2014.02.18 08:35:38.611 1: ----- NOTIFY ----- TCControler.Bad state:send_disabled msgReduce:5 msgCtr:2
2014.02.18 08:35:39.220 1: ----- NOTIFY ----- TCControler.WZ state:send_disabled msgReduce:5 msgCtr:3
2014.02.18 08:35:39.364 0: HMLAN_Parse: HMLAN1 R:R43EBEB5E stat:0008 t:00000000 d:FF r:7FFF     m:CF A258 B4B4B4 1CE9F5 0300
2014.02.18 08:35:39.367 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.18 08:35:48.330 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_2
2014.02.18 08:35:51.192 1: ----- NOTIFY ----- TCControler.Kueche state:send_disabled msgReduce:5 msgCtr:2
2014.02.18 08:36:00.033 1: Including fhem.cfg
2014.02.18 08:36:01.713 1: telnetPort: Can't open server port at 7072: Address already in use. Exiting.
2014.02.18 08:36:22.909 1: Perfmon: possible freeze starting at 08:36:21, delay is 1.908
2014.02.18 08:36:37.863 1: VD-test B5B5B501 c:53 t:1392709154352 x:156500
2014.02.18 08:36:37.872 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.18 08:36:37.874 0: HMLAN_Send:  HMLAN1 S:S43ECD432 stat:  00 t:00000000 d:01 r:43ECD432 m:35 A258 B5B5B5 1C4E25 0000
2014.02.18 08:36:38.481 0: HMLAN_Parse: HMLAN1 R:R43ECD432 stat:0008 t:00000000 d:FF r:7FFF     m:35 A258 B5B5B5 1C4E25 0000
2014.02.18 08:36:38.484 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.18 08:36:46.748 1: VD-test B1B1B101 c:205 t:1392709151485 x:144750
2014.02.18 08:36:46.806 0: HMLAN_Send:  HMLAN1 S:S43ECF716 stat:  00 t:00000000 d:01 r:43ECF716 m:CD A258 B1B1B1 1BFC52 03FD
2014.02.18 08:36:47.415 0: HMLAN_Parse: HMLAN1 R:R43ECF716 stat:0008 t:00000000 d:FF r:7FFF     m:CD A258 B1B1B1 1BFC52 03FD
2014.02.18 08:36:47.418 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.18 08:36:48.234 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_3
2014.02.18 08:36:57.007 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_2
2014.02.18 08:37:06.347 1: VD-test B3B3B301 c:241 t:1392709193587 x:167250
2014.02.18 08:37:06.356 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.18 08:37:06.359 0: HMLAN_Send:  HMLAN1 S:S43ED4376 stat:  00 t:00000000 d:01 r:43ED4376 m:F1 A258 B3B3B3 193A9A 004C
2014.02.18 08:37:06.967 0: HMLAN_Parse: HMLAN1 R:R43ED4376 stat:0008 t:00000000 d:FF r:7FFF     m:F1 A258 B3B3B3 193A9A 004C
2014.02.18 08:37:06.970 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.18 08:37:16.418 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_3
2014.02.18 08:37:45.962 1: VD-test B2B2B201 c:203 t:1392709222451 x:156500
2014.02.18 08:37:45.970 0: HMLAN_Send:  HMLAN1 S:S43EDDE34 stat:  00 t:00000000 d:01 r:43EDDE34 m:CB A258 B2B2B2 1DFC2F 0311
2014.02.18 08:37:46.576 0: HMLAN_Parse: HMLAN1 R:R43EDDE34 stat:0008 t:00000000 d:FF r:7FFF     m:CB A258 B2B2B2 1DFC2F 0311
2014.02.18 08:37:46.579 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.18 08:37:46.760 1: VD-test B4B4B401 c:208 t:1392709244750 x:178000
2014.02.18 08:37:46.768 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.18 08:37:46.770 0: HMLAN_Send:  HMLAN1 S:S43EDE152 stat:  00 t:00000000 d:01 r:43EDE152 m:D0 A258 B4B4B4 1CE9F5 0000
2014.02.18 08:37:47.377 0: HMLAN_Parse: HMLAN1 R:R43EDE152 stat:0008 t:00000000 d:FF r:7FFF     m:D0 A258 B4B4B4 1CE9F5 0000
2014.02.18 08:37:47.380 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.18 08:37:50.761 1: ----- NOTIFY ----- TCControler.SZ state:send_disabled msgReduce:5 msgCtr:3
2014.02.18 08:37:56.033 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_3
2014.02.18 08:37:56.831 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_3
2014.02.18 08:38:00.874 1: Perfmon: possible freeze starting at 08:37:59, delay is 1.872
2014.02.18 08:38:01.795 1: ----- NOTIFY ----- TCControler.Kueche state:send_disabled msgReduce:5 msgCtr:3
2014.02.18 08:38:32.350 1: ----- NOTIFY ----- TCControler.Bad state:send_disabled msgReduce:5 msgCtr:3
2014.02.18 08:38:42.275 1: ----- NOTIFY ----- TCControler.WZ state:send_disabled msgReduce:5 msgCtr:4
2014.02.18 08:39:11.499 1: VD-test B1B1B101 c:206 t:1392709281735 x:130250
2014.02.18 08:39:11.510 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.18 08:39:11.515 0: HMLAN_Send:  HMLAN1 S:S43EF2C58 stat:  00 t:00000000 d:01 r:43EF2C58 m:CE A258 B1B1B1 1BFC52 00FD
2014.02.18 08:39:12.123 0: HMLAN_Parse: HMLAN1 R:R43EF2C58 stat:0008 t:00000000 d:FF r:7FFF     m:CE A258 B1B1B1 1BFC52 00FD
2014.02.18 08:39:12.126 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.18 08:39:14.362 1: VD-test B5B5B501 c:54 t:1392709296352 x:142000
2014.02.18 08:39:14.370 0: HMLAN_Send:  HMLAN1 S:S43EF3784 stat:  00 t:00000000 d:01 r:43EF3784 m:36 A258 B5B5B5 1C4E25 0000
2014.02.18 08:39:14.978 0: HMLAN_Parse: HMLAN1 R:R43EF3784 stat:0008 t:00000000 d:FF r:7FFF     m:36 A258 B5B5B5 1C4E25 0000
2014.02.18 08:39:14.981 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.18 08:39:21.600 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_3
2014.02.18 08:39:24.434 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_4
2014.02.18 08:39:53.597 1: VD-test B3B3B301 c:242 t:1392709346337 x:152750
2014.02.18 08:39:53.605 0: HMLAN_Send:  HMLAN1 S:S43EFD0C8 stat:  00 t:00000000 d:01 r:43EFD0C8 m:F2 A258 B3B3B3 193A9A 004C
2014.02.18 08:39:54.212 0: HMLAN_Parse: HMLAN1 R:R43EFD0C8 stat:0008 t:00000000 d:FF r:7FFF     m:F2 A258 B3B3B3 193A9A 004C
2014.02.18 08:39:54.214 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.18 08:40:03.669 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_4
2014.02.18 08:40:22.466 1: VD-test B2B2B201 c:204 t:1392709364451 x:142000
2014.02.18 08:40:22.478 0: HMLAN_Send:  HMLAN1 S:S43F04190 stat:  00 t:00000000 d:01 r:43F04190 m:CC A258 B2B2B2 1DFC2F 031C
2014.02.18 08:40:23.088 0: HMLAN_Parse: HMLAN1 R:R43F04190 stat:0008 t:00000000 d:FF r:7FFF     m:CC A258 B2B2B2 1DFC2F 031C
2014.02.18 08:40:23.091 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.18 08:40:32.568 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_4

gruss frank

martinp876 · 18 Februar 2014, 11:39:09

Hi Frank,

Zitatweil unterschiedliche versionen von 10culhm

du machst mich fertig. Da ist es verdammt schwer zu folgen.

Zitatubernahme aus statefile wohl ok.

na das war doch das erste Problem von gestern - also doch ja

Zitataber keine rettung.

das ist dann das 2. Problem - eins nachdem anderen

Zitatdie do-schleife wird 2 mal durchlaufen!!!

das war gestern schon so - war aber 2.rangig, da erst sein statefile-problem geloeset werden musste.

Der Hinweis ist sicher wichtig - und passiert bei fast allen.
Zu beachten ist, dass
- der Aufruf 54 sekunden auseinander liegt - eine Zeit, die so nicht vorkommt
- der msgCnt weiter gezaehlt wird
- der .next noch vom alten wert gelesen wird
=> und hier liegt das Problem: next und msgCnt sind nicht synchron

Das bringt mich zu meiner urspruenglichen implementierung zurueck (die du geaendert haben wolltest...) in der
$hashVd->{next} in CUL_HM_valvePosUpdt(@) erst einmal auf $hashVd->{nextF} gesetzt wird. In CUL_HM_valvePosTmr(@) wird es dann auf $hashVd->{nextF} korrigiert wird, so wir ein ack erhalten.

Gruss Martin

frank · 18 Februar 2014, 17:06:16

hallo martin,

ZitatDas bringt mich zu meiner urspruenglichen implementierung zurueck (die du geaendert haben wolltest...)

ich hätte es nur gerne wieder so gehabt, wie es vorher schon einmal gewesen war.

in v4973 macht glaube ich auch der umstand probleme, dass CUL_HM_valvePosUpdt nach restart sowohl von culhmconfig als auch von culhmset aufgerufen werden. da gibt es dann wohl konkurenz.

gruss frank

frank · 19 Februar 2014, 12:08:24

hallo martin,

sollten die grösseren umbaumassnahmen in v4989 mit einer geplanten verbesserung der rettung der vd nach restart in zusammenhang stehen, müssen dich meine vd leider enttäuschen.

Code Auswählen

2014.02.19 11:20:21.244 0: HMLAN_Send:  HMLAN1 S:S49A914BE stat:  00 t:00000000 d:01 r:49A914BE m:67 A258 B2B2B2 1DFC2F 0302
2014.02.19 11:20:21.432 0: HMLAN_Parse: HMLAN1 R:E1DFC2F   stat:0000 t:0CBAFDD2 d:FF r:FFC9     m:67 8202 1DFC2F B2B2B2 0101000034
2014.02.19 11:20:21.810 0: HMLAN_Parse: HMLAN1 R:R49A914BE stat:0008 t:00000000 d:FF r:7FFF     m:67 A258 B2B2B2 1DFC2F 0302
2014.02.19 11:20:21.814 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 11:21:36.182 0: HMLAN_Send:  HMLAN1 S:S49AA3978 stat:  00 t:00000000 d:01 r:49AA3978 m:75 A258 B1B1B1 1BFC52 00FD
2014.02.19 11:21:36.369 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:0CBC2298 d:FF r:FFB7     m:75 8202 1BFC52 B1B1B1 0101C60047
2014.02.19 11:21:36.749 0: HMLAN_Parse: HMLAN1 R:R49AA3978 stat:0008 t:00000000 d:FF r:7FFF     m:75 A258 B1B1B1 1BFC52 00FD
2014.02.19 11:21:36.752 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 11:21:42.246 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.19 11:21:42.250 0: HMLAN_Send:  HMLAN1 S:S49AA5128 stat:  00 t:00000000 d:01 r:49AA5128 m:97 A258 B3B3B3 193A9A 0040
2014.02.19 11:21:42.435 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:0CBC3A4C d:FF r:FFB6     m:97 8202 193A9A B3B3B3 0101320048
2014.02.19 11:21:42.816 0: HMLAN_Parse: HMLAN1 R:R49AA5128 stat:0008 t:00000000 d:FF r:7FFF     m:97 A258 B3B3B3 193A9A 0040
2014.02.19 11:21:42.819 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 11:22:31.368 0: HMLAN_Send:  HMLAN1 S:S49AB110A stat:  00 t:00000000 d:01 r:49AB110A m:53 A258 B4B4B4 1CE9F5 03AE
2014.02.19 11:22:31.553 0: HMLAN_Parse: HMLAN1 R:E1CE9F5   stat:0000 t:0CBCFA2F d:FF r:FFBC     m:53 8202 1CE9F5 B4B4B4 0101902045
2014.02.19 11:22:31.933 0: HMLAN_Parse: HMLAN1 R:R49AB110A stat:0008 t:00000000 d:FF r:7FFF     m:53 A258 B4B4B4 1CE9F5 03AE
2014.02.19 11:22:31.939 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 11:22:39.016 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.19 11:22:39.020 0: HMLAN_Send:  HMLAN1 S:S49AB2EEA stat:  00 t:00000000 d:01 r:49AB2EEA m:E8 A258 B5B5B5 1C4E25 0000
2014.02.19 11:22:39.202 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:0CBD1812 d:FF r:FFB2     m:E8 8202 1C4E25 B5B5B5 010100004D
2014.02.19 11:22:39.585 0: HMLAN_Parse: HMLAN1 R:R49AB2EEA stat:0008 t:00000000 d:FF r:7FFF     m:E8 A258 B5B5B5 1C4E25 0000
2014.02.19 11:22:39.590 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 11:24:36.375 0: HMLAN_Send:  HMLAN1 S:S49ACF959 stat:  00 t:00000000 d:01 r:49ACF959 m:54 A258 B4B4B4 1CE9F5 03B0
2014.02.19 11:24:36.560 0: HMLAN_Parse: HMLAN1 R:E1CE9F5   stat:0000 t:0CBEE28F d:FF r:FFBC     m:54 8202 1CE9F5 B4B4B4 0101881045
2014.02.19 11:24:36.940 0: HMLAN_Parse: HMLAN1 R:R49ACF959 stat:0008 t:00000000 d:FF r:7FFF     m:54 A258 B4B4B4 1CE9F5 03B0
2014.02.19 11:24:36.943 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5


2014.02.19 11:24:43.916 0: Server shutdown
2014.02.19 11:24:48.277 1: Including fhem.cfg
2014.02.19 11:24:53.674 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.19 11:24:53.732 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.19 11:25:04.604 1: Including ./log/fhem.save
2014.02.19 11:25:06.705 1: HCS BROETJE monitoring of devices started
2014.02.19 11:25:08.430 0: Server started with 298 defined entities (version $Id: fhem.pl 4935 2014-02-15 08:34:09Z rudolfkoenig $, os linux, user root, pid 6603)
2014.02.19 11:25:09.225 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.19 11:25:11.399 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: restart
2014.02.19 11:25:11.571 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: restart
2014.02.19 11:25:11.735 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: restart
2014.02.19 11:25:11.968 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: restart
2014.02.19 11:25:12.233 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: restart
2014.02.19 11:25:15.192 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: init
2014.02.19 11:25:15.222 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.19 11:25:15.226 0: HMLAN_Send:  HMLAN1 S:S49AD9118 stat:  00 t:00000000 d:01 r:49AD9118 m:68 A258 B2B2B2 1DFC2F 030A
2014.02.19 11:25:15.539 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: init
2014.02.19 11:25:15.558 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.19 11:25:15.561 0: HMLAN_Send:  HMLAN1 S:S49AD9268 stat:  00 t:00000000 d:01 r:49AD9268 m:98 A258 B3B3B3 193A9A 0347
2014.02.19 11:25:15.898 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: init
2014.02.19 11:25:15.918 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.19 11:25:15.921 0: HMLAN_Send:  HMLAN1 S:S49AD93D1 stat:  00 t:00000000 d:01 r:49AD93D1 m:54 A258 B4B4B4 1CE9F5 03B0
2014.02.19 11:25:16.230 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: init
2014.02.19 11:25:16.250 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.19 11:25:16.253 0: HMLAN_Send:  HMLAN1 S:S49AD951C stat:  00 t:00000000 d:01 r:49AD951C m:E8 A258 B5B5B5 1C4E25 0300
2014.02.19 11:25:17.081 0: HMLAN_Parse: HMLAN1 R:R49AD9118 stat:0008 t:00000000 d:FF r:7FFF     m:68 A258 B2B2B2 1DFC2F 030A
2014.02.19 11:25:17.084 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 11:25:17.088 0: HMLAN_Parse: HMLAN1 R:R49AD9268 stat:0008 t:00000000 d:FF r:7FFF     m:98 A258 B3B3B3 193A9A 0347
2014.02.19 11:25:17.090 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 11:25:17.093 0: HMLAN_Parse: HMLAN1 R:R49AD93D1 stat:0008 t:00000000 d:FF r:7FFF     m:54 A258 B4B4B4 1CE9F5 03B0
2014.02.19 11:25:17.096 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 11:25:17.098 0: HMLAN_Parse: HMLAN1 R:R49AD951C stat:0008 t:00000000 d:FF r:7FFF     m:E8 A258 B5B5B5 1C4E25 0300
2014.02.19 11:25:17.101 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 11:25:25.282 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_1
2014.02.19 11:25:26.872 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_1
2014.02.19 11:25:26.944 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.19 11:25:27.016 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_1
2014.02.19 11:25:27.055 0: HMLAN_Send:  HMLAN1 S:S49ADBF51 stat:  00 t:00000000 d:01 r:49ADBF51 m:E9 A258 B5B5B5 1C4E25 0300
2014.02.19 11:25:27.779 0: HMLAN_Parse: HMLAN1 R:R49ADBF51 stat:0008 t:00000000 d:FF r:7FFF     m:E9 A258 B5B5B5 1C4E25 0300
2014.02.19 11:25:27.781 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 11:25:37.118 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_2
2014.02.19 11:25:47.504 0: HMLAN_Send:  HMLAN1 S:S49AE0F31 stat:  00 t:00000000 d:01 r:49AE0F31 m:69 A258 B2B2B2 1DFC2F 030A
2014.02.19 11:25:48.112 0: HMLAN_Parse: HMLAN1 R:R49AE0F31 stat:0008 t:00000000 d:FF r:7FFF     m:69 A258 B2B2B2 1DFC2F 030A
2014.02.19 11:25:48.115 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 11:25:57.566 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_2
2014.02.19 11:26:08.682 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: init
2014.02.19 11:26:08.721 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.19 11:26:08.723 0: HMLAN_Send:  HMLAN1 S:S49AE6213 stat:  00 t:00000000 d:01 r:49AE6213 m:76 A258 B1B1B1 1BFC52 03FD
2014.02.19 11:26:09.330 0: HMLAN_Parse: HMLAN1 R:R49AE6213 stat:0008 t:00000000 d:FF r:7FFF     m:76 A258 B1B1B1 1BFC52 03FD
2014.02.19 11:26:09.334 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 11:26:18.782 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_1
2014.02.19 11:26:42.691 0: HMLAN_Send:  HMLAN1 S:S49AEE6C6 stat:  00 t:00000000 d:01 r:49AEE6C6 m:77 A258 B1B1B1 1BFC52 03FD
2014.02.19 11:26:43.515 0: HMLAN_Parse: HMLAN1 R:R49AEE6C6 stat:0008 t:00000000 d:FF r:7FFF     m:77 A258 B1B1B1 1BFC52 03FD
2014.02.19 11:26:43.518 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 11:26:52.753 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_2
2014.02.19 11:26:54.478 0: HMLAN_Send:  HMLAN1 S:S49AF14D0 stat:  00 t:00000000 d:01 r:49AF14D0 m:55 A258 B4B4B4 1CE9F5 03A6
2014.02.19 11:26:55.086 0: HMLAN_Parse: HMLAN1 R:R49AF14D0 stat:0008 t:00000000 d:FF r:7FFF     m:55 A258 B4B4B4 1CE9F5 03A6
2014.02.19 11:26:55.090 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 11:27:04.540 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_2
2014.02.19 11:27:25.016 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.19 11:27:25.019 0: HMLAN_Send:  HMLAN1 S:S49AF8C1A stat:  00 t:00000000 d:01 r:49AF8C1A m:E9 A258 B5B5B5 1C4E25 0000
2014.02.19 11:27:25.626 0: HMLAN_Parse: HMLAN1 R:R49AF8C1A stat:0008 t:00000000 d:FF r:7FFF     m:E9 A258 B5B5B5 1C4E25 0000
2014.02.19 11:27:25.628 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 11:27:31.755 0: HMLAN_Send:  HMLAN1 S:S49AFA66D stat:  00 t:00000000 d:01 r:49AFA66D m:99 A258 B3B3B3 193A9A 034F
2014.02.19 11:27:32.362 0: HMLAN_Parse: HMLAN1 R:R49AFA66D stat:0008 t:00000000 d:FF r:7FFF     m:99 A258 B3B3B3 193A9A 034F
2014.02.19 11:27:32.366 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 11:27:35.072 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_3
2014.02.19 11:27:35.120 0: HMLAN_Send:  HMLAN1 S:S49AFB392 stat:  00 t:00000000 d:01 r:49AFB392 m:EA A258 B5B5B5 1C4E25 0000
2014.02.19 11:27:35.727 0: HMLAN_Parse: HMLAN1 R:R49AFB392 stat:0008 t:00000000 d:FF r:7FFF     m:EA A258 B5B5B5 1C4E25 0000
2014.02.19 11:27:35.730 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 11:27:41.821 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_2
2014.02.19 11:27:45.182 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_4
2014.02.19 11:28:29.515 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.19 11:28:29.518 0: HMLAN_Send:  HMLAN1 S:S49B0880D stat:  00 t:00000000 d:01 r:49B0880D m:6A A258 B2B2B2 1DFC2F 000A
2014.02.19 11:28:30.128 0: HMLAN_Parse: HMLAN1 R:R49B0880D stat:0008 t:00000000 d:FF r:7FFF     m:6A A258 B2B2B2 1DFC2F 000A
2014.02.19 11:28:30.132 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 11:28:39.640 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_3
2014.02.19 11:29:31.943 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.19 11:29:31.946 0: HMLAN_Send:  HMLAN1 S:S49B17BE8 stat:  00 t:00000000 d:01 r:49B17BE8 m:78 A258 B1B1B1 1BFC52 00FD
2014.02.19 11:29:32.553 0: HMLAN_Parse: HMLAN1 R:R49B17BE8 stat:0008 t:00000000 d:FF r:7FFF     m:78 A258 B1B1B1 1BFC52 00FD
2014.02.19 11:29:32.557 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 11:29:34.374 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.19 11:29:34.377 0: HMLAN_Send:  HMLAN1 S:S49B18568 stat:  00 t:00000000 d:01 r:49B18568 m:56 A258 B4B4B4 1CE9F5 00A6
2014.02.19 11:29:34.984 0: HMLAN_Parse: HMLAN1 R:R49B18568 stat:0008 t:00000000 d:FF r:7FFF     m:56 A258 B4B4B4 1CE9F5 00A6
2014.02.19 11:29:34.986 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 11:29:42.035 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_3
2014.02.19 11:29:44.437 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_3
2014.02.19 11:30:08.256 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.19 11:30:08.258 0: HMLAN_Send:  HMLAN1 S:S49B209C1 stat:  00 t:00000000 d:01 r:49B209C1 m:9A A258 B3B3B3 193A9A 004F
2014.02.19 11:30:08.870 0: HMLAN_Parse: HMLAN1 R:R49B209C1 stat:0008 t:00000000 d:FF r:7FFF     m:9A A258 B3B3B3 193A9A 004F
2014.02.19 11:30:08.873 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 11:30:14.980 0: HMLAN_Send:  HMLAN1 S:S49B22406 stat:  00 t:00000000 d:01 r:49B22406 m:EB A258 B5B5B5 1C4E25 0000
2014.02.19 11:30:15.650 0: HMLAN_Parse: HMLAN1 R:R49B22406 stat:0008 t:00000000 d:FF r:7FFF     m:EB A258 B5B5B5 1C4E25 0000
2014.02.19 11:30:15.653 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 11:30:18.319 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_3
2014.02.19 11:30:25.043 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_5
2014.02.19 11:30:57.005 0: HMLAN_Send:  HMLAN1 S:S49B2C82F stat:  00 t:00000000 d:01 r:49B2C82F m:6B A258 B2B2B2 1DFC2F 000A
2014.02.19 11:30:57.612 0: HMLAN_Parse: HMLAN1 R:R49B2C82F stat:0008 t:00000000 d:FF r:7FFF     m:6B A258 B2B2B2 1DFC2F 000A
2014.02.19 11:30:57.616 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 11:31:07.070 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_4
2014.02.19 11:32:00.130 0: HMLAN_Send:  HMLAN1 S:S49B3BEC3 stat:  00 t:00000000 d:01 r:49B3BEC3 m:57 A258 B4B4B4 1CE9F5 00A6
2014.02.19 11:32:00.738 0: HMLAN_Parse: HMLAN1 R:R49B3BEC3 stat:0008 t:00000000 d:FF r:7FFF     m:57 A258 B4B4B4 1CE9F5 00A6
2014.02.19 11:32:00.741 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 11:32:06.691 0: HMLAN_Send:  HMLAN1 S:S49B3D865 stat:  00 t:00000000 d:01 r:49B3D865 m:79 A258 B1B1B1 1BFC52 00FD
2014.02.19 11:32:07.298 0: HMLAN_Parse: HMLAN1 R:R49B3D865 stat:0008 t:00000000 d:FF r:7FFF     m:79 A258 B1B1B1 1BFC52 00FD
2014.02.19 11:32:07.301 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 11:32:10.224 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_4
2014.02.19 11:32:16.756 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_4

msgnummern und/oder eventzeitpunkte passen nicht. => alle vd verstorben.

gruss frank

martinp876 · 19 Februar 2014, 12:42:15

Hallo Frank,

hm - hatte gestern bei mir funktioniert - hatte aber nur einen Versuch.
wie du sicher gesehen hast ist es der Zaehler. Nachdem eine message ausgelassen wurde muss die erste nach restart um 2 hoeher sein, ist es aber nicht.
somit muss das "-1" raus.
else{#successful - store sendtime and msgCnt that calculated it
CUL_HM_UpdtReadBulk($hash,0,".next:".$hashVd->{nextL}
# ,".msgCnt:".($hashVd->{msgCnt}-1));
,".msgCnt:".($hashVd->{msgCnt}));

ich kann es bestenfalls heute Abend testen. Falls du logs sendest waere der in der while schleife hilfreich.
Ein doppelter Aurfuf macht durch die While anstelle von until keine Probleme mehr.
nextL(astSend) sollte besser lesbar verarbeitet werden koennen.

Gruss Martin

frank · 19 Februar 2014, 15:52:31

hallo martin,

anbei noch mal ein log mit zusätzlichen logeinträgen (ende while-schleife, einsprung restart, einsprung init).

Code Auswählen

2014.02.19 13:41:35.192 0: HMLAN_Send:  HMLAN1 S:S4A2A621A stat:  00 t:00000000 d:01 r:4A2A621A m:8A A258 B4B4B4 1CE9F5 03B3
2014.02.19 13:41:35.758 0: HMLAN_Parse: HMLAN1 R:R4A2A621A stat:0008 t:00000000 d:FF r:7FFF     m:8A A258 B4B4B4 1CE9F5 03B3
2014.02.19 13:41:35.761 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 13:41:35.766 0: HMLAN_Parse: HMLAN1 R:E1CE9F5   stat:0000 t:0D3C5136 d:FF r:FFC3     m:8A 8202 1CE9F5 B4B4B4 010186103F
2014.02.19 13:41:49.173 0: HMLAN_Send:  HMLAN1 S:S4A2A98B8 stat:  00 t:00000000 d:01 r:4A2A98B8 m:CE A258 B3B3B3 193A9A 0042
2014.02.19 13:41:49.558 0: HMLAN_Parse: HMLAN1 R:E193A9A   stat:0000 t:0D3C870D d:FF r:FFB4     m:CE 8202 193A9A B3B3B3 010134004A
2014.02.19 13:41:49.739 0: HMLAN_Parse: HMLAN1 R:R4A2A98B8 stat:0008 t:00000000 d:FF r:7FFF     m:CE A258 B3B3B3 193A9A 0042
2014.02.19 13:41:49.741 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 13:41:55.637 0: HMLAN_Send:  HMLAN1 S:S4A2AB1F7 stat:  00 t:00000000 d:01 r:4A2AB1F7 m:1F A258 B5B5B5 1C4E25 0000
2014.02.19 13:41:56.019 0: HMLAN_Parse: HMLAN1 R:E1C4E25   stat:0000 t:0D3CA04A d:FF r:FFB2     m:1F 8202 1C4E25 B5B5B5 010100004D
2014.02.19 13:41:56.199 0: HMLAN_Parse: HMLAN1 R:R4A2AB1F7 stat:0008 t:00000000 d:FF r:7FFF     m:1F A258 B5B5B5 1C4E25 0000
2014.02.19 13:41:56.203 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 13:43:54.070 0: HMLAN_Send:  HMLAN1 S:S4A2C8099 stat:  00 t:00000000 d:01 r:4A2C8099 m:AD A258 B1B1B1 1BFC52 00FD
2014.02.19 13:43:54.257 0: HMLAN_Parse: HMLAN1 R:E1BFC52   stat:0000 t:0D3E6E37 d:FF r:FFB6     m:AD 8202 1BFC52 B1B1B1 0101C60049
2014.02.19 13:43:54.636 0: HMLAN_Parse: HMLAN1 R:R4A2C8099 stat:0008 t:00000000 d:FF r:7FFF     m:AD A258 B1B1B1 1BFC52 00FD
2014.02.19 13:43:54.639 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 13:44:14.657 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.19 13:44:14.660 0: HMLAN_Send:  HMLAN1 S:S4A2CD103 stat:  00 t:00000000 d:01 r:4A2CD103 m:9F A258 B2B2B2 1DFC2F 0323
2014.02.19 13:44:15.044 0: HMLAN_Parse: HMLAN1 R:E1DFC2F   stat:0000 t:0D3EBF6D d:FF r:FFC9     m:9F 8202 1DFC2F B2B2B2 0101161034
2014.02.19 13:44:15.243 0: HMLAN_Parse: HMLAN1 R:R4A2CD103 stat:0008 t:00000000 d:FF r:7FFF     m:9F A258 B2B2B2 1DFC2F 0323
2014.02.19 13:44:15.246 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F


2014.02.19 13:44:15.749 0: Server shutdown
2014.02.19 13:44:19.786 1: Including fhem.cfg
2014.02.19 13:44:24.098 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.19 13:44:24.151 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.19 13:44:35.428 1: Including ./log/fhem.save
2014.02.19 13:44:37.842 1: HCS BROETJE monitoring of devices started
2014.02.19 13:44:39.291 0: Server started with 298 defined entities (version $Id: fhem.pl 4935 2014-02-15 08:34:09Z rudolfkoenig $, os linux, user root, pid 7585)
2014.02.19 13:44:39.905 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.19 13:44:41.874 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: restart
2014.02.19 13:44:41.885 1: ##### restart ##### n:VentilControler.AZ.Nord_Btn1 c:30 t:1392813715631
2014.02.19 13:44:42.043 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: restart
2014.02.19 13:44:42.054 1: ##### restart ##### n:VentilControler.Bad_Btn1 c:205 t:1392813709167
2014.02.19 13:44:42.221 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: restart
2014.02.19 13:44:42.232 1: ##### restart ##### n:VentilControler.Kueche_Btn1 c:172 t:1392813834063
2014.02.19 13:44:42.388 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: restart
2014.02.19 13:44:42.400 1: ##### restart ##### n:VentilControler.SZ_Btn1 c:157 t:1392813689139
2014.02.19 13:44:42.561 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: restart
2014.02.19 13:44:42.571 1: ##### restart ##### n:VentilControler.WZ_Btn1 c:137 t:1392813695183
2014.02.19 13:44:46.616 1: VD-test B2B2B201 c:158 t:1392813834139 x:145000
2014.02.19 13:44:46.618 1: VD-test B2B2B201 c:159 t:1392813964639 x:130500
2014.02.19 13:44:46.658 1: VD-test B4B4B401 c:138 t:1392813872683 x:177500
2014.02.19 13:44:46.660 1: VD-test B4B4B401 c:139 t:1392814035683 x:163000
2014.02.19 13:44:46.700 1: VD-test B3B3B301 c:206 t:1392813865917 x:156750
2014.02.19 13:44:46.702 1: VD-test B3B3B301 c:207 t:1392814008167 x:142250
2014.02.19 13:44:46.742 1: VD-test B5B5B501 c:31 t:1392813836381 x:120750
2014.02.19 13:44:46.744 1: VD-test B5B5B501 c:32 t:1392814006631 x:170250
2014.02.19 13:44:46.785 1: VD-test B1B1B101 c:173 t:1392813954813 x:120750
2014.02.19 13:44:46.826 1: Perfmon: possible freeze starting at 13:44:40, delay is 6.825
2014.02.19 13:44:46.946 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: init
2014.02.19 13:44:46.957 1: ##### init ##### n:VentilControler.SZ_Btn1 c:159 t:1392813964639
2014.02.19 13:44:46.968 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.19 13:44:46.971 0: HMLAN_Send:  HMLAN1 S:S4A2D4F3A stat:  00 t:00000000 d:01 r:4A2D4F3A m:9F A258 B2B2B2 1DFC2F 0323
2014.02.19 13:44:47.259 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: init
2014.02.19 13:44:47.270 1: ##### init ##### n:VentilControler.Bad_Btn1 c:207 t:1392814008167
2014.02.19 13:44:47.281 0: HMLAN_Send:  HMLAN1 S:+193A9A,00,01,
2014.02.19 13:44:47.283 0: HMLAN_Send:  HMLAN1 S:S4A2D5073 stat:  00 t:00000000 d:01 r:4A2D5073 m:CF A258 B3B3B3 193A9A 0338
2014.02.19 13:44:47.608 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: init
2014.02.19 13:44:47.618 1: ##### init ##### n:VentilControler.WZ_Btn1 c:139 t:1392814035683
2014.02.19 13:44:47.629 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.19 13:44:47.631 0: HMLAN_Send:  HMLAN1 S:S4A2D51CF stat:  00 t:00000000 d:01 r:4A2D51CF m:8B A258 B4B4B4 1CE9F5 03B5
2014.02.19 13:44:47.941 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: init
2014.02.19 13:44:47.952 1: ##### init ##### n:VentilControler.AZ.Nord_Btn1 c:32 t:1392814006631
2014.02.19 13:44:47.962 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.19 13:44:47.965 0: HMLAN_Send:  HMLAN1 S:S4A2D531C stat:  00 t:00000000 d:01 r:4A2D531C m:20 A258 B5B5B5 1C4E25 0300
2014.02.19 13:44:48.164 0: HMLAN_Parse: HMLAN1 R:R4A2D4F3A stat:0008 t:00000000 d:FF r:7FFF     m:9F A258 B2B2B2 1DFC2F 0323
2014.02.19 13:44:48.167 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 13:44:48.170 0: HMLAN_Parse: HMLAN1 R:R4A2D5073 stat:0008 t:00000000 d:FF r:7FFF     m:CF A258 B3B3B3 193A9A 0338
2014.02.19 13:44:48.173 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 13:44:48.188 1: Perfmon: possible freeze starting at 13:44:47, delay is 1.187
2014.02.19 13:44:48.262 0: HMLAN_Parse: HMLAN1 R:R4A2D51CF stat:0008 t:00000000 d:FF r:7FFF     m:8B A258 B4B4B4 1CE9F5 03B5
2014.02.19 13:44:48.265 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 13:44:48.572 0: HMLAN_Parse: HMLAN1 R:R4A2D531C stat:0008 t:00000000 d:FF r:7FFF     m:20 A258 B5B5B5 1C4E25 0300
2014.02.19 13:44:48.575 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 13:44:57.029 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_1
2014.02.19 13:44:57.341 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_1
2014.02.19 13:44:57.689 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_1
2014.02.19 13:44:58.022 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_1
2014.02.19 13:45:39.524 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: init
2014.02.19 13:45:39.536 1: ##### init ##### n:VentilControler.Kueche_Btn1 c:173 t:1392813954813
2014.02.19 13:45:39.546 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.19 13:45:39.549 0: HMLAN_Send:  HMLAN1 S:S4A2E1C9C stat:  00 t:00000000 d:01 r:4A2E1C9C m:AD A258 B1B1B1 1BFC52 03FD
2014.02.19 13:45:41.088 0: HMLAN_Parse: HMLAN1 R:R4A2E1C9C stat:0008 t:00000000 d:FF r:7FFF     m:AD A258 B1B1B1 1BFC52 03FD
2014.02.19 13:45:41.093 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 13:45:41.124 1: Perfmon: possible freeze starting at 13:45:40, delay is 1.102
2014.02.19 13:45:49.609 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_1
2014.02.19 13:45:54.823 1: VD-test B1B1B101 c:174 t:1392814098563 x:143750
2014.02.19 13:45:54.831 0: HMLAN_Send:  HMLAN1 S:S4A2E5852 stat:  00 t:00000000 d:01 r:4A2E5852 m:AE A258 B1B1B1 1BFC52 03FD
2014.02.19 13:45:55.440 0: HMLAN_Parse: HMLAN1 R:R4A2E5852 stat:0008 t:00000000 d:FF r:7FFF     m:AE A258 B1B1B1 1BFC52 03FD
2014.02.19 13:45:55.445 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 13:46:04.650 1: VD-test B2B2B201 c:160 t:1392814101139 x:136500
2014.02.19 13:46:04.658 0: HMLAN_Send:  HMLAN1 S:S4A2E7EB4 stat:  00 t:00000000 d:01 r:4A2E7EB4 m:A0 A258 B2B2B2 1DFC2F 0323
2014.02.19 13:46:04.894 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_2
2014.02.19 13:46:05.267 0: HMLAN_Parse: HMLAN1 R:R4A2E7EB4 stat:0008 t:00000000 d:FF r:7FFF     m:A0 A258 B2B2B2 1DFC2F 0323
2014.02.19 13:46:05.271 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 13:46:15.574 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_2
2014.02.19 13:46:46.640 1: VD-test B5B5B501 c:33 t:1392814131631 x:125000
2014.02.19 13:46:46.648 0: HMLAN_Send:  HMLAN1 S:S4A2F22BA stat:  00 t:00000000 d:01 r:4A2F22BA m:21 A258 B5B5B5 1C4E25 0300
2014.02.19 13:46:47.255 0: HMLAN_Parse: HMLAN1 R:R4A2F22BA stat:0008 t:00000000 d:FF r:7FFF     m:21 A258 B5B5B5 1C4E25 0300
2014.02.19 13:46:47.258 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 13:46:48.177 1: VD-test B3B3B301 c:208 t:1392814139417 x:131250
2014.02.19 13:46:48.185 0: HMLAN_Send:  HMLAN1 S:S4A2F28BB stat:  00 t:00000000 d:01 r:4A2F28BB m:D0 A258 B3B3B3 193A9A 0338
2014.02.19 13:46:48.793 0: HMLAN_Parse: HMLAN1 R:R4A2F28BB stat:0008 t:00000000 d:FF r:7FFF     m:D0 A258 B3B3B3 193A9A 0338
2014.02.19 13:46:48.796 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 13:46:56.710 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_2
2014.02.19 13:46:58.245 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_2
2014.02.19 13:47:15.698 1: VD-test B4B4B401 c:140 t:1392814170433 x:134750
2014.02.19 13:47:15.710 0: HMLAN_Send:  HMLAN1 S:S4A2F943F stat:  00 t:00000000 d:01 r:4A2F943F m:8C A258 B4B4B4 1CE9F5 03B5
2014.02.19 13:47:16.317 0: HMLAN_Parse: HMLAN1 R:R4A2F943F stat:0008 t:00000000 d:FF r:7FFF     m:8C A258 B4B4B4 1CE9F5 03B5
2014.02.19 13:47:16.320 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 13:47:25.830 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_2
2014.02.19 13:48:18.573 1: VD-test B1B1B101 c:175 t:1392814228063 x:129500
2014.02.19 13:48:18.581 0: HMLAN_Send:  HMLAN1 S:+1BFC52,00,01,
2014.02.19 13:48:18.583 0: HMLAN_Send:  HMLAN1 S:S4A3089D7 stat:  00 t:00000000 d:01 r:4A3089D7 m:AF A258 B1B1B1 1BFC52 00FD
2014.02.19 13:48:19.190 0: HMLAN_Parse: HMLAN1 R:R4A3089D7 stat:0008 t:00000000 d:FF r:7FFF     m:AF A258 B1B1B1 1BFC52 00FD
2014.02.19 13:48:19.192 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 13:48:21.148 1: VD-test B2B2B201 c:161 t:1392814223389 x:122250
2014.02.19 13:48:21.156 0: HMLAN_Send:  HMLAN1 S:S4A3093E6 stat:  00 t:00000000 d:01 r:4A3093E6 m:A1 A258 B2B2B2 1DFC2F 032E
2014.02.19 13:48:21.762 0: HMLAN_Parse: HMLAN1 R:R4A3093E6 stat:0008 t:00000000 d:FF r:7FFF     m:A1 A258 B2B2B2 1DFC2F 032E
2014.02.19 13:48:21.766 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 13:48:28.642 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_3
2014.02.19 13:48:31.219 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_3
2014.02.19 13:48:51.641 1: VD-test B5B5B501 c:34 t:1392814306131 x:174500
2014.02.19 13:48:51.649 0: HMLAN_Send:  HMLAN1 S:+1C4E25,00,01,
2014.02.19 13:48:51.651 0: HMLAN_Send:  HMLAN1 S:S4A310B03 stat:  00 t:00000000 d:01 r:4A310B03 m:22 A258 B5B5B5 1C4E25 0000
2014.02.19 13:48:52.258 0: HMLAN_Parse: HMLAN1 R:R4A310B03 stat:0008 t:00000000 d:FF r:7FFF     m:22 A258 B5B5B5 1C4E25 0000
2014.02.19 13:48:52.261 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 13:48:59.427 1: VD-test B3B3B301 c:209 t:1392814320167 x:180750
2014.02.19 13:48:59.435 0: HMLAN_Send:  HMLAN1 S:S4A31296D stat:  00 t:00000000 d:01 r:4A31296D m:D1 A258 B3B3B3 193A9A 0340
2014.02.19 13:49:00.324 0: HMLAN_Parse: HMLAN1 R:R4A31296D stat:0008 t:00000000 d:FF r:7FFF     m:D1 A258 B3B3B3 193A9A 0340
2014.02.19 13:49:00.327 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 13:49:01.711 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_3
2014.02.19 13:49:09.564 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_3
2014.02.19 13:49:30.444 1: VD-test B4B4B401 c:141 t:1392814290933 x:120500
2014.02.19 13:49:30.452 0: HMLAN_Send:  HMLAN1 S:+1CE9F5,00,01,
2014.02.19 13:49:30.454 0: HMLAN_Send:  HMLAN1 S:S4A31A296 stat:  00 t:00000000 d:01 r:4A31A296 m:8D A258 B4B4B4 1CE9F5 00B5
2014.02.19 13:49:31.062 0: HMLAN_Parse: HMLAN1 R:R4A31A296 stat:0008 t:00000000 d:FF r:7FFF     m:8D A258 B4B4B4 1CE9F5 00B5
2014.02.19 13:49:31.066 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 13:49:40.580 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_3
2014.02.19 13:50:23.404 1: VD-test B2B2B201 c:162 t:1392814395139 x:171750
2014.02.19 13:50:23.414 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.19 13:50:23.418 0: HMLAN_Send:  HMLAN1 S:S4A327178 stat:  00 t:00000000 d:01 r:4A327178 m:A2 A258 B2B2B2 1DFC2F 002E
2014.02.19 13:50:24.026 0: HMLAN_Parse: HMLAN1 R:R4A327178 stat:0008 t:00000000 d:FF r:7FFF     m:A2 A258 B2B2B2 1DFC2F 002E
2014.02.19 13:50:24.030 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 13:50:28.072 1: VD-test B1B1B101 c:176 t:1392814407063 x:179000
2014.02.19 13:50:28.080 0: HMLAN_Send:  HMLAN1 S:S4A3283B2 stat:  00 t:00000000 d:01 r:4A3283B2 m:B0 A258 B1B1B1 1BFC52 00FD
2014.02.19 13:50:28.686 0: HMLAN_Parse: HMLAN1 R:R4A3283B2 stat:0008 t:00000000 d:FF r:7FFF     m:B0 A258 B1B1B1 1BFC52 00FD
2014.02.19 13:50:28.690 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 13:50:33.521 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_4
2014.02.19 13:50:38.143 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_4
2014.02.19 13:51:30.943 1: VD-test B4B4B401 c:142 t:1392814460933 x:170000
2014.02.19 13:51:30.951 0: HMLAN_Send:  HMLAN1 S:S4A337949 stat:  00 t:00000000 d:01 r:4A337949 m:8E A258 B4B4B4 1CE9F5 00B5
2014.02.19 13:51:31.558 0: HMLAN_Parse: HMLAN1 R:R4A337949 stat:0008 t:00000000 d:FF r:7FFF     m:8E A258 B4B4B4 1CE9F5 00B5
2014.02.19 13:51:31.561 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 13:51:41.013 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_4
2014.02.19 13:51:46.140 1: VD-test B5B5B501 c:35 t:1392814466131 x:160000
2014.02.19 13:51:46.148 0: HMLAN_Send:  HMLAN1 S:S4A33B4A6 stat:  00 t:00000000 d:01 r:4A33B4A6 m:23 A258 B5B5B5 1C4E25 0000
2014.02.19 13:51:46.756 0: HMLAN_Parse: HMLAN1 R:R4A33B4A6 stat:0008 t:00000000 d:FF r:7FFF     m:23 A258 B5B5B5 1C4E25 0000
2014.02.19 13:51:46.759 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 13:51:56.209 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_4
2014.02.19 13:52:00.177 1: VD-test B3B3B301 c:210 t:1392814486417 x:166250
2014.02.19 13:52:00.185 0: HMLAN_Send:  HMLAN1 S:S4A33EB7B stat:  00 t:00000000 d:01 r:4A33EB7B m:D2 A258 B3B3B3 193A9A 034A
2014.02.19 13:52:00.792 0: HMLAN_Parse: HMLAN1 R:R4A33EB7B stat:0008 t:00000000 d:FF r:7FFF     m:D2 A258 B3B3B3 193A9A 034A
2014.02.19 13:52:00.796 0: HMLAN_Parse: HMLAN1 no ACK from 193A9A
2014.02.19 13:52:10.281 1: ----- VD-STATUS ----- VentilControler.Bad_Btn1 valveCtrl: miss_4
2014.02.19 13:53:15.154 1: VD-test B2B2B201 c:163 t:1392814552389 x:157250
2014.02.19 13:53:15.162 0: HMLAN_Send:  HMLAN1 S:+1DFC2F,00,01,
2014.02.19 13:53:15.167 0: HMLAN_Send:  HMLAN1 S:S4A35105C stat:  00 t:00000000 d:01 r:4A35105C m:A3 A258 B2B2B2 1DFC2F 0323
2014.02.19 13:53:15.817 0: HMLAN_Parse: HMLAN1 R:R4A35105C stat:0008 t:00000000 d:FF r:7FFF     m:A3 A258 B2B2B2 1DFC2F 0323
2014.02.19 13:53:15.821 0: HMLAN_Parse: HMLAN1 no ACK from 1DFC2F
2014.02.19 13:53:25.267 1: ----- VD-STATUS ----- VentilControler.SZ_Btn1 valveCtrl: miss_5
2014.02.19 13:53:27.073 1: VD-test B1B1B101 c:177 t:1392814571563 x:164500
2014.02.19 13:53:27.081 0: HMLAN_Send:  HMLAN1 S:S4A353EEB stat:  00 t:00000000 d:01 r:4A353EEB m:B1 A258 B1B1B1 1BFC52 00FD
2014.02.19 13:53:27.696 0: HMLAN_Parse: HMLAN1 R:R4A353EEB stat:0008 t:00000000 d:FF r:7FFF     m:B1 A258 B1B1B1 1BFC52 00FD
2014.02.19 13:53:27.699 0: HMLAN_Parse: HMLAN1 no ACK from 1BFC52
2014.02.19 13:53:37.142 1: ----- VD-STATUS ----- VentilControler.Kueche_Btn1 valveCtrl: miss_5
2014.02.19 13:54:20.943 1: VD-test B4B4B401 c:143 t:1392814616433 x:155500
2014.02.19 13:54:20.952 0: HMLAN_Send:  HMLAN1 S:S4A36115A stat:  00 t:00000000 d:01 r:4A36115A m:8F A258 B4B4B4 1CE9F5 00B5
2014.02.19 13:54:21.559 0: HMLAN_Parse: HMLAN1 R:R4A36115A stat:0008 t:00000000 d:FF r:7FFF     m:8F A258 B4B4B4 1CE9F5 00B5
2014.02.19 13:54:21.561 0: HMLAN_Parse: HMLAN1 no ACK from 1CE9F5
2014.02.19 13:54:26.140 1: VD-test B5B5B501 c:36 t:1392814611881 x:145750
2014.02.19 13:54:26.148 0: HMLAN_Send:  HMLAN1 S:S4A3625A5 stat:  00 t:00000000 d:01 r:4A3625A5 m:24 A258 B5B5B5 1C4E25 0000
2014.02.19 13:54:26.755 0: HMLAN_Parse: HMLAN1 R:R4A3625A5 stat:0008 t:00000000 d:FF r:7FFF     m:24 A258 B5B5B5 1C4E25 0000
2014.02.19 13:54:26.758 0: HMLAN_Parse: HMLAN1 no ACK from 1C4E25
2014.02.19 13:54:31.014 1: ----- VD-STATUS ----- VentilControler.WZ_Btn1 valveCtrl: miss_5
2014.02.19 13:54:32.353 1: Perfmon: possible freeze starting at 13:54:31, delay is 1.352
2014.02.19 13:54:36.312 1: ----- VD-STATUS ----- VentilControler.AZ.Nord_Btn1 valveCtrl: miss_5

ich sehe mehrere probleme:
1. msgctr vor und nach restart.
2. das erste event aller vd wird quasi gleichzeitig rausgehauen. zeitpunkte können nicht stimmen.
3. die abstände zwischen 1. und 2. event können offensichtlich bei einigen vtc nicht stimmen.

meiner meinung nach erzeugt restart das erste event mit falscher nummer plus falschem zeitpunkt. dann kommt init, erzeugt aus den restart daten ein neues event , das nun eventuell zum richtigen zeitpunkt (zumindestens sind die abstände zum letzten event vor shutdown im rahmen, der absolute zeitpunkt kann ja nicht stimmen), aber mit nun doppelt falscher msg nummer gesendet wird.

selbst wenn das rausgehauene restart-event die richtige nummer hätte, wäre das folgende init-event falsch, da es auf den daten des restart-events aufbaut.

Zitatsomit muss das "-1" raus.

kann eigentlich nicht funktionieren. es sei denn, du hast die logik umgebaut.

bisher galt: ausserhalb der funktionen ist msgCtr die zahl, mit welcher aus dem aktuellen zeitpunkt (altes next) das nächste next berechnet wird. wenn wir also nach cul_hm_valveposupd kommen, ist in next der augenblickliche zeitpunkt (wir sind dabei das event zu senden) enthalten. in msgCtr aber die nummer des vorherigen events. sozusagen ist msgCtr immer um ein event verschoben gegenüber next, sofern sie synchron sind.
genauso wird msgCtr und next gespeichert. in next der zeitpunkt des letzten erfolgreichen events und in msgCtr die nummer des davor gesendeten events.
letztendlich egal welche msgCtr gespeichert wird. muss nur passen.

am einfachsten würde man es sich bestimmt machen, wenn die berechnung des nächsten eventzeitpunktes in eine eigene funktion ausgelagert wäre. dadurch kann auf einfache weise die berechnung und das senden getrennt behandelt werden. man übergibt ein beliebiges msgCtr/next-paar und bekommt ein entprechendes päarchen mit zukünftigem sendetermin zurück.

ist denn der zusätzliche einsprung aus culhmconfig nötig?

gruss frank

martinp876 · 19 Februar 2014, 17:13:03

Hi Frank,

die Logic ist nicht geaendert - solltest du aus den Code lesen koennen.
Es wird gespeichert(nach Readings): der Zeitpunkt des letzten Sendens und der entsprechende msgCount wenn es ein ACK gegeben hat. Das ist das gleiche Konzept.
Durch die umstellung auf die while-schleifen kann man die Prozedur endlos aufrufen, Zaehlern und zeitpunkt muessen stabil bleiben. Es wird erst weiter gerechnet, wenn wir in der Vergangenheit sind.
Das hat gestern noch funktioniert - und das ist Basis des Konzepts.

Somit muss man - wir von dir vorgesehen in der Schleifen - aus den Reading werden den naechsten in der Zukunft liegenden Wert errechnen koennen.
Die Logs der Schleife vor und nach dem Restart muessen zum gleichen Ergebniss kommen. Vor dem Restart sind leider keine logs der Schleife enthalten - werde ich selbst machen. Da muss man nur die Werte vergleichen - die muessen 100% uebereinstimmen. Sollte einfach zu vergleiche sein - dauert nur immer 10 min je test

Zitatist denn der zusätzliche einsprung aus culhmconfig nötig?

verstrehe ich nicht - was ist das?
Und nein, ein neuer Einsprung ist nicht notwendig. Auch eine separate Berechnung nicht.

Gruss Martin

martinp876 · 20 Februar 2014, 07:13:24

Hi Frank,

habe gestern einen neuen Versuch eingecheckt. ist ein klein wenig getunt. Hat bei 3 restarts ohne Probleme funktioniert. Aktuell ist ein Log drin - mit verbose 5 des VTC solltest du es einschalten koennen. Damit sollte das korrekte timerhandling tracebar sein.
Nach dem reboot koennte noch eine message zu viel kommen - habe ich noch nicht untersucht. Kann damit zusammen haengen, dass ich im fhem.cfg ein Kommando absetze - das kommt demnach vor dem Init... Wirkliche Probleme macht das jetzt auch nicht mehr (bei mir)

Gruss Martin