HM-SEC-SC watchdog triggert bei alive Lebenszeichen

Begonnen von ThorstenH, 13 November 2013, 18:25:52

Vorheriges Thema - Nächstes Thema

ThorstenH

Hallo,

Ich habe einen watchdog definiert, der mir eine mail senden soll, wenn ein Schalter für mindestens 5 Sekunden auf war:
Internals:
   CMD        {\
  system("/volume1/addons/fhem/bin/sendmail.sh", "Testkontakt offen", "Testkontak ist offen.");\
  fhem("setstate watchdog_window_open_threestatesensor_test defined");\
}
   DEF        threestatesensor_test:open 00:00:05 threestatesensor_test:closed {\
  system("/volume1/addons/fhem/bin/sendmail.sh", "Testkontakt offen", "Testkontak ist offen.");\
  fhem("setstate watchdog_window_open_threestatesensor_test defined");\
}
   NAME       watchdog_window_open_threestatesensor_test
   NR         83
   NTFY_ORDER 50-watchdog_window_open_threestatesensor_test
   RE1        threestatesensor_test:open
   RE2        threestatesensor_test:closed
   STATE      defined
   TO         5
   TYPE       watchdog
   Readings:
     2013-11-11 14:24:11   Triggered       triggered
Attributes:
   regexp1WontReactivate 1
   room       Test


Dummerweise kommt mit jedem alive Lebenszeichen des HM-SEC-SC der open Status mit, weshalb der Trigger immer einmal am Tag ansprang, obwohl die Schalterstellung sich nicht verändert hat. Um das zu vermeiden hat mir Martin geraten, folgendes Attribut zu verwenden:
event-on-change-reading contact,state


Damit werden nur noch change Ereignisse getriggert, die den state wirklich ändern. Das half bei den mitgesendeten "open" states in den Lebenszeichen und es wurden nur noch mails bei Änderung geschickt.

Zu meinem Problem: hin und wieder sehe ich im Log:
HMLAN_Parse: HMLAN1 no ACK from 1E7C35


1E7C35 ist mein HM-SEC-SC. Jetzt passiert folgendes: der state geht auf "missing-ack" und das nächste Lebenszeichen am nächsten Tag schickt ein state "open" mit. Das ist in fhem ein "change", auch bei gesetztem "event-on-change-reading" Attribut. Und schon wird wieder eine mail geschickt, obwohl die Schalterstellung sich nicht verändert hat.

Kann ich das irgendwie abfangen?


# $Id: fhem.pl 3872 2013-09-07 11:58:33Z rudolfkoenig $
# $Id: 10_CUL_HM.pm 4032 2013-10-11 17:53:00Z martinp876 $
# $Id: 01_FHEMWEB.pm 3963 2013-09-26 08:55:32Z martinp876 $
# $Id: 10_FS20.pm 3764 2013-08-22 07:09:38Z rudolfkoenig $
# $Id: 92_FileLog.pm 3759 2013-08-21 08:13:08Z rudolfkoenig $
# $Id: 00_HMLAN.pm 4030 2013-10-10 21:25:10Z martinp876 $
# $Id: 99_SUNRISE_EL.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 99_Utils.pm 3595 2013-08-05 05:38:48Z tobiasfaust $
# $Id: 90_at.pm 4011 2013-10-06 08:15:26Z rudolfkoenig $
# $Id: 98_autocreate.pm 3999 2013-10-04 05:15:46Z rudolfkoenig $
# $Id: 91_notify.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 98_telnet.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 91_watchdog.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $

martinp876

Hallo Thorsten,

da sind mehrere Fragen drin. Zum Ersten: warum kommt ein missing-ACK? Ich denke es liegt daran, dass ein statusRequest von System angefordert wird - und der SC dies nicht unterstützt. In der nächsten Version habe ich dies korrigiert, dann sollte es nicht mehr kommen.

Alternativ/zusätzlich kannst du channel und device trennen. Die Protokoll-Fehler werden am device gemeldet, nicht am channel.
also ein
define <sec-sc-chn> CUL_HM xxxxxx01
Dann sollten alle funktionalen Ereignisse am channel kommen und die Protokoll-dinge im Device. Ein missing-ack sollte nicht am Channel gezeigt werden.

Wenn channel und device "kombiniert" sind sollte das missing-ack weiterhin am state angezeigt werden da ich so etwas für wichtig halte - immerhin ist etwas nicht ausgeführt worden.
Gruss Martin

ThorstenH

Hallo Martin,

keine Ahnung, was für ein Request das war. Hier ist etwas mehr Log:

2013.11.10 14:31:09.820 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:31:09.824 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C053AF4F IDcnt:0009
2013.11.10 14:31:34.823 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:31:34.827 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C05410FD IDcnt:0009
2013.11.10 14:31:51.077 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:C0545074 d:FF r:FFBC     m:04 8670 1BF932 000000 00D63F
2013.11.10 14:31:59.826 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:31:59.830 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C05472AC IDcnt:0009
2013.11.10 14:32:11.077 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:C0549E97 d:FF r:FFBD     m:04 A258 1BF932 1C4DD3 002D
2013.11.10 14:32:11.209 1: HMLAN_Parse: HMLAN1 R:E1C4DD3   stat:0000 t:C0549F19 d:FF r:FFBE     m:04 8202 1C4DD3 1BF932 0101220030
2013.11.10 14:32:15.580 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:C054B02F d:FF r:FFC9     m:A6 8670 1CDFBA 000000 00B23D
2013.11.10 14:32:24.830 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:32:24.834 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C054D45B IDcnt:0009
2013.11.10 14:32:35.579 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:C054FE51 d:FF r:FFC9     m:A6 A258 1CDFBA 1CF1FC 0000
2013.11.10 14:32:35.713 1: HMLAN_Parse: HMLAN1 R:E1CF1FC   stat:0000 t:C054FED5 d:FF r:FFC3     m:A6 8202 1CF1FC 1CDFBA 010100003D
2013.11.10 14:32:49.833 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:32:49.837 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C055360A IDcnt:0009
2013.11.10 14:33:14.836 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:33:14.840 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C05597B9 IDcnt:0009
2013.11.10 14:33:23.773 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0000 t:C055BA97 d:FF r:FFAD     m:83 A610 1E7C35 DE7474 0601C800
2013.11.10 14:33:24.276 1: HMLAN_Send:  HMLAN1 I:+1E7C35,00,00,
2013.11.10 14:33:24.276 1: HMLAN_Send:  HMLAN1 S:S423786F6 stat:  00 t:00000000 d:01 r:423786F6 m:35 A001 DE7474 1E7C35 00040000000000
2013.11.10 14:33:24.882 1: HMLAN_Parse: HMLAN1 R:R423786F6 stat:0008 t:00000000 d:FF r:7FFF     m:35 A001 DE7474 1E7C35 00040000000000
2013.11.10 14:33:24.882 1: HMLAN_Parse: HMLAN1 no ACK from 1E7C35
2013.11.10 14:33:28.231 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0000 t:C055CC04 d:FF r:FFAB     m:83 A610 1E7C35 DE7474 0601C800
2013.11.10 14:33:28.538 1: HMLAN_Parse: HMLAN1 R:E1CE123   stat:0000 t:C055CD37 d:FF r:FFBA     m:1F 8670 1CE123 000000 00C743
2013.11.10 14:33:34.940 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:C055E63A d:FF r:FFCC     m:F8 8670 1CE75F 000000 00AD44
2013.11.10 14:33:39.839 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:33:39.843 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C055F967 IDcnt:000A
2013.11.10 14:33:48.538 1: HMLAN_Parse: HMLAN1 R:E1CE123   stat:0000 t:C0561B5A d:FF r:FFBA     m:1F A258 1CE123 1C494E 0200
2013.11.10 14:33:48.672 1: HMLAN_Parse: HMLAN1 R:E1C494E   stat:0000 t:C0561BDE d:FF r:FFAC     m:1F 8202 1C494E 1CE123 0101000033
2013.11.10 14:33:54.940 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:C056345D d:FF r:FFCC     m:F8 A258 1CE75F 1CED2A 0000
2013.11.10 14:33:55.073 1: HMLAN_Parse: HMLAN1 R:E1CED2A   stat:0000 t:C05634E0 d:FF r:FFC0     m:F8 8202 1CED2A 1CE75F 0101000046
2013.11.10 14:34:04.843 1: HMLAN_Send:  HMLAN1 I:K
2013.11.10 14:34:04.847 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:C0565B17 IDcnt:000A
2013.11.10 14:34:18.833 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:C05691B5 d:FF r:FFBC     m:05 8670 1BF932 000000 00D63F


Die Alternative habe ich nicht verstanden, daher versuche ich erst mal das update. Wann soll ich denn aktualisieren? Jetzt oder kommt deine nächste Version erst noch?

Viele Grüße
Thorsten

martinp876

Hallo Thorsten,

etwas stimmt noch nicht.
wie oft wacht der SC eigentlich auf? alle 24h?
Ich bin mir nicht sicher, dass HMLAN das ACK gesendet hat. Der SC meldet sich das erste mal nach HMLAN reboot? - vielleicht ist der SC deshalb beleidigt und beantwortet die Anfrage nicht. Wenn das so ist muss ich mit etwas einfallen lassen.
Seltsam ist auch, dass keine "hallo-wach" gesendet wird.

- Du bist auf der neusten Version?
- du hast ein Nachricht gesendet (getConfig) und dann auf das Erwachen gewartet?

Danke
Martin

ThorstenH

#4
Die Ausgabe von "version" hatte ich angehängt. Ist nicht die neueste, da ich meist nur aktualisiere, wenn es Probleme bei mir gibt. Gerade im Winter muss  ich höllisch aufpassen, dass die Heizung warm bleibt, sonst gibt's Ärger mit der Regierung  ;).
Ich aktualisiere dann jetzt mal (*zitter*)...

Ich hatte zu diesem Zeitpunkt nichts aktiv gesendet, also kein getconfig oder so was.

Was meinst du mit "Der SC meldet sich das erste mal nach HMLAN reboot?"? Generell oder zu diesem Zeitpunkt? Zwischen diesem Lebenszeichen und dem "davor" gab es kein Neustart des HMLANs.

Nach dem gerade erfolgten update bin ich jetzt auf:

# $Id: fhem.pl 4208 2013-11-12 17:43:33Z rudolfkoenig $
# $Id: 10_CUL_HM.pm 4227 2013-11-15 15:51:54Z martinp876 $
# $Id: 01_FHEMWEB.pm 4226 2013-11-15 12:57:17Z rudolfkoenig $
# $Id: 10_FS20.pm 3764 2013-08-22 07:09:38Z rudolfkoenig $
# $Id: 92_FileLog.pm 3759 2013-08-21 08:13:08Z rudolfkoenig $
# $Id: 00_HMLAN.pm 4203 2013-11-11 12:34:12Z martinp876 $
# $Id: 99_SUNRISE_EL.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 99_Utils.pm 3595 2013-08-05 05:38:48Z tobiasfaust $
# $Id: 90_at.pm 4011 2013-10-06 08:15:26Z rudolfkoenig $
# $Id: 98_autocreate.pm 4229 2013-11-15 17:29:55Z rudolfkoenig $
# $Id: 91_notify.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 98_telnet.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $
# $Id: 91_watchdog.pm 3738 2013-08-18 14:13:59Z rudolfkoenig $


EDIT: Nach dem update das Log (inklusive update):

27:37.758 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:27:37.761 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF00CD58 IDcnt:0006
2013.11.16 13:28:02.763 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:28:02.766 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF012F09 IDcnt:0006
2013.11.16 13:28:27.765 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:28:27.768 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF0190B6 IDcnt:0006
2013.11.16 13:28:37.505 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:DF01B6BC d:FF r:FFCB     m:35 8670 1CE75F 000000 00A73F
2013.11.16 13:28:52.768 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:28:52.771 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF01F265 IDcnt:0006
2013.11.16 13:28:57.505 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:DF0204DF d:FF r:FFCB     m:35 A258 1CE75F 1CED2A 0200
2013.11.16 13:28:57.638 1: HMLAN_Parse: HMLAN1 R:E1CED2A   stat:0000 t:DF020561 d:FF r:FFC1     m:35 8202 1CED2A 1CE75F 010100003E
2013.11.16 13:29:09.637 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF023444 d:FF r:FFCC     m:E0 8670 1CDFBA 000000 00A63A
2013.11.16 13:29:10.000 1: HMLAN_Parse: HMLAN1 R:E1CE123   stat:0000 t:DF0235AF d:FF r:FFB6     m:60 8670 1CE123 000000 00A941
2013.11.16 13:29:17.772 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:29:17.775 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF025415 IDcnt:0006
2013.11.16 13:29:29.637 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF028267 d:FF r:FFCC     m:E0 A258 1CDFBA 1CF1FC 0200
2013.11.16 13:29:29.771 1: HMLAN_Parse: HMLAN1 R:E1CF1FC   stat:0000 t:DF0282EB d:FF r:FFC5     m:E0 8202 1CF1FC 1CDFBA 0101000034
2013.11.16 13:29:30.000 1: HMLAN_Parse: HMLAN1 R:E1CE123   stat:0000 t:DF0283D2 d:FF r:FFB5     m:60 A258 1CE123 1CF1E3 0200
2013.11.16 13:29:30.135 1: HMLAN_Parse: HMLAN1 R:E1CF1E3   stat:0000 t:DF028457 d:FF r:FFAF     m:60 8202 1CF1E3 1CE123 010100002E
2013.11.16 13:29:37.219 1: update check Releases => local: Fhem 5.5 (DEVELOPMENT) remote: Fhem 5.5 (DEVELOPMENT)
2013.11.16 13:29:37.590 1: update saving statefile
2013.11.16 13:30:29.599 1: backup done: FHEM-20131116_132937.tar.gz (24139813 Bytes)
2013.11.16 13:30:40.520 1: update 99 file(s) have been updated.
2013.11.16 13:30:40.521 1: update A new version of fhem.pl was installed, 'shutdown restart' is required!
2013.11.16 13:30:40.638 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:30:40.639 1: 192.168.74.50:1000 disconnected, waiting to reappear
2013.11.16 13:30:40.643 1: HMLAN_Parse: HMLAN1 new condition disconnected
2013.11.16 13:30:40.649 1: 192.168.74.50:1000 reappeared (HMLAN1)
2013.11.16 13:30:40.649 1: HMLAN_Send:  HMLAN1 I:ADE7474
2013.11.16 13:30:40.649 1: HMLAN_Send:  HMLAN1 I:C
2013.11.16 13:30:40.649 1: HMLAN_Send:  HMLAN1 I:Y01,00,
2013.11.16 13:30:40.649 1: HMLAN_Send:  HMLAN1 I:Y02,00,
2013.11.16 13:30:40.649 1: HMLAN_Send:  HMLAN1 I:Y03,00,
2013.11.16 13:30:40.649 1: HMLAN_Send:  HMLAN1 I:T1A1A13E0,04,00,00000000
2013.11.16 13:30:40.649 1: HMLAN_Parse: HMLAN1 new condition init
2013.11.16 13:30:40.661 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF0397DE IDcnt:0006
2013.11.16 13:30:40.661 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:DF030CE1 d:FF r:FFC0     m:38 8670 1BF932 000000 00DF34
2013.11.16 13:30:40.674 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:DF035B04 d:FF r:FFC0     m:38 A258 1BF932 1BFC15 0033
2013.11.16 13:30:40.715 1: HMLAN_Parse: HMLAN1 R:E1BFC15   stat:0000 t:DF035B88 d:FF r:FFC1     m:38 8202 1BFC15 1BF932 010128002D
2013.11.16 13:31:05.658 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:31:05.661 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF03F992 IDcnt:0000
2013.11.16 13:31:07.997 0: Server shutdown
2013.11.16 13:31:10.606 1: Including fhem.cfg
2013.11.16 13:31:11.549 1: configfile: hmMsgLowLimit:please add integer between 10 and 120
2013.11.16 13:31:11.549 1: Including ./log/fhem.save
2013.11.16 13:31:11.631 0: Server started with 104 defined entities (version $Id: fhem.pl 4208 2013-11-12 17:43:33Z rudolfkoenig $, os linux, user root, pid 13315)
2013.11.16 13:31:11.632 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF040EDE IDcnt:0000
2013.11.16 13:31:11.632 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:DF040B85 d:FF r:FFCB     m:36 8670 1CE75F 000000 00A73F
2013.11.16 13:31:11.649 1: HMLAN_Parse: HMLAN1 R:R60E4B847 stat:0002 t:00000000 d:FF r:7FFF     m:99 8112 999999 000001
2013.11.16 13:31:11.649 1: HMLAN_Parse: HMLAN1 new condition ok
2013.11.16 13:31:17.906 1: HMLAN_Send:  HMLAN1 I:+1CF4FB,00,00,
2013.11.16 13:31:17.906 1: HMLAN_Send:  HMLAN1 S:S60E4D2D4 stat:  00 t:00000000 d:01 r:60E4D2D4 m:01 B001 DE7474 1CF4FB 010E
2013.11.16 13:31:18.418 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF042B63 d:FF r:FFC1     m:01 A010 1CF4FB DE7474 0601000040
2013.11.16 13:31:18.538 1: HMLAN_Parse: HMLAN1 R:R60E4D2D4 stat:0001 t:DF042B68 d:FF r:FFC1     m:01 A010 1CF4FB DE7474 0601000040
2013.11.16 13:31:18.909 1: HMLAN_Send:  HMLAN1 I:+1A5D71,00,00,
2013.11.16 13:31:18.909 1: HMLAN_Send:  HMLAN1 S:S60E4D6BF stat:  00 t:00000000 d:01 r:60E4D6BF m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:19.515 1: HMLAN_Parse: HMLAN1 R:R60E4D6BF stat:0008 t:00000000 d:FF r:7FFF     m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:19.515 1: HMLAN_Parse: HMLAN1 no ACK from 1A5D71
2013.11.16 13:31:19.912 1: HMLAN_Send:  HMLAN1 I:+1DB350,00,00,
2013.11.16 13:31:19.913 1: HMLAN_Send:  HMLAN1 S:S60E4DAAB stat:  00 t:00000000 d:01 r:60E4DAAB m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:20.518 1: HMLAN_Parse: HMLAN1 R:R60E4DAAB stat:0008 t:00000000 d:FF r:7FFF     m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:20.518 1: HMLAN_Parse: HMLAN1 no ACK from 1DB350
2013.11.16 13:31:20.940 1: HMLAN_Send:  HMLAN1 S:S60E4DEAE stat:  00 t:00000000 d:01 r:60E4DEAE m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:21.543 1: HMLAN_Parse: HMLAN1 R:R60E4DEAE stat:0008 t:00000000 d:FF r:7FFF     m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:21.543 1: HMLAN_Parse: HMLAN1 no ACK from 1A5D71
2013.11.16 13:31:23.192 1: HMLAN_Send:  HMLAN1 S:S60E4E77A stat:  00 t:00000000 d:01 r:60E4E77A m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:23.795 1: HMLAN_Parse: HMLAN1 R:R60E4E77A stat:0008 t:00000000 d:FF r:7FFF     m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:23.796 1: HMLAN_Parse: HMLAN1 no ACK from 1DB350
2013.11.16 13:31:23.915 1: HMLAN_Send:  HMLAN1 S:+1CF4FB,00,01,
2013.11.16 13:31:23.916 1: HMLAN_Send:  HMLAN1 S:S60E4EA4D stat:  00 t:00000000 d:01 r:60E4EA4D m:04 B001 DE7474 1CF4FB 00040000000000
2013.11.16 13:31:24.434 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF0442E4 d:FF r:FFC1     m:04 A010 1CF4FB DE7474 02020103990ADE0B740C740000
2013.11.16 13:31:24.548 1: HMLAN_Parse: HMLAN1 R:R60E4EA4D stat:0001 t:DF0442E9 d:FF r:FFC1     m:04 A010 1CF4FB DE7474 02020103990ADE0B740C740000
2013.11.16 13:31:24.638 1: HMLAN_Send:  HMLAN1 S:+1CF4FB,00,01,
2013.11.16 13:31:24.638 1: HMLAN_Send:  HMLAN1 S:S60E4ED20 stat:  00 t:00000000 d:01 r:60E4ED20 m:05 B001 DE7474 1CF4FB 0103
2013.11.16 13:31:25.180 1: HMLAN_Send:  HMLAN1 S:S60E4EF3E stat:  00 t:00000000 d:01 r:60E4EF3E m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:25.305 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF04464B d:FF r:FFC1     m:05 A010 1CF4FB DE7474 011D0528021D05280100000000
2013.11.16 13:31:25.423 1: HMLAN_Parse: HMLAN1 R:R60E4ED20 stat:0001 t:DF044650 d:FF r:FFC1     m:05 A010 1CF4FB DE7474 011D0528021D05280100000000
2013.11.16 13:31:25.783 1: HMLAN_Parse: HMLAN1 R:R60E4EF3E stat:0008 t:00000000 d:FF r:7FFF     m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:25.783 1: HMLAN_Parse: HMLAN1 no ACK from 1A5D71
2013.11.16 13:31:25.911 1: HMLAN_Send:  HMLAN1 S:S60E4F219 stat:  00 t:00000000 d:01 r:60E4F219 m:06 B001 DE7474 1CF4FB 01041D05280103
2013.11.16 13:31:26.430 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF044AB0 d:FF r:FFC1     m:06 A010 1CF4FB DE7474 0203000432056407FF0B66830084328564
2013.11.16 13:31:26.541 1: HMLAN_Parse: HMLAN1 R:R60E4F219 stat:0001 t:DF044AB5 d:FF r:FFC1     m:06 A010 1CF4FB DE7474 0203000432056407FF0B66830084328564
2013.11.16 13:31:26.673 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF044BA4 d:FF r:FFC1     m:07 A010 1CF4FB DE7474 0287FF8B660000
2013.11.16 13:31:26.677 1: HMLAN_Send:  HMLAN1 S:S60E4F517 stat:  00 t:00000000 d:01 r:60E4F517 m:07 B001 DE7474 1CF4FB 01041D05280203
2013.11.16 13:31:27.546 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF044F0C d:FF r:FFC1     m:07 A010 1CF4FB DE7474 0203000432056407FF0B33830084328564
2013.11.16 13:31:27.657 1: HMLAN_Parse: HMLAN1 R:R60E4F517 stat:0001 t:DF044F11 d:FF r:FFC1     m:07 A010 1CF4FB DE7474 0203000432056407FF0B33830084328564
2013.11.16 13:31:27.789 1: HMLAN_Parse: HMLAN1 R:E1CF4FB   stat:0000 t:DF045000 d:FF r:FFC1     m:08 A010 1CF4FB DE7474 0287FF8B880000
2013.11.16 13:31:28.101 1: HMLAN_Send:  HMLAN1 S:S60E4FAA7 stat:  00 t:00000000 d:01 r:60E4FAA7 m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:28.704 1: HMLAN_Parse: HMLAN1 R:R60E4FAA7 stat:0008 t:00000000 d:FF r:7FFF     m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:28.704 1: HMLAN_Parse: HMLAN1 no ACK from 1DB350
2013.11.16 13:31:30.262 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:DF0459A7 d:FF r:FFCB     m:36 A258 1CE75F 1CED2A 0200
2013.11.16 13:31:30.393 1: HMLAN_Parse: HMLAN1 R:E1CED2A   stat:0000 t:DF045A2A d:FF r:FFC1     m:36 8202 1CED2A 1CE75F 010100003F
2013.11.16 13:31:30.714 1: HMLAN_Send:  HMLAN1 S:S60E504DC stat:  00 t:00000000 d:01 r:60E504DC m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:31.317 1: HMLAN_Parse: HMLAN1 R:R60E504DC stat:0008 t:00000000 d:FF r:7FFF     m:02 A001 DE7474 1A5D71 010E
2013.11.16 13:31:31.318 1: HMLAN_Parse: HMLAN1 no ACK from 1A5D71
2013.11.16 13:31:33.403 1: HMLAN_Send:  HMLAN1 S:S60E50F5D stat:  00 t:00000000 d:01 r:60E50F5D m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:34.006 1: HMLAN_Parse: HMLAN1 R:R60E50F5D stat:0008 t:00000000 d:FF r:7FFF     m:03 A001 DE7474 1DB350 010E
2013.11.16 13:31:34.006 1: HMLAN_Parse: HMLAN1 no ACK from 1DB350
2013.11.16 13:31:36.111 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:31:36.114 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF04708B IDcnt:0003
2013.11.16 13:31:38.395 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF04796D d:FF r:FFCC     m:E1 8670 1CDFBA 000000 00A63A
2013.11.16 13:31:38.496 1: HMLAN_Send:  HMLAN1 S:S60E522DE stat:  00 t:00000000 d:01 r:60E522DE m:08 A112 DE7474 1CDFBA
2013.11.16 13:31:38.661 1: HMLAN_Parse: HMLAN1 R:R60E522DE stat:0001 t:DF047A7C d:FF r:FFCC     m:08 8002 1CDFBA DE7474 00
2013.11.16 13:31:38.662 1: HMLAN_Send:  HMLAN1 I:+1CDFBA,00,00,
2013.11.16 13:31:38.662 1: HMLAN_Send:  HMLAN1 S:S60E523E8 stat:  00 t:00000000 d:01 r:60E523E8 m:09 A001 DE7474 1CDFBA 010E
2013.11.16 13:31:39.065 1: HMLAN_Parse: HMLAN1 R:R60E523E8 stat:0001 t:DF047C12 d:FF r:FFCC     m:09 8002 1CDFBA DE7474 01020B0034
2013.11.16 13:31:39.066 1: HMLAN_Send:  HMLAN1 S:+1CDFBA,00,01,
2013.11.16 13:31:39.066 1: HMLAN_Send:  HMLAN1 S:S60E5257C stat:  00 t:00000000 d:01 r:60E5257C m:0A A001 DE7474 1CDFBA 020E
2013.11.16 13:31:39.470 1: HMLAN_Parse: HMLAN1 R:R60E5257C stat:0001 t:DF047DA8 d:FF r:FFCC     m:0A 8002 1CDFBA DE7474 01020B0034
2013.11.16 13:31:39.472 1: HMLAN_Send:  HMLAN1 S:+1CDFBA,00,01,
2013.11.16 13:31:39.472 1: HMLAN_Send:  HMLAN1 S:S60E52712 stat:  00 t:00000000 d:01 r:60E52712 m:0B A001 DE7474 1CDFBA 030E
2013.11.16 13:31:39.876 1: HMLAN_Parse: HMLAN1 R:R60E52712 stat:0001 t:DF047F3E d:FF r:FFCC     m:0B 8002 1CDFBA DE7474 01020B0034
2013.11.16 13:31:58.393 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF04C790 d:FF r:FFCC     m:E1 A258 1CDFBA 1CF1FC 0200
2013.11.16 13:31:58.526 1: HMLAN_Parse: HMLAN1 R:E1CF1FC   stat:0000 t:DF04C813 d:FF r:FFC5     m:E1 8202 1CF1FC 1CDFBA 0101000034
2013.11.16 13:32:01.115 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:32:01.118 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF04D23B IDcnt:0004
2013.11.16 13:32:06.759 1: HMLAN_Parse: HMLAN1 R:E1CE123   stat:0000 t:DF04E83D d:FF r:FFB5     m:61 8670 1CE123 000000 00AA41
2013.11.16 13:32:06.860 1: HMLAN_Send:  HMLAN1 S:S60E591AA stat:  00 t:00000000 d:01 r:60E591AA m:0C A112 DE7474 1CE123
2013.11.16 13:32:07.020 1: HMLAN_Parse: HMLAN1 R:R60E591AA stat:0001 t:DF04E947 d:FF r:FFB5     m:0C 8002 1CE123 DE7474 00
2013.11.16 13:32:07.021 1: HMLAN_Send:  HMLAN1 I:+1CE123,00,00,
2013.11.16 13:32:07.021 1: HMLAN_Send:  HMLAN1 S:S60E592AF stat:  00 t:00000000 d:01 r:60E592AF m:0D A001 DE7474 1CE123 010E
2013.11.16 13:32:07.421 1: HMLAN_Parse: HMLAN1 R:R60E592AF stat:0001 t:DF04EADB d:FF r:FFB5     m:0D 8002 1CE123 DE7474 01020B004C
2013.11.16 13:32:07.423 1: HMLAN_Send:  HMLAN1 S:+1CE123,00,01,
2013.11.16 13:32:07.423 1: HMLAN_Send:  HMLAN1 S:S60E59441 stat:  00 t:00000000 d:01 r:60E59441 m:0E A001 DE7474 1CE123 020E
2013.11.16 13:32:07.825 1: HMLAN_Parse: HMLAN1 R:R60E59441 stat:0001 t:DF04EC6F d:FF r:FFB5     m:0E 8002 1CE123 DE7474 01020B004B
2013.11.16 13:32:07.827 1: HMLAN_Send:  HMLAN1 S:+1CE123,00,01,
2013.11.16 13:32:07.827 1: HMLAN_Send:  HMLAN1 S:S60E595D5 stat:  00 t:00000000 d:01 r:60E595D5 m:0F A001 DE7474 1CE123 030E
2013.11.16 13:32:08.228 1: HMLAN_Parse: HMLAN1 R:R60E595D5 stat:0001 t:DF04EE02 d:FF r:FFB6     m:0F 8002 1CE123 DE7474 01020B004C
2013.11.16 13:32:26.132 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:32:26.135 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF0533F7 IDcnt:0005
2013.11.16 13:32:26.757 1: HMLAN_Parse: HMLAN1 R:E1CE123   stat:0000 t:DF053660 d:FF r:FFB6     m:61 A258 1CE123 1C494E 0200
2013.11.16 13:32:26.891 1: HMLAN_Parse: HMLAN1 R:E1C494E   stat:0000 t:DF0536E4 d:FF r:FFB5     m:61 8202 1C494E 1CE123 0101000033
2013.11.16 13:32:50.840 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:DF059475 d:FF r:FFC0     m:39 8670 1BF932 000000 00E034
2013.11.16 13:32:50.935 1: HMLAN_Send:  HMLAN1 S:S60E63DDC stat:  00 t:00000000 d:01 r:60E63DDC m:10 A112 DE7474 1BF932
2013.11.16 13:32:51.098 1: HMLAN_Parse: HMLAN1 R:R60E63DDC stat:0001 t:DF05957C d:FF r:FFC0     m:10 8002 1BF932 DE7474 00
2013.11.16 13:32:51.099 1: HMLAN_Send:  HMLAN1 I:+1BF932,00,00,
2013.11.16 13:32:51.100 1: HMLAN_Send:  HMLAN1 S:S60E63EDE stat:  00 t:00000000 d:01 r:60E63EDE m:11 A001 DE7474 1BF932 010E
2013.11.16 13:32:51.133 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:32:51.363 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF059687 IDcnt:0006
2013.11.16 13:32:51.502 1: HMLAN_Parse: HMLAN1 R:R60E63EDE stat:0001 t:DF059712 d:FF r:FFC0     m:11 8002 1BF932 DE7474 01022C0044
2013.11.16 13:32:51.503 1: HMLAN_Send:  HMLAN1 S:+1BF932,00,01,
2013.11.16 13:32:51.504 1: HMLAN_Send:  HMLAN1 S:S60E64071 stat:  00 t:00000000 d:01 r:60E64071 m:12 A001 DE7474 1BF932 020E
2013.11.16 13:32:51.907 1: HMLAN_Parse: HMLAN1 R:R60E64071 stat:0001 t:DF0598A7 d:FF r:FFC0     m:12 8002 1BF932 DE7474 01022C0044
2013.11.16 13:32:51.908 1: HMLAN_Send:  HMLAN1 S:+1BF932,00,01,
2013.11.16 13:32:51.909 1: HMLAN_Send:  HMLAN1 S:S60E64206 stat:  00 t:00000000 d:01 r:60E64206 m:13 A001 DE7474 1BF932 030E
2013.11.16 13:32:52.313 1: HMLAN_Parse: HMLAN1 R:R60E64206 stat:0001 t:DF059A3D d:FF r:FFC0     m:13 8002 1BF932 DE7474 01022C0044
2013.11.16 13:33:10.838 1: HMLAN_Parse: HMLAN1 R:E1BF932   stat:0000 t:DF05E298 d:FF r:FFC0     m:39 A258 1BF932 1C4DD3 0033
2013.11.16 13:33:10.971 1: HMLAN_Parse: HMLAN1 R:E1C4DD3   stat:0000 t:DF05E31A d:FF r:FFC4     m:39 8202 1C4DD3 1BF932 0101280033
2013.11.16 13:33:16.140 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:33:16.143 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF05F757 IDcnt:0006
2013.11.16 13:33:28.767 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:DF0628A1 d:FF r:FFCB     m:37 8670 1CE75F 000000 00A73F
2013.11.16 13:33:28.860 1: HMLAN_Send:  HMLAN1 S:S60E6D202 stat:  00 t:00000000 d:01 r:60E6D202 m:14 A112 DE7474 1CE75F
2013.11.16 13:33:29.020 1: HMLAN_Parse: HMLAN1 R:R60E6D202 stat:0001 t:DF0629A3 d:FF r:FFCB     m:14 8002 1CE75F DE7474 00
2013.11.16 13:33:29.021 1: HMLAN_Send:  HMLAN1 I:+1CE75F,00,00,
2013.11.16 13:33:29.021 1: HMLAN_Send:  HMLAN1 S:S60E6D2FF stat:  00 t:00000000 d:01 r:60E6D2FF m:15 A001 DE7474 1CE75F 010E
2013.11.16 13:33:29.421 1: HMLAN_Parse: HMLAN1 R:R60E6D2FF stat:0001 t:DF062B36 d:FF r:FFCC     m:15 8002 1CE75F DE7474 01020B0039
2013.11.16 13:33:29.422 1: HMLAN_Send:  HMLAN1 S:+1CE75F,00,01,
2013.11.16 13:33:29.422 1: HMLAN_Send:  HMLAN1 S:S60E6D490 stat:  00 t:00000000 d:01 r:60E6D490 m:16 A001 DE7474 1CE75F 020E
2013.11.16 13:33:29.824 1: HMLAN_Parse: HMLAN1 R:R60E6D490 stat:0001 t:DF062CC9 d:FF r:FFCB     m:16 8002 1CE75F DE7474 01020B0038
2013.11.16 13:33:29.825 1: HMLAN_Send:  HMLAN1 S:+1CE75F,00,01,
2013.11.16 13:33:29.825 1: HMLAN_Send:  HMLAN1 S:S60E6D623 stat:  00 t:00000000 d:01 r:60E6D623 m:17 A001 DE7474 1CE75F 030E
2013.11.16 13:33:30.227 1: HMLAN_Parse: HMLAN1 R:R60E6D623 stat:0001 t:DF062E5C d:FF r:FFCB     m:17 8002 1CE75F DE7474 01020B0038
2013.11.16 13:33:41.151 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:33:41.154 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF06590D IDcnt:0007
2013.11.16 13:33:48.765 1: HMLAN_Parse: HMLAN1 R:E1CE75F   stat:0000 t:DF0676C4 d:FF r:FFCB     m:37 A258 1CE75F 1CED2A 0200
2013.11.16 13:33:48.896 1: HMLAN_Parse: HMLAN1 R:E1CED2A   stat:0000 t:DF067746 d:FF r:FFC1     m:37 8202 1CED2A 1CE75F 010100003F
2013.11.16 13:33:52.647 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF0685F0 d:FF r:FFCC     m:E2 8670 1CDFBA 000000 00A63A
2013.11.16 13:34:06.166 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 13:34:06.169 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF06BAC9 IDcnt:0007
2013.11.16 13:34:12.647 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF06D413 d:FF r:FFCC     m:E2 A258 1CDFBA 1CF1FC 0200
2013.11.16 13:34:12.782 1: HMLAN_Parse: HMLAN1 R:E1CF1FC   stat:0000 t:DF06D497 d:FF r:FFC5     m:E2 8202


EDIT:
Da sind einige Missing Acks drin...
Die beiden devices mit "No Ack" sind Zwischenstecker, die gerade nicht am Netz sind. No Ack also ok.

martinp876

Hallo Thorsten,

erst einmal Glückwunsch - der Update scheint funktioniert zu haben :-)

dein SC hat die ID 1E7C35 - korrekt?
nein, ein SC meldet sich nicht automatisch nach HMLAN restart.

FHEM liest automatisch die Config, so du autoReadReg nicht abgeschaltet hast. gelesen wird immer, wenn FHEM der Meinung ist, dass es sich geändert hat oder nach restart - aber nur wenn es nicht aus dem Statefile gefüllt wird. Also kurz immer dann, wenn FHEM den Eindruck hat seine Daten seien nicht korrekt

Im aktuellen Log taucht der SC nicht auf.
Du könntest einmal ein getConfig an den SC senden und die rohmessages aufzeichnen - jetzt nach den Update. Irgendwann sollte sich der SC gemeldet haben (cyclicInfoMsg ist on?) spätestens nach einem Tag.
dann würde ich gerne nachsehen, was der SC so zu sagen hat und wie es FHEM präsentiert.

Gruss Martin

ThorstenH

Das Update hat geklappt, ja.. der TC im Wohnzimmer danach zwar desired-temp=off aber das habe ich glücklicherweise schneller gemerkt als meine Frau und schnell per Web Interface korrigiert  8).

List des SCs:
Internals:
   CHANGED   
   DEF        1E7C35
   EVENTS     16
   HMLAN1_MSGCNT 28
   HMLAN1_RAWMSG R61378158,0041,DF56DC66,01,FFC1,29A0101E7C35DE74740100000000
   HMLAN1_RSSI -63
   HMLAN1_TIME 2013-11-16 15:01:37
   IODev      HMLAN1
   LASTInputDev HMLAN1
   MSGCNT     28
   NAME       threestatesensor_test
   NR         81
   STATE      open
   TYPE       CUL_HM
   lastMsg    No:29 - t:10 s:1E7C35 d:DE7474 0100000000
   protLastRcv 2013-11-16 15:01:37
   protSnd    26 last_at:2013-11-16 15:01:36
   protState  CMDs_done
   rssi_at_HMLAN1 avg:-65.21 min:-82 max:-60 lst:-63 cnt:28
   Readings:
     2013-11-16 15:01:32   Activity        alive
     2013-11-16 15:01:35   PairedTo        0xDE7474
     2013-11-16 14:52:41   R-cyclicInfoMsg on
     2013-11-16 14:52:41   R-eventDlyTime  0 s
     2013-10-20 18:37:45   R-intKeyVisib   invisib
     2013-11-16 14:52:41   R-ledOnTime     0.5 s
     2013-11-16 14:52:41   R-msgScPosA     closed
     2013-11-16 14:52:41   R-msgScPosB     open
     2013-11-16 14:52:41   R-pairCentral   0xDE7474
     2013-11-16 14:52:41   R-sabotageMsg   on
     2013-11-16 14:52:41   R-transmDevTryMax 6
     2013-11-16 14:52:41   R-transmitTryMax 6
     2013-11-16 15:01:35   RegL_00:          02:01 09:01 0A:DE 0B:74 0C:74 10:01 14:06 00:00
     2013-11-16 15:01:36   RegL_01:          08:00 20:60 21:00 22:64 30:06 00:00
     2013-11-16 15:01:36   aesKeyNbr       FF
     2013-11-16 14:51:54   alive           yes
     2013-11-16 14:51:54   battery         ok
     2013-11-16 14:51:54   contact         open (to HMLAN1)
     2013-11-16 14:51:54   sabotageError   on
     2013-11-16 14:51:54   state           open
   Helper:
     mId        002F
     peerIDsRaw ,00000000
     rxType     4
     Prt:
       bErr       0
       sProc      0
       Rspwait:
     Q:
       qReqConf   
       qReqStat   
     Role:
       chn        1
       dev        1
     Rssi:
       At_hmlan1:
         avg        -65.2142857142857
         cnt        28
         lst        -63
         max        -60
         min        -82
     Shadowreg:
Attributes:
   actCycle   028:00
   actStatus  alive
   alias      Fensterkontakt Test
   autoReadReg 4_reqStatus
   event-on-change-reading contact,state
   expert     2_full
   firmware   2.0
   model      HM-SEC-SC
   peerIDs    00000000,
   room       CUL_HM,Test
   serialNr   JEQ0720401
   subType    threeStateSensor


Log des getconfig:
2013.11.16 15:01:32.864 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0000 t:DF56CC9D d:FF r:FFC2     m:8C 8400 1E7C35 000000 20002F4A45513037323034303180810101
2013.11.16 15:01:32.868 1: HMLAN_Send:  HMLAN1 S:+1E7C35,01,01,FE1F
2013.11.16 15:01:32.868 1: HMLAN_Send:  HMLAN1 S:S61377306 stat:  00 t:00000000 d:01 r:61377306 m:24 A001 DE7474 1E7C35 00040000000000
2013.11.16 15:01:33.036 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0100 t:DF56CD49 d:FF r:FFC1     m:24 A010 1E7C35 DE7474 02020109010ADE0B740C74100114060000
2013.11.16 15:01:33.403 1: HMLAN_Parse: HMLAN1 R:R61377306 stat:0041 t:DF56CD4E d:01 r:FFC1     m:24 A010 1E7C35 DE7474 02020109010ADE0B740C74100114060000
2013.11.16 15:01:33.441 1: HMLAN_Send:  HMLAN1 S:+1E7C35,01,01,FE1F
2013.11.16 15:01:33.441 1: HMLAN_Send:  HMLAN1 S:S61377543 stat:  00 t:00000000 d:01 r:61377543 m:25 A001 DE7474 1E7C35 01040000000001
2013.11.16 15:01:33.807 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0100 t:DF56D04D d:FF r:FFC2     m:25 A010 1E7C35 DE7474 02080020602100226430060000
2013.11.16 15:01:34.177 1: HMLAN_Parse: HMLAN1 R:R61377543 stat:0041 t:DF56D052 d:01 r:FFC2     m:25 A010 1E7C35 DE7474 02080020602100226430060000
2013.11.16 15:01:34.212 1: HMLAN_Send:  HMLAN1 S:S61377846 stat:  00 t:00000000 d:01 r:61377846 m:26 A001 DE7474 1E7C35 0103
2013.11.16 15:01:34.574 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0100 t:DF56D34D d:FF r:FFC2     m:26 A010 1E7C35 DE7474 0100000000
2013.11.16 15:01:34.951 1: HMLAN_Parse: HMLAN1 R:R61377846 stat:0041 t:DF56D352 d:01 r:FFC2     m:26 A010 1E7C35 DE7474 0100000000
2013.11.16 15:01:34.978 1: HMLAN_Send:  HMLAN1 S:+1E7C35,01,01,FE1F
2013.11.16 15:01:34.978 1: HMLAN_Send:  HMLAN1 S:S61377B44 stat:  00 t:00000000 d:01 r:61377B44 m:27 A001 DE7474 1E7C35 00040000000000
2013.11.16 15:01:35.358 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0100 t:DF56D65E d:FF r:FFC1     m:27 A010 1E7C35 DE7474 02020109010ADE0B740C74100114060000
2013.11.16 15:01:35.726 1: HMLAN_Parse: HMLAN1 R:R61377B44 stat:0041 t:DF56D663 d:01 r:FFC1     m:27 A010 1E7C35 DE7474 02020109010ADE0B740C74100114060000
2013.11.16 15:01:35.764 1: HMLAN_Send:  HMLAN1 S:+1E7C35,01,01,FE1F
2013.11.16 15:01:35.764 1: HMLAN_Send:  HMLAN1 S:S61377E56 stat:  00 t:00000000 d:01 r:61377E56 m:28 A001 DE7474 1E7C35 01040000000001
2013.11.16 15:01:36.129 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0100 t:DF56D961 d:FF r:FFC1     m:28 A010 1E7C35 DE7474 02080020602100226430060000
2013.11.16 15:01:36.500 1: HMLAN_Parse: HMLAN1 R:R61377E56 stat:0041 t:DF56D966 d:01 r:FFC1     m:28 A010 1E7C35 DE7474 02080020602100226430060000
2013.11.16 15:01:36.534 1: HMLAN_Send:  HMLAN1 S:S61378158 stat:  00 t:00000000 d:01 r:61378158 m:29 A001 DE7474 1E7C35 0103
2013.11.16 15:01:36.897 1: HMLAN_Parse: HMLAN1 R:E1E7C35   stat:0100 t:DF56DC61 d:FF r:FFC1     m:29 A010 1E7C35 DE7474 0100000000
2013.11.16 15:01:37.274 1: HMLAN_Parse: HMLAN1 R:R61378158 stat:0041 t:DF56DC66 d:01 r:FFC1     m:29 A010 1E7C35 DE7474 0100000000
2013.11.16 15:01:38.395 1: HMLAN_Send:  HMLAN1 I:K
2013.11.16 15:01:38.398 1: HMLAN_Parse: HMLAN1 V:03C1 sNo:IEQ0061959 d:139779 O:DE7474 t:DF56E244 IDcnt:0009
2013.11.16 15:01:39.091 1: HMLAN_Parse: HMLAN1 R:E1CDFBA   stat:0000 t:DF56E4F4 d:FF r:FFCC     m:04 A258 1CDFBA 1CF1FC 0200
2013.11.16 15:01:39.224 1: HMLAN_Parse: HMLAN1 R:E1CF1FC   stat:0000 t:DF56E577 d:FF r:FFC5     m:04 8202 1CF1FC 1CDFBA 0101000034

martinp876

Hi Thorsten,

das mit der desired-temp kann ich nur schwer dem update zuschreiben. so etwas darf nicht automatisch gesendet werden. Vielleicht hast du ein notify oder einen Eintrag im fhem.cfg? lässt sich jetzt schwer nachvollziehen.


kannst du jetzt noch ein getConfig machen und warten bis/ ob es von selbst abgeholt wird? Also ohne Anlernen drücken? Einfach über nacht Stehen lassen - bis 24h um sind oder die pending commands weg sind.

Gruss Martin


ThorstenH

So, alles erst mal stehengelassen. Um ca. 2013.11.16 17:34 GetConfig angefordert. Ausschnitt Logfile habe ich hochgeladen.
Heute um 14:54 kam übrigens wieder eine mail vom watchdog. Hier noch ein list des SCs (gerade eben gemacht).

Internals:
   DEF        1E7C35
   EVENTS     19
   HMLAN1_MSGCNT 34
   HMLAN1_RAWMSG E1E7C35,0040,E99CF017,01,FFBD,8FA6101E7C35DE74740601C800
   HMLAN1_RSSI -67
   HMLAN1_TIME 2013-11-18 14:53:57
   IODev      HMLAN1
   LASTInputDev HMLAN1
   MSGCNT     34
   NAME       threestatesensor_test
   NR         81
   STATE      open
   TYPE       CUL_HM
   lastMsg    No:8F - t:10 s:1E7C35 d:DE7474 0601C800
   protCmdDel 3
   protLastRcv 2013-11-18 14:53:57
   protResndFail 1 last_at:2013-11-17 15:02:52
   protSnd    30 last_at:2013-11-18 14:53:56
   protState  CMDs_done_Errors:1
   rssi_at_HMLAN1 avg:-65.35 min:-82 max:-59 lst:-67 cnt:34
   Readings:
     2013-11-16 15:01:32   Activity        alive
     2013-11-16 15:01:35   PairedTo        0xDE7474
     2013-11-16 14:52:41   R-cyclicInfoMsg on
     2013-11-16 14:52:41   R-eventDlyTime  0 s
     2013-10-20 18:37:45   R-intKeyVisib   invisib
     2013-11-16 14:52:41   R-ledOnTime     0.5 s
     2013-11-16 14:52:41   R-msgScPosA     closed
     2013-11-16 14:52:41   R-msgScPosB     open
     2013-11-16 14:52:41   R-pairCentral   0xDE7474
     2013-11-16 14:52:41   R-sabotageMsg   on
     2013-11-16 14:52:41   R-transmDevTryMax 6
     2013-11-16 14:52:41   R-transmitTryMax 6
     2013-11-18 14:53:56   aesKeyNbr       FF
     2013-11-18 14:53:56   alive           yes
     2013-11-18 14:53:56   battery         ok
     2013-11-18 14:53:56   contact         open (to HMLAN1)
     2013-11-18 14:53:56   sabotageError   off
     2013-11-18 14:53:56   state           open
     Regl_00::
       VAL       
   Helper:
     getCfgList all
     getCfgListNo 4
     mId        002F
     peerIDsRaw ,00000000
     rxType     4
     Prt:
       bErr       1
       sProc      0
       Rspwait:
     Q:
       qReqConf   
       qReqStat   
     Role:
       chn        1
       dev        1
     Rssi:
       At_hmlan1:
         avg        -65.3529411764706
         cnt        34
         lst        -67
         max        -59
         min        -82
     Shadowreg:
Attributes:
   actCycle   028:00
   actStatus  alive
   alias      Fensterkontakt Test
   autoReadReg 4_reqStatus
   event-on-change-reading contact,state
   expert     2_full
   firmware   2.0
   model      HM-SEC-SC
   peerIDs    00000000,
   room       CUL_HM,Test
   serialNr   JEQ0720401
   subType    threeStateSensor

martinp876

Hallo Thorsten,

dein Sensor schickt aktuell etwa um 15:00 eine statusmeldung.
Was mich stört ist, dass FHEM erst anch 500ms einen request an den SC schickt. Es kann jetzt sein, dass der SC schon wieder schläft oder generell nicht wirklich "aufgewacht" ist.

mit der Aktuellen Version kannst du etwas selektiver loggen. Setze

attr <hmlan> logIDs 1E7C35,sys

macht das Log viel kürzer.
Du musst, wenn du updatest und den Test wiederholst wakeup einschalten. Also im File HMConfig.pm in der Zeile 95 ("002F" => {name=>"HM-SEC-SC"   )
,rxt=>'c'
gegen
,rxt=>'c:w'

Gruss Martin
ersetzen musst

ThorstenH

#10
Habe ich alles gemacht. Wird die Einstellung in HMConfig.pm beim nächsten Update wieder überschrieben oder muss ich selbst daran denken, wieder zurückzustellen?

Mit der letzten Version hatte ich übrigens Probleme. Als ich heute nach Hause kam, war noch alles in Ordnung (warm). Dann habe ich die Fenster aufgemacht (SCs) und die TCs sind auf desired-temp 6.0 gegangen (so konfiguriert). Nach ein paar Minuten schaue ich nochmals drauf, da stehen die TCs auf 18°. Aber das mit den SCs in Verbindung mit den TCs hat bei mir noch nie zuverlässig geklappt. Inzwischen gehe ich dazu über, nach dem Fenster öffnen auf desired-temp "off" zu stellen (am Gerät). Das ist immer noch die zuverlässigste Methode, die Heizung wirklich auszustellen (ohne dass sie wieder von alleine angeht). Alles immer in Manuell.

Da musst du jetzt nicht groß nachforschen, ich wollte nur alles berichten, was mir im Laufe der Tests so auffällt. Habe sowieso den Logfile dummerweise gelöscht.  :-[

Ich mache dann mal wieder ein getconfig und warte ab. Steht aber nicht mehr viel drin im Log, oder? Nach dem update und Neustart gerade mal:
2013.11.19 20:18:04.607 1: Including fhem.cfg
2013.11.19 20:18:04.618 1: HMLAN_Parse: HMLAN1 new condition disconnected
2013.11.19 20:18:04.622 1: HMLAN_Parse: HMLAN1 new condition init
2013.11.19 20:18:04.766 1: Including ./log/fhem.save
2013.11.19 20:18:04.847 1: HMLAN_Parse: HMLAN1 new condition ok


Was mir noch aufgefallen ist: meine VD Logs sind im Schlafzimmer und im Kinderzimmer ca. 14 MB groß. Die von Küche und Wohnzimmer ca. 7 MB (alles seit 2013-09-21). Der Unterschied: in Wohnzimmer und Küche habe ich alles mit fhem gepairt und über fhem gepeert. Im Schlafzimmer und Kinderzimmer bin ich noch anders vorgegangen: erst alles ohne fhem gepairt und gepeert, dann ganz zum Schluss per hmpairforsec und Taste drücken an fhem angebunden. Letzteres sorgt für doppelt so große Logfiles, warum auch immer. Muss ich noch genauer untersuchen...

EDIT:
Der VD im Kinderzimmer/Schlafzimmer (14 MB) meldet sich alle 2-3 Minuten. Die VDs in der Küche ca. alle 7 Minuten. In der Bedienungsanleitung steht, dass bei Verwendung eines SCs öfter Telegramme ausgetauscht werden, damit der TC schneller auf ein offenes Fenster reagieren kann. Hängt vielleicht damit zusammen?

martinp876

Hallo Thorsten,

zu den SCs und der TC-temp: Ich schreiben mir ALLE trigger in EIN logfile, damit ich es später nachvollziehen kann. Wenn man überall wo möglich event-on-change .* einbaut get dies (ausser das setzen des VD-status...). Wenn du so ein file hast kann man nachträglich auch einmal versuchen zu verstehen, warum  der TC rückschaltet.

warum der TC manchmal nur alle 7 min einen update schickt ist mir nicht klar. Wenn mehrere VDs gepeert sind könnte so etwas sein, muss ich aber testen. Dass es etwas mit den SC zu tun haben könnte halte ich für unwahrscheinlich... Vielleicht komme ich beim Testen auf etwas...

für die tests mit dem SC müsstest du bis auf weiteres nach dem Update die Zeile Ändern. Das schalte ich erst wieder auf wakeup wenn es auch klappt.

Danke
Martin

ThorstenH

ZitatALLE trigger in EIN logfile
Da habe ich gleich ein paar Fragen:

  • Du meinst die Logfiles, die autocreate für jedes Gerät erzeugt hat? Umbiegen auf genau ein Logfile?
  • Warum geht das nur, wenn ich "event-on-change .*" eingetragen habe? Performancegründe?
  • Kann es passieren, dass mehrere Geräte gleichzeitig in eine Datei schreiben wollen oder geschieht das auf jeden Fall immer sequentiell?

  • Was ist mit meinen Plots? Im Moment kann ich ja bei meinem TC bei "thermostat_wohnzimmer-2013.log" auf "Plot" klicken und sehe die Temperatur Plots. Welche Plots sehe ich, wenn alles in eine Datei schreibt?
  • Wie ändere ich überhaupt den Logfile für ein Gerät? Im Web Interface habe ich nichts gefunden.

---
ZitatDass es etwas mit den SC zu tun haben könnte halte ich für unwahrscheinlich
Ich meine ich folgendes:

Ohne fhem: wenn ich am TC einen SC anlerne, dann wird die Kommunikationsrate laut Bedienungsanleitung erhöht, damit schneller auf ein offenes Fenster reagiert wird. Ich vermute, der TC "macht" diese Einstellung beim Anlernen (wo auch immer).

Mit fhem: da ging das Pairing und Peering über fhem (der einzige Unterschied). Vielleicht geht bei dieser Vorgehensweise der TC davon aus, dass fhem dafür sorgt, dass diese Einstellung (erhöhte Kommunikationsrate) getätigt wird.

---
Das getconfig kommt später, ist noch pending.

martinp876

   
ZitatDu meinst die Logfiles, die autocreate für jedes Gerät erzeugt hat? Umbiegen auf genau ein Logfile?
Inhaltlich ja, technisch nein. Also das ist MEINE Ansicht...:
je mehr files du offen hältst um so mehr performance braucht das system
je mehr notifies du hast um so mehr performance braucht das System.
Autocreate logfile werden für jeden entity gemacht, jeden Button. Halte ich nicht für sinnvoll.
=> besonders wenn dein System wächst überlege, welche logfiles du haben willst. Wenn du graphen erzeugen willst ist eins für die temp-werte sinnvoll.
wenn du ein log brauchst um evtl etwas nachzuschauen - mache eins für das gesamte System. Biege NICHT alle notifies um, kommentiere sie aus und mache EINES.

   
ZitatWarum geht das nur, wenn ich "event-on-change .*" eingetragen habe? Performancegründe?
absolut. Das logfile geht immer - bläht sich aber gewaltig auf mit den gleichen Werten, wenn du event-on-update zulässt.
Beachte, dass JEDES event einen trigger auslöst und ALLE notifies abklappern muss, ob es zutrifft. das kostet (unabhängig von logfiles) performance. Bis auf sehr wenige Ausnahmen (motion detector "motion") sollte daher event-on-change .* default sein.

   
ZitatKann es passieren, dass mehrere Geräte gleichzeitig in eine Datei schreiben wollen oder geschieht das auf jeden Fall immer sequentiell?
gleichzeitig ist relativ. fhem ist single-threated - alles ist sequenziell. Ausserdem wird das schreiben vom OS (Linux?) gemacht. Das ist wasserdicht, wenn fhem ein paar regeln einhält - und da habe ich keine Bedenken

 
ZitatWas ist mit meinen Plots? Im Moment kann ich ja bei meinem TC bei "thermostat_wohnzimmer-2013.log" auf "Plot" klicken und sehe die Temperatur Plots. Welche Plots sehe ich, wenn alles in eine Datei schreibt?
für die Plots würde ich eine separate Datei spendieren. Da sollte ausschliesslich die zu plotenden Daten hinein. Das file sollte so klein wie möglich gehalten werden - das macht das erstellen des Plots schneller und spart platz auf der Platte. Ist auch eine Frage, wie lange man die Daten aufheben will.
Das allgemeine log will man nicht ewig speichern, nehme ich an. Ein paar Wochen sollte reichen um etwas nachzuvollziehen.

ZitatWie ändere ich überhaupt den Logfile für ein Gerät? Im Web Interface habe ich nichts gefunden.
das logfile funktioniert (fast) wie ein notify.
Ich würde erst alle logfiles auskommentieren (oder löschen)
log für alle events:
#logs für alle events
define log_all FileLog log/all_%Y.log .*
attr log_all logtype text
attr log_all room Log,Common

#log fuer TC plots
define thLog FileLog log/thLog%Y.log th:.*T:.*
attr thLog logtype temp4hum6:temp/hum
attr thLog room Log,Heating

#log fuer RT plots - alle rts in ein file
define log_h_ab FileLog log/log_h_ab%Y.log h_.*_Clima:.*T:.*
attr log_h_ab logtype temp4hum6:temp/hum
attr log_h_ab room Log,Heating



Das aufteilen der logs für plots auf mehrere files könnte sicher sinn manchen. Aber es braucht nicht jeder RT/TC ein logfile

Anlernen eines SC:
wenn ein SC angelernt wird schaltet der TC burst-receive ein. Der SC sendet einen "burst" um ALLE burst-fähigen Devices aufzuwecken. Dann kommt die message, alle schauen nach, obes ihre Adresse ist, wenn nicht weiter schlafen(Batterie sparen). Beim Sender ist für jeden peer einzutragen, ob der Empfänger "burst" braucht (peerNeedsBurst).
Devices die auf burst reagieren sind i.a. batterie-devices - da will man enerie sparen. Burst kann man immer senden, sollte man aber nicht - man weckt immer den ganzen Schlafsaal auf - und spricht dann nur mit einem. Das kostet ALLEN Batterie.

Zum TC- der macht das nur mit, wenn notwendig. Mit dem VD legt er fest, wann gequasselt wird. Die Zentrale kann warten bis er aufwacht. Aber der SC will sofort - also wird dann burst freigeschaltet (kannst du über ein Register im TC und RT steuern).

Dann kann auch die Zentrale, so sie will, einen burst auslösen und sofort kommandos auslösen.... soll man aber nicht dauernd machen.

Gruss Martin

ThorstenH

Der SC hat sich gemeldet. Ich habe noch nicht alles auf eine Logdatei umgestellt, daher habe ich alles zusammengeschnippelt.
GL = global log file
SC = threestate sensor log file
** = Kommentar von mir


GL 2013.11.19 20:18:04.607 1: Including fhem.cfg
GL 2013.11.19 20:18:04.618 1: HMLAN_Parse: HMLAN1 new condition disconnected
GL 2013.11.19 20:18:04.622 1: HMLAN_Parse: HMLAN1 new condition init
GL 2013.11.19 20:18:04.766 1: Including ./log/fhem.save
GL 2013.11.19 20:18:04.847 1: HMLAN_Parse: HMLAN1 new condition ok
** global log ist hier zu Ende
** 2013.11.19 20:35    GetConfig angefordert
SC 2013-11-20_20:20:16 threestatesensor_test ResndFail
SC 2013-11-20_20:20:16 threestatesensor_test RESPONSE TIMEOUT:RegisterRead
** 2013.11.21 18:11    Mail ausgelöst
SC 2013-11-21_20:11:19 threestatesensor_test open


Das war's. nicht sehr viel log...

Um nicht durcheinnderzukommen, bei dem ganzen Geschreibe über die TCs und SCs: der SC "threestatesensor_test" ist mit keinem TC verbunden und dient nur zu Testzwecken. Bei meinen Tests ist der ständig "open".