FHEM Forum

FHEM => Automatisierung => Thema gestartet von: abc2006 am 06 April 2016, 13:42:49

Titel: ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 13:42:49
Hallo,
ich habe seit einigen Wochen Probleme mit massivem Delay zwischen dem Betätigen des Lichtschalters und dem schalten des Aktors.
Konkret geht es dabei um Homematic Wired. Wenn ich auf einen Taster drücke, der am 12/7 angeschlossen ist, schaltet das licht irgendwann zwischen sofort und nie. Oft liegt der Zeitbereich zwischen 2 und 5 sekunden, aber viel zu oft auch darüber. Ich habe schon folgende Dinge versucht:

- fhem update: erfolgreich, tagesaktuell
- ubuntu update: erfolgreich, tagesaktuell ( Intel Atom, 4 GB RAM)
- neue fhem.cfg ( alte umbenannt, neue angelegt): gleicher Fehler
- owserver upgedated( selbst kompiliert, auf version 3.1): gleicher Fehler
- dabei über die ausgabe von apptime gewundert:https://forum.fhem.de/index.php/topic,51854.0.html (https://forum.fhem.de/index.php/topic,51854.0.html)
- owserver wieder downgegraded : gleicher Fehler
- Homematic-Komponenten stromlos gemacht, 30sek gewartet, wieder eingeschaltet: gleicher Fehler
- Homematic-Komponenten bis auf einen 12/7 vom Gateway getrennt ( Bus-Leitungen (beide) abgeklemmt): gleicher Fehler
- festgestellt, dass die Festplatte vollgelaufen ist: die Updates haben die sqlite-Datenbank gesichert. Festplatte vergrößert: gleiches Fehlerbild.
- festgestellt, dass von den 4 eingebauten GB nur 768 verwendet werden: im BIOS die RAM-Zuweisung verändert.
- SQLite verdächtigt, LOG deaktiviert ( in der fhem.cfg): gleiches Fehlerbild.
- managbarer Switch(Netgear GS724T): STP deaktiviert: neues Fehlerbild:
##einmal drücken: schaltet sofort
##zweites mal drücken: schaltet sofort
##3. mal drücken: es tut sich nichts.
##Nach 5 Sekunden, in denen ich drücken kann, sooft ich will, fängt das spiel wieder von vorne an.
zur Zeit reproduzierbar.
Umstecken der Verbindung HM-Gateway - FHEM vom Switch auf die Fritzbox:  gleiches Fehlerbild
- event-Monitor:
Ich habe einen Taster genommen und von 13:24:09 bis 13:24:20 ca 2-3 mal pro Sekunde gedrückt:
2016-04-06 13:24:01.537 HM485 HMW02.I07 leistung: 2139
2016-04-06 13:24:09.232 HM485 HMW02.I07 literpromin: 17.5179784629291
2016-04-06 13:24:09.703 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:09.786 HM485 HMW01.I01 press_short: 34
2016-04-06 13:24:09.786 HM485 HMW01.I01 press_short_34
2016-04-06 13:24:09.825 HM485 HMW01.O04 set_toggle
2016-04-06 13:24:09.875 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:09.914 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:09.997 HM485 HMW01.O04 off
2016-04-06 13:24:09.997 HM485 HMW01.O04 working: off
2016-04-06 13:24:12.643 HM485 HMW02.I07 leistung: 2130
2016-04-06 13:24:15.004 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:15.075 HM485 HMW01.I01 press_short: 35
2016-04-06 13:24:15.075 HM485 HMW01.I01 press_short_35
2016-04-06 13:24:15.823 HM485 HMW01.O04 set_toggle
2016-04-06 13:24:15.865 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:15.906 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:15.976 HM485 HMW01.I01 press_short: 36
2016-04-06 13:24:15.976 HM485 HMW01.I01 press_short_36
2016-04-06 13:24:16.009 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.043 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.113 HM485 HMW01.I01 press_short: 37
2016-04-06 13:24:16.113 HM485 HMW01.I01 press_short_37
2016-04-06 13:24:16.154 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.187 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.234 HM485 HMW01.O04 working: off
2016-04-06 13:24:16.234 HM485 HMW01.O04 on
2016-04-06 13:24:16.276 HM485 HMW01.O04 set_toggle
2016-04-06 13:24:16.309 HM485 HMW01.O04 set_toggle
2016-04-06 13:24:16.343 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.413 HM485 HMW01.I01 press_short: 38
2016-04-06 13:24:16.413 HM485 HMW01.I01 press_short_38
2016-04-06 13:24:16.443 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.480 HM485 HMW01.O04 set_toggle
2016-04-06 13:24:16.520 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 13:24:16.568 HM485 HMW01.O04 off

Als Gegenprobe habe ich einen FS20-S4U verwendet:
2016-04-06 13:26:56.227 FS20 FS20_Garage on
2016-04-06 13:26:56.702 FS20 FS20_Garage on
2016-04-06 13:26:57.175 FS20 FS20_Garage on
2016-04-06 13:26:57.767 FS20 FS20_Garage on
2016-04-06 13:26:58.288 FS20 FS20_Garage on
2016-04-06 13:26:58.669 FS20 FS20_Garage on
2016-04-06 13:26:59.193 FS20 FS20_Garage on
2016-04-06 13:26:59.789 FS20 FS20_Garage on
2016-04-06 13:27:00.220 FS20 FS20_Garage on
2016-04-06 13:27:00.726 FS20 FS20_Garage on
2016-04-06 13:27:01.216 FS20 FS20_Garage on
2016-04-06 13:27:01.712 FS20 FS20_Garage on
2016-04-06 13:27:02.272 FS20 FS20_Garage on
2016-04-06 13:27:02.784 FS20 FS20_Garage on
2016-04-06 13:27:03.315 FS20 FS20_Garage on
2016-04-06 13:27:03.757 FS20 FS20_Garage on
2016-04-06 13:27:04.242 FS20 FS20_Garage on
2016-04-06 13:27:04.817 FS20 FS20_Garage on
2016-04-06 13:27:05.750 FS20 FS20_Garage on
2016-04-06 13:27:05.797 FS20 FS20_Garage on
2016-04-06 13:27:06.159 FS20 FS20_Garage on
2016-04-06 13:27:06.853 FS20 FS20_Garage on
2016-04-06 13:27:08.815 FS20 FS20_Garage on

Also liegts doch vermutlich nicht an fhem...

also hab ich nen tcpdump gemacht, um herauszufinden, obs am LAN liegt:
 13:30:51.375884 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 2044516721:2044516739, ack 2882156965, win 4380, length 18
13:30:51.375916 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 18, win 29200, length 0
13:30:54.767453 ARP, Request who-has 192.168.0.5 tell 192.168.0.30, length 28
13:30:54.767551 ARP, Reply 192.168.0.5 is-at 00:1a:22:04:0a:c1, length 46
13:30:56.604983 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 18:36, ack 1, win 4380, length 18
13:30:56.605029 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 36, win 29200, length 0
13:31:00.035066 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 36:53, ack 1, win 4380, length 17
13:31:00.035111 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 53, win 29200, length 0
13:31:00.060057 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 53:82, ack 1, win 4380, length 29
13:31:00.060103 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 82, win 29200, length 0
13:31:00.164680 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [P.], seq 1:18, ack 82, win 29200, length 17
13:31:00.170002 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [.], ack 18, win 4380, length 0
13:31:00.202093 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 82:91, ack 18, win 4380, length 9
13:31:00.202138 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 91, win 29200, length 0
13:31:00.436080 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 91:108, ack 18, win 4380, length 17
13:31:00.436138 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 108, win 29200, length 0
13:31:00.461211 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 108:137, ack 18, win 4380, length 29
13:31:00.461258 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 137, win 29200, length 0
13:31:00.589294 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [P.], seq 18:35, ack 137, win 29200, length 17
13:31:00.594623 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [.], ack 35, win 4380, length 0
13:31:00.626112 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 137:146, ack 35, win 4380, length 9
13:31:00.626157 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 146, win 29200, length 0
13:31:01.238115 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 146:163, ack 35, win 4380, length 17
13:31:01.238170 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 163, win 29200, length 0
13:31:01.262892 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 163:192, ack 35, win 4380, length 29
13:31:01.262938 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 192, win 29200, length 0
13:31:01.413241 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [P.], seq 35:52, ack 192, win 29200, length 17
13:31:01.418567 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [.], ack 52, win 4380, length 0
13:31:01.450130 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 192:201, ack 52, win 4380, length 9
13:31:01.450176 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 201, win 29200, length 0
13:31:01.955962 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 201:218, ack 52, win 4380, length 17
13:31:01.956019 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 218, win 29200, length 0
13:31:01.981108 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 218:247, ack 52, win 4380, length 29
13:31:01.981156 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 247, win 29200, length 0
13:31:02.100376 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [P.], seq 52:69, ack 247, win 29200, length 17
13:31:02.105710 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [.], ack 69, win 4380, length 0
13:31:02.136152 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 247:256, ack 69, win 4380, length 9
13:31:02.136197 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 256, win 29200, length 0
13:31:03.944193 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 256:274, ack 69, win 4380, length 18
13:31:03.944253 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 274, win 29200, length 0
13:31:07.132271 IP 192.168.0.5.1000 > 192.168.0.30.50442: Flags [P.], seq 274:292, ack 69, win 4380, length 18
13:31:07.132308 IP 192.168.0.30.50442 > 192.168.0.5.1000: Flags [.], ack 292, win 29200, length 0
das waren 10 mal drücken, die ersten vier haben einen Aktor ausgelöst, die folgenden 6 sind "verloren gegangen". Ich hab aber zu wenig Ahnung vom Protokoll, um da was rein interpretieren zu können...

bliebe noch, einen Reset vom LAN-Gateway zu machen.. aber ich habe sorge, dass mir das mein System dann komplett zerschießt ( momentan gehts ja noch halbwegs)... Ich such mal nach ner Anleitung, ggf muss ich Ersatz kaufen.
was mir grade noch einfällt: ich hab nen Raspi, da installiere ich gleich mal fhem..


Ja, und da werde ich jetzt echt ratlos. Ich hab sonst keine Idee mehr... Kann jemand helfen?
Wenn noch Informationen gewünscht werden, sagt Bescheid, vielleicht hab ich was vergessen. Oben steht soweit alles, was ich bisher versucht habe.

Danke!
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 06 April 2016, 15:12:14
Hi,
wie sind denn die Taster mit den Aktoren verbunden? Sind die Dinger direkt gepeert oder geht da was über notify oder so?
Wahrscheinlich wäre es das beste, wenn Du das hier in den Homematic-Bereich verschiebst.
Dann bitte auch gleich die Ausgabe von "version" hinzufügen.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 15:24:09
Hi Thorsten,

Das geht ausschließlich über notify, habe keinerlei direkte peerings drin.

Hier das erste notify als Beispiel:
HMW01.I01:press_long:.* set FS20_Garage onHMW01.I01:press_short:.* set FS20_Garage offSo habe ich das für jeden Eingang meines Homematic angelegt, mit verschiedenen set-Befehlen natürlich.

#version:
Latest Revision: 11184

File                     Rev   Last Change

fhem.pl                  11191 2016-04-05 11:55:05Z rudolfkoenig
96_allowed.pm            11184 2016-04-04 08:24:33Z rudolfkoenig
98_apptime.pm             8074 2015-02-22 19:31:19Z betateilchen
90_at.pm                 11183 2016-04-04 08:22:42Z rudolfkoenig
98_autocreate.pm         11094 2016-03-20 09:41:03Z rudolfkoenig
00_CUL.pm                10855 2016-02-15 11:30:02Z rudolfkoenig
15_CUL_EM.pm              6825 2014-10-28 09:25:24Z arnoaugustin
09_CUL_FHTTK.pm           7529 2015-01-11 18:52:43Z matscher
14_CUL_WS.pm             10794 2016-02-11 06:26:06Z rudolfkoenig
93_DbLog.pm              10796 2016-02-11 09:11:41Z rapster
98_DOIF.pm               10985 2016-03-03 17:25:24Z damian-s
98_dummy.pm              10530 2016-01-16 19:27:21Z rudolfkoenig
91_eventTypes.pm         10530 2016-01-16 19:27:21Z rudolfkoenig
01_FHEMWEB.pm            11178 2016-04-03 14:20:32Z rudolfkoenig
92_FileLog.pm            10530 2016-01-16 19:27:21Z rudolfkoenig
10_FS20.pm               10984 2016-03-03 08:59:47Z rudolfkoenig
98_help.pm               10809 2016-02-13 09:51:16Z betateilchen
# $Id: 10_HM485.pm 0743 2016-02-26 09:02:00Z Thorsten Pferdekaemper $
No Id found for 00_HM485_LAN.pm
12_HMS.pm                 5097 2014-03-02 15:25:08Z rudolfkoenig
No Id found for hmw_io12_fm.pm
No Id found for hmw_io12_sw14_dr.pm
No Id found for hmw_io12_sw7_dr.pm
No Id found for hmw_io12_sw7_dr_v3_02.pm
No Id found for hmw_io12_sw7_dr_V3_02.pm
No Id found for hmw_io_12_fm.pm
No Id found for hmw_sen_sc_12_dr.pm
98_JsonList2.pm          10530 2016-01-16 19:27:21Z rudolfkoenig
No Id found for 99_MyUtils.pm
91_notify.pm             11178 2016-04-03 14:20:32Z rudolfkoenig
11_OWDevice.pm           11137 2016-03-28 14:07:20Z borisneubert
10_OWServer.pm           11137 2016-03-28 14:07:20Z borisneubert
No Id found for 99_perfmon.pm
98_PID20.pm              10722 2016-02-04 17:12:18Z john99sr
99_SUNRISE_EL.pm         10569 2016-01-19 06:30:28Z rudolfkoenig
98_SVG.pm                10792 2016-02-10 20:20:49Z rudolfkoenig
50_TelegramBot.pm        11090 2016-03-19 21:38:31Z viegener
98_telnet.pm             10551 2016-01-17 11:03:44Z rudolfkoenig
99_Utils.pm              10570 2016-01-19 06:39:23Z rudolfkoenig
98_version.pm            11026 2016-03-08 07:25:45Z markusbloch

No Id found for ConfigurationManager.pm
No Id found for Constants.pm
DevIo.pm                 11020 2016-03-07 12:01:43Z rudolfkoenig
No Id found for FhemWebHelper.pm
No Id found for hmw-sen-sc-12.pm
No Id found for hmw_central.pm
No Id found for hmw_generic.pm
No Id found for hmw_io_4_fm.pm
No Id found for hmw_io_4_fm_v3_02.pm
No Id found for hmw_io_4_fm_V3_02.pm
No Id found for hmw_io_sr_fm.pm
No Id found for hmw_lc_bl1_dr.pm
No Id found for hmw_lc_bl1_dr_v3_02.pm
No Id found for hmw_lc_bl1_dr_V3_02.pm
No Id found for hmw_lc_dim1l_dr.pm
No Id found for hmw_lc_sw2_dr.pm
No Id found for hmw_lc_sw2_dr_v3_02.pm
No Id found for hmw_lc_sw2_dr_V3_02.pm
No Id found for hmw_virtual.pm
HttpUtils.pm             11154 2016-03-30 08:57:41Z rudolfkoenig
# $Id: OWNet.pm,v 1.24 2013/02/02 11:41:28 alfille Exp $
No Id found for PeeringManager.pm
RTypes.pm                10476 2016-01-12 21:03:33Z borisneubert
SetExtensions.pm         10858 2016-02-15 17:42:13Z rudolfkoenig
TcpServerUtils.pm        10346 2016-01-03 12:34:27Z rudolfkoenig
TimeSeries.pm            10907 2016-02-21 17:38:02Z borisneubert
No Id found for Util.pm

Leider habe ich keinen blassen Dunst, wie ich den Beitrag verschiebe ... ??
Danke
Stephan


PS: wenn ich im Web-Frontend die Ausgänge schalte, scheint es, als würde alles einwandfrei funktionieren...
Deutet mit den fehlenden Events auf Probleme mit dem Eingang hin. Möchtest du auch noch einen Auszug aus dem Log haben?


Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 15:37:01
Loglevel 5, habe umm 15:27:50 mehrfach den Taster gedrückt, Licht hat auch geschaltet, aber nicht so oft, wie ich gedrückt habe:

[code]
2016.04.06 15:27:47.599 4: Connection closed for WEB_192.168.0.29_57516: EOF
2016.04.06 15:27:47.756 4: WEB_192.168.0.29_57614 GET /fhem?XHR=1&inform=type=status;filter=;since=1459949266;fmt=JSON&fw_id=561&timestamp=1459949267752; BUFLEN:0
2016.04.06 15:27:48.655 4: Connection accepted from WEB_192.168.0.29_57616
2016.04.06 15:27:48.656 4: WEB_192.168.0.29_57616 GET /fhem?XHR=1&inform=type=raw;withLog=0;filter=.*&timestamp=1459949268650; BUFLEN:0
2016.04.06 15:27:48.732 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 82 Cmd: 101
2016.04.06 15:27:48.733 4: MyHMLAN: Event:HASH(0x58ae780)
2016.04.06 15:27:48.733 5: MyHMLAN dispatch \FDRe\00\00\00^\00\00\9A+i\00:\F6
2016.04.06 15:27:48.733 5: MyHMLAN: HM485_Parse: MsgId: 82
2016.04.06 15:27:48.733 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:48.733 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003AF6
2016.04.06 15:27:48.736 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:48.737 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:48.740 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:48.742 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:48.742 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15094
2016.04.06 15:27:48.743 5: HM485: HM485:Device:dataConversion: retVal = 15094.00
2016.04.06 15:27:48.743 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15094.00
2016.04.06 15:27:48.743 5: HM485: Device:valueToControl: valName = frequency = 15094.00
2016.04.06 15:27:48.743 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003AF6 actionType = frame
2016.04.06 15:27:48.745 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:27:48.745 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15094.00 Alter Wert = 15384.00
2016.04.06 15:27:48.745 4: HMW02.I07: frequency -> 15094.00
2016.04.06 15:27:49.007 4: Connection closed for WEB_192.168.0.29_57616: EOF
2016.04.06 15:27:49.008 4: Connection accepted from WEB_192.168.0.29_57618
2016.04.06 15:27:49.010 4: WEB_192.168.0.29_57618 GET /fhem?XHR=1&inform=type=raw;withLog=1;filter=.*&timestamp=1459949269003; BUFLEN:0
2016.04.06 15:27:50.114 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 83 Cmd: 101
2016.04.06 15:27:50.115 4: MyHMLAN: Event:HASH(0x58a89e8)
2016.04.06 15:27:50.115 5: MyHMLAN dispatch \FDSe\00\00\00X\00\00\9A+i\00;\CF
2016.04.06 15:27:50.115 5: MyHMLAN: HM485_Parse: MsgId: 83
2016.04.06 15:27:50.116 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:50.116 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003BCF
2016.04.06 15:27:50.118 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:50.120 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:50.123 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:50.124 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:50.125 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15311
2016.04.06 15:27:50.125 5: HM485: HM485:Device:dataConversion: retVal = 15311.00
2016.04.06 15:27:50.126 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15311.00
2016.04.06 15:27:50.126 5: HM485: Device:valueToControl: valName = frequency = 15311.00
2016.04.06 15:27:50.126 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003BCF actionType = frame
2016.04.06 15:27:50.128 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:27:50.128 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15311.00 Alter Wert = 15094.00
2016.04.06 15:27:50.128 4: HMW02.I07: frequency -> 15311.00
2016.04.06 15:27:50.242 5: CUL/RAW: /K016561631D

2016.04.06 15:27:50.242 4: CUL_Parse: CUL_0 K016561631D -59.5
2016.04.06 15:27:50.243 5: CUL_0 dispatch K01656163
2016.04.06 15:27:50.244 4: CUL_WS S300TH S300TH: T: 16.5  H: 63.6
2016.04.06 15:27:50.248 5: Triggering S300TH (4 changes)
2016.04.06 15:27:50.248 5: Starting notify loop for S300TH, first event T: 16.5  H: 63.6
2016.04.06 15:27:50.253 5: Notify from Device: S300TH recieved
2016.04.06 15:27:50.254 5: DbLog: logging of Device: S300TH , Type: CUL_WS , Event: T: 16.5  H: 63.6 , Reading: data , Value: T: 16.5  H: 63.6 , Unit: 63.6
2016.04.06 15:27:50.281 5: DbLog: logging of Device: S300TH , Type: CUL_WS , Event: temperature: 16.5 , Reading: temperature , Value: 16.5 , Unit: °C
2016.04.06 15:27:50.332 5: DbLog: logging of Device: S300TH , Type: CUL_WS , Event: taupunkt: 9.5 , Reading: taupunkt , Value: 9.5 , Unit:
2016.04.06 15:27:50.369 5: DbLog: logging of Device: S300TH , Type: CUL_WS , Event: avgTemp: 15.5605263157895 , Reading: avgTemp , Value: 15.5605263157895 , Unit:
2016.04.06 15:27:51.254 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 84 Cmd: 101
2016.04.06 15:27:51.255 4: MyHMLAN: Event:HASH(0x5931c08)
2016.04.06 15:27:51.255 5: MyHMLAN dispatch \FDTe\00\00\00Z\00\00\9A+i\00:\F6
2016.04.06 15:27:51.255 5: MyHMLAN: HM485_Parse: MsgId: 84
2016.04.06 15:27:51.256 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:51.256 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003AF6
2016.04.06 15:27:51.258 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:51.260 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:51.263 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:51.264 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:51.265 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15094
2016.04.06 15:27:51.265 5: HM485: HM485:Device:dataConversion: retVal = 15094.00
2016.04.06 15:27:51.266 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15094.00
2016.04.06 15:27:51.266 5: HM485: Device:valueToControl: valName = frequency = 15094.00
2016.04.06 15:27:51.266 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003AF6 actionType = frame
2016.04.06 15:27:51.268 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:27:51.268 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15094.00 Alter Wert = 15311.00
2016.04.06 15:27:51.268 4: HMW02.I07: frequency -> 15094.00
2016.04.06 15:27:51.272 5: Triggering HMW02.I07 (1 changes)
2016.04.06 15:27:51.273 5: Starting notify loop for HMW02.I07, first event leistung: 2754.2
2016.04.06 15:27:51.274 5: Notify from Device: HMW02.I07 recieved
2016.04.06 15:27:51.275 5: DbLog: logging of Device: HMW02.I07 , Type: HM485 , Event: leistung: 2754.2 , Reading: leistung , Value: 2754.2 , Unit:
2016.04.06 15:27:52.277 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 85 Cmd: 101
2016.04.06 15:27:52.277 4: MyHMLAN: Event:HASH(0x5931bf0)
2016.04.06 15:27:52.278 5: MyHMLAN dispatch \FDUe\00\00\00\\00\00\9A+i\00<
2016.04.06 15:27:52.278 5: MyHMLAN: HM485_Parse: MsgId: 85
2016.04.06 15:27:52.278 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:52.279 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003C18
2016.04.06 15:27:52.281 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:52.282 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:52.286 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:52.287 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:52.288 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15384
2016.04.06 15:27:52.288 5: HM485: HM485:Device:dataConversion: retVal = 15384.00
2016.04.06 15:27:52.288 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15384.00
2016.04.06 15:27:52.289 5: HM485: Device:valueToControl: valName = frequency = 15384.00
2016.04.06 15:27:52.289 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003C18 actionType = frame
2016.04.06 15:27:52.290 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:27:52.291 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15384.00 Alter Wert = 15094.00
2016.04.06 15:27:52.291 4: HMW02.I07: frequency -> 15384.00
2016.04.06 15:27:53.421 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 86 Cmd: 101
2016.04.06 15:27:53.421 4: MyHMLAN: Event:HASH(0x5932908)
2016.04.06 15:27:53.421 5: MyHMLAN dispatch \FDVe\00\00\00^\00\00\9A+i\00;=
2016.04.06 15:27:53.422 5: MyHMLAN: HM485_Parse: MsgId: 86
2016.04.06 15:27:53.422 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:53.422 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003B3D
2016.04.06 15:27:53.425 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:53.426 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:53.429 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:53.431 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:53.432 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15165
2016.04.06 15:27:53.432 5: HM485: HM485:Device:dataConversion: retVal = 15165.00
2016.04.06 15:27:53.432 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15165.00
2016.04.06 15:27:53.432 5: HM485: Device:valueToControl: valName = frequency = 15165.00
2016.04.06 15:27:53.433 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003B3D actionType = frame
2016.04.06 15:27:53.434 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:27:53.435 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15165.00 Alter Wert = 15384.00
2016.04.06 15:27:53.435 4: HMW02.I07: frequency -> 15165.00
2016.04.06 15:27:54.029 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 87 Cmd: 101
2016.04.06 15:27:54.029 4: MyHMLAN: Event:HASH(0x58e9f10)
2016.04.06 15:27:54.030 5: MyHMLAN dispatch \FDWe\FF\FF\FF\FF\DA\00\00\B8\C5K\00\00\C2
2016.04.06 15:27:54.030 5: MyHMLAN: HM485_Parse: MsgId: 87
2016.04.06 15:27:54.031 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:54.031 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4B0000C2
2016.04.06 15:27:54.034 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 48
2016.04.06 15:27:54.034 5: HM485: HM485:Device:dataConversion: retVal = 48
2016.04.06 15:27:54.035 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 48
2016.04.06 15:27:54.035 5: HM485: Device:valueToControl: valName = press_short = 48
2016.04.06 15:27:54.035 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B0000C2 actionType = frame
2016.04.06 15:27:54.037 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:27:54.037 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:27:54.038 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:27:54.040 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:27:54.080 4: HMW01.I01: HM485_ChannelDoUpdate
2016.04.06 15:27:54.080 5: HMW01.I01: HM485_ChannelDoUpdate: valueKey = press_short value = 48 Alter Wert = 47
2016.04.06 15:27:54.080 4: HMW01.I01: press_short -> 48
2016.04.06 15:27:54.081 5: Triggering HMW01.I01 (2 changes)
2016.04.06 15:27:54.081 5: Starting notify loop for HMW01.I01, first event press_short: 48
2016.04.06 15:27:54.082 5: Triggering N_1.I01_short
2016.04.06 15:27:54.082 4: N_1.I01_short exec set HMW01.O04 toggle
2016.04.06 15:27:54.083 5: Cmd: >set HMW01.O04 toggle<
2016.04.06 15:27:54.086 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_SendCommand: 780FFF
2016.04.06 15:27:54.088 5: Notify from Device: HMW01.I01 recieved
2016.04.06 15:27:54.089 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short: 48 , Reading: press_short , Value: 48 , Unit:
2016.04.06 15:27:54.127 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short_48 , Reading: state , Value: press_short_48 , Unit:
2016.04.06 15:27:54.160 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 88 Cmd: 101
2016.04.06 15:27:54.160 4: MyHMLAN: Event:HASH(0x58c0200)
2016.04.06 15:27:54.160 5: MyHMLAN dispatch \FDXe\FF\FF\FF\FF\DC\00\00\B8\C5A\00\00LEQ0016352
2016.04.06 15:27:54.161 5: MyHMLAN: HM485_Parse: MsgId: 88
2016.04.06 15:27:54.161 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:54.161 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4100120003064C455130303136333532
2016.04.06 15:27:54.162 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 15:27:54.164 5: Triggering HMW01.O04 (1 changes)
2016.04.06 15:27:54.164 5: Starting notify loop for HMW01.O04, first event set_toggle
2016.04.06 15:27:54.165 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:27:54.167 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: set_toggle , Reading: state , Value: set_toggle , Unit:
2016.04.06 15:27:54.192 5: MyHMLAN: HM485_LAN_Write TX: 100
2016.04.06 15:27:54.193 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000191
2016.04.06 15:27:54.193 5: SW: fd0f6453c80000b8c51e00000001780fff
2016.04.06 15:27:54.194 4: MyHMLAN: MyHMLAN: TX: (100) I[3](0,F,B)(1E) 00000001 -> 0000B8C5 [5] 78(x) 0FFF
2016.04.06 15:27:54.195 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_DoSendCommand: hmwId = 0000B8C5 data = 780FFF requestId = 100
2016.04.06 15:27:54.196 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:27:54.196 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:27:54.197 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:27:54.199 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:27:54.228 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 100 Cmd: 114
2016.04.06 15:27:54.228 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Response: (100) 690F0000
2016.04.06 15:27:54.229 5: MyHMLAN dispatch \FDdr~i\00\00
2016.04.06 15:27:54.229 5: MyHMLAN: HM485_Parse: MsgId: 100
2016.04.06 15:27:54.229 5: MyHMLAN: HM485_Parse: ProcessResponse
2016.04.06 15:27:54.229 5: MyHMLAN: HM485_ProcessResponse: msgData = 690F0000
2016.04.06 15:27:54.230 5: MyHMLAN: HM485_ProcessResponse: deviceKey =  requestType = 78 requestData = 0FFF msgData = 690F0000
2016.04.06 15:27:54.232 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state_flags value1 = 0
2016.04.06 15:27:54.232 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:54.233 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 0
2016.04.06 15:27:54.233 5: HM485: Device:valueToControl: valName = working = 0
2016.04.06 15:27:54.234 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state value1 = 0
2016.04.06 15:27:54.234 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:54.235 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 0
2016.04.06 15:27:54.235 5: HM485: Device:valueToControl: valName = state = 0
2016.04.06 15:27:54.235 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 16 msgData = 690F0000 actionType = response
2016.04.06 15:27:54.236 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Removing Queue 00000191
2016.04.06 15:27:54.236 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000191
2016.04.06 15:27:54.237 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:27:54.237 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:27:54.239 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:27:54.240 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:27:54.284 4: HMW01.O04: HM485_ChannelDoUpdate
2016.04.06 15:27:54.284 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = working value = off Alter Wert = off
2016.04.06 15:27:54.284 4: HMW01.O04: working -> off
2016.04.06 15:27:54.285 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = state value = off Alter Wert = set_toggle
2016.04.06 15:27:54.285 4: HMW01.O04: state -> off
2016.04.06 15:27:54.285 5: Triggering HMW01.O04 (2 changes)
2016.04.06 15:27:54.285 5: Starting notify loop for HMW01.O04, first event working: off
2016.04.06 15:27:54.287 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:27:54.288 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: working: off , Reading: working , Value: off , Unit:
2016.04.06 15:27:54.314 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: off , Reading: state , Value: off , Unit:
2016.04.06 15:27:56.309 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 89 Cmd: 101
2016.04.06 15:27:56.309 4: MyHMLAN: Event:HASH(0x5943970)
2016.04.06 15:27:56.309 5: MyHMLAN dispatch \FDYe\00\00\00X\00\00\9A+i\00;\86
2016.04.06 15:27:56.309 5: MyHMLAN: HM485_Parse: MsgId: 89
2016.04.06 15:27:56.310 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:27:56.310 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003B86
2016.04.06 15:27:56.312 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:56.314 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:56.317 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:56.318 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:27:56.319 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15238
2016.04.06 15:27:56.320 5: HM485: HM485:Device:dataConversion: retVal = 15238.00
2016.04.06 15:27:56.320 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15238.00
2016.04.06 15:27:56.320 5: HM485: Device:valueToControl: valName = frequency = 15238.00
2016.04.06 15:27:56.320 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003B86 actionType = frame
2016.04.06 15:27:56.322 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:27:56.322 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15238.00 Alter Wert = 15165.00
2016.04.06 15:27:56.323 4: HMW02.I07: frequency -> 15238.00
2016.04.06 15:27:57.006 5: OWServer child ID for reading '/28.615050070000/temperature10' is 25803
2016.04.06 15:27:58.121 5: OWServer child read /28.615050070000/temperature10: 85
2016.04.06 15:27:58.598 5: OWServer child ID for reading '/28.FF8EE6A41503/temperature10' is 25807
2016.04.06 15:27:58.853 5: OWServer child read /28.FF8EE6A41503/temperature10: 23.25
2016.04.06 15:27:59.324 1: Perfmon: possible freeze starting at 15:27:58, delay is 1.324
2016.04.06 15:27:59.331 5: OWServer child ID for reading '/28.95CD51070000/temperature10' is 25811
2016.04.06 15:27:59.651 5: OWServer child read /28.95CD51070000/temperature10: 36.5
2016.04.06 15:28:00.127 5: OWServer child ID for reading '/28.2B308A060000/temperature10' is 25815
2016.04.06 15:28:00.382 5: OWServer child read /28.2B308A060000/temperature10: 26.5
2016.04.06 15:28:00.860 5: OWServer child ID for reading '/28.FF9BA5A41503/temperature10' is 25819
2016.04.06 15:28:01.179 5: OWServer child read /28.FF9BA5A41503/temperature10: 22.5
2016.04.06 15:28:01.651 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 90 Cmd: 101
2016.04.06 15:28:01.651 4: MyHMLAN: Event:HASH(0x59442a0)
2016.04.06 15:28:01.652 5: MyHMLAN dispatch \FDZe\00\00\00Z\00\00\9A+i\00;=
2016.04.06 15:28:01.652 5: MyHMLAN: HM485_Parse: MsgId: 90
2016.04.06 15:28:01.652 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:01.653 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003B3D
2016.04.06 15:28:01.656 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:01.657 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:01.661 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:01.662 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:01.663 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15165
2016.04.06 15:28:01.663 5: HM485: HM485:Device:dataConversion: retVal = 15165.00
2016.04.06 15:28:01.664 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15165.00
2016.04.06 15:28:01.664 5: HM485: Device:valueToControl: valName = frequency = 15165.00
2016.04.06 15:28:01.664 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003B3D actionType = frame
2016.04.06 15:28:01.670 5: OWServer child ID for reading '/28.3BC952070000/temperature10' is 25823
2016.04.06 15:28:01.923 5: OWServer child read /28.3BC952070000/temperature10: 22.75
2016.04.06 15:28:02.394 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:02.395 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15165.00 Alter Wert = 15238.00
2016.04.06 15:28:02.395 4: HMW02.I07: frequency -> 15165.00
2016.04.06 15:28:02.400 5: Triggering HMW02.I07 (1 changes)
2016.04.06 15:28:02.400 5: Starting notify loop for HMW02.I07, first event leistung: 2751.75
2016.04.06 15:28:02.402 5: Notify from Device: HMW02.I07 recieved
2016.04.06 15:28:02.403 5: DbLog: logging of Device: HMW02.I07 , Type: HM485 , Event: leistung: 2751.75 , Reading: leistung , Value: 2751.75 , Unit:
2016.04.06 15:28:02.434 5: OWServer child ID for reading '/28.6A568A060000/temperature10' is 25827
2016.04.06 15:28:02.689 5: OWServer child read /28.6A568A060000/temperature10: 28.5
2016.04.06 15:28:03.166 5: OWServer child ID for reading '/28.AE7720070000/temperature10' is 25831
2016.04.06 15:28:03.419 5: OWServer child read /28.AE7720070000/temperature10: 25.25
2016.04.06 15:28:03.894 5: OWServer child ID for reading '/28.058A50070000/temperature10' is 25835
2016.04.06 15:28:04.213 5: OWServer child read /28.058A50070000/temperature10: 28.25
2016.04.06 15:28:04.684 1: Perfmon: possible freeze starting at 15:28:03, delay is 1.684
2016.04.06 15:28:04.686 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 91 Cmd: 101
2016.04.06 15:28:04.686 4: MyHMLAN: Event:HASH(0x588bc40)
2016.04.06 15:28:04.686 5: MyHMLAN dispatch \FD[e\00\00\00\\00\00\9A+i\00<
2016.04.06 15:28:04.687 5: MyHMLAN: HM485_Parse: MsgId: 91
2016.04.06 15:28:04.687 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:04.688 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003C18
2016.04.06 15:28:04.690 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.692 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.695 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.697 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.698 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15384
2016.04.06 15:28:04.698 5: HM485: HM485:Device:dataConversion: retVal = 15384.00
2016.04.06 15:28:04.698 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15384.00
2016.04.06 15:28:04.698 5: HM485: Device:valueToControl: valName = frequency = 15384.00
2016.04.06 15:28:04.699 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003C18 actionType = frame
2016.04.06 15:28:04.699 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 92 Cmd: 101
2016.04.06 15:28:04.700 4: MyHMLAN: Event:HASH(0x5930f48)
2016.04.06 15:28:04.700 5: MyHMLAN dispatch \FD\e\00\00\00^\00\00\9A+i\00:\AF
2016.04.06 15:28:04.700 5: MyHMLAN: HM485_Parse: MsgId: 92
2016.04.06 15:28:04.701 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:04.701 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003AAF
2016.04.06 15:28:04.703 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.705 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.708 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.709 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:04.710 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15023
2016.04.06 15:28:04.710 5: HM485: HM485:Device:dataConversion: retVal = 15023.00
2016.04.06 15:28:04.711 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15023.00
2016.04.06 15:28:04.711 5: HM485: Device:valueToControl: valName = frequency = 15023.00
2016.04.06 15:28:04.711 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003AAF actionType = frame
2016.04.06 15:28:04.712 5: CUL/RAW: /E03093E65B6000065B60F

2016.04.06 15:28:04.712 4: CUL_Parse: CUL_0 E03093E65B6000065B60F -66.5
2016.04.06 15:28:04.713 5: CUL_0 dispatch E03093E65B6000065B6
2016.04.06 15:28:04.714 5: Mi Apr  6 15:28:04 2016
2016.04.06 15:28:04.714 5: CUL_EM Gaszaehler: seqno: 62 total_cnt: 46693  current_cnt: 0  peak_cnt: 46693
2016.04.06 15:28:04.715 4: CUL_EM Gaszaehler: CNT: 62 CUM: 15196.992  5MIN: 0.000  TOP: 0.000
2016.04.06 15:28:04.717 5: Triggering Gaszaehler (1 changes)
2016.04.06 15:28:04.717 5: Starting notify loop for Gaszaehler, first event CNT: 62 CUM: 15196.992  5MIN: 0.000  TOP: 0.000
2016.04.06 15:28:04.719 5: Notify from Device: Gaszaehler recieved
2016.04.06 15:28:04.721 5: DbLog: logging of Device: Gaszaehler , Type: CUL_EM , Event: CNT: 62 CUM: 15196.992  5MIN: 0.000  TOP: 0.000 , Reading: CNT , Value: 62 CUM: 15196.992  5MIN: 0.000  TOP: 0.000 , Unit:
2016.04.06 15:28:04.752 5: OWServer child ID for reading '/28.36FE4F070000/temperature10' is 25839
2016.04.06 15:28:05.007 5: OWServer child read /28.36FE4F070000/temperature10: 24.5
2016.04.06 15:28:05.479 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:05.480 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15384.00 Alter Wert = 15165.00
2016.04.06 15:28:05.480 4: HMW02.I07: frequency -> 15384.00
2016.04.06 15:28:05.485 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:05.486 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15023.00 Alter Wert = 15384.00
2016.04.06 15:28:05.486 4: HMW02.I07: frequency -> 15023.00
2016.04.06 15:28:05.491 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 93 Cmd: 101
2016.04.06 15:28:05.492 4: MyHMLAN: Event:HASH(0x5946a18)
2016.04.06 15:28:05.492 5: MyHMLAN dispatch \FD]e\00\00\00X\00\00\9A+i\00<
2016.04.06 15:28:05.492 5: MyHMLAN: HM485_Parse: MsgId: 93
2016.04.06 15:28:05.493 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:05.493 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003C18
2016.04.06 15:28:05.496 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:05.497 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:05.501 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:05.502 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:05.503 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15384
2016.04.06 15:28:05.503 5: HM485: HM485:Device:dataConversion: retVal = 15384.00
2016.04.06 15:28:05.503 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15384.00
2016.04.06 15:28:05.504 5: HM485: Device:valueToControl: valName = frequency = 15384.00
2016.04.06 15:28:05.504 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003C18 actionType = frame
2016.04.06 15:28:05.510 5: OWServer child ID for reading '/28.6B878A060000/temperature10' is 25843
2016.04.06 15:28:05.831 5: OWServer child read /28.6B878A060000/temperature10: 28.75
2016.04.06 15:28:06.302 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:06.303 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15384.00 Alter Wert = 15023.00
2016.04.06 15:28:06.303 4: HMW02.I07: frequency -> 15384.00
2016.04.06 15:28:06.313 5: OWServer child ID for reading '/28.59F550070000/temperature10' is 25847
2016.04.06 15:28:06.569 5: OWServer child read /28.59F550070000/temperature10: 22.5
2016.04.06 15:28:07.040 1: Perfmon: possible freeze starting at 15:28:06, delay is 1.04
2016.04.06 15:28:07.041 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 94 Cmd: 101
2016.04.06 15:28:07.042 4: MyHMLAN: Event:HASH(0x5946688)
2016.04.06 15:28:07.042 5: MyHMLAN dispatch \FD^e\00\00\00Z\00\00\9A+i\00:\F6
2016.04.06 15:28:07.043 5: MyHMLAN: HM485_Parse: MsgId: 94
2016.04.06 15:28:07.043 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:07.043 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003AF6
2016.04.06 15:28:07.046 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:07.048 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:07.051 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:07.053 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:07.053 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15094
2016.04.06 15:28:07.054 5: HM485: HM485:Device:dataConversion: retVal = 15094.00
2016.04.06 15:28:07.054 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15094.00
2016.04.06 15:28:07.054 5: HM485: Device:valueToControl: valName = frequency = 15094.00
2016.04.06 15:28:07.054 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003AF6 actionType = frame
2016.04.06 15:28:07.060 5: OWServer child ID for reading '/28.AF6551070000/temperature10' is 25851
2016.04.06 15:28:07.380 5: OWServer child read /28.AF6551070000/temperature10: 32.5
2016.04.06 15:28:07.856 5: OWServer child ID for reading '/28.F4C58A060000/temperature10' is 25855
2016.04.06 15:28:08.109 5: OWServer child read /28.F4C58A060000/temperature10: 33.25
2016.04.06 15:28:08.580 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:08.581 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15094.00 Alter Wert = 15384.00
2016.04.06 15:28:08.581 4: HMW02.I07: frequency -> 15094.00
2016.04.06 15:28:08.585 5: Triggering HMW02.I07 (1 changes)
2016.04.06 15:28:08.586 5: Starting notify loop for HMW02.I07, first event leistung: 2755.75
2016.04.06 15:28:08.588 5: Notify from Device: HMW02.I07 recieved
2016.04.06 15:28:08.589 5: DbLog: logging of Device: HMW02.I07 , Type: HM485 , Event: leistung: 2755.75 , Reading: leistung , Value: 2755.75 , Unit:
2016.04.06 15:28:08.616 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 95 Cmd: 101
2016.04.06 15:28:08.617 4: MyHMLAN: Event:HASH(0x5946d90)
2016.04.06 15:28:08.617 5: MyHMLAN dispatch \FD_e\00\00\00\\00\00\9A+i\00;\86
2016.04.06 15:28:08.617 5: MyHMLAN: HM485_Parse: MsgId: 95
2016.04.06 15:28:08.618 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:08.618 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003B86
2016.04.06 15:28:08.621 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:08.622 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:08.626 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:08.627 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:08.628 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15238
2016.04.06 15:28:08.628 5: HM485: HM485:Device:dataConversion: retVal = 15238.00
2016.04.06 15:28:08.628 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15238.00
2016.04.06 15:28:08.629 5: HM485: Device:valueToControl: valName = frequency = 15238.00
2016.04.06 15:28:08.629 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003B86 actionType = frame
2016.04.06 15:28:08.635 5: OWServer child ID for reading '/28.FF7DE5A41503/temperature10' is 25859
2016.04.06 15:28:08.888 5: OWServer child read /28.FF7DE5A41503/temperature10: 22.75
2016.04.06 15:28:09.359 1: Perfmon: possible freeze starting at 15:28:08, delay is 1.359
2016.04.06 15:28:09.360 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:09.360 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15238.00 Alter Wert = 15094.00
2016.04.06 15:28:09.361 4: HMW02.I07: frequency -> 15238.00
2016.04.06 15:28:09.369 4: <hidden>: HTTP response code 200
2016.04.06 15:28:09.369 4: HttpUtils <hidden>: Got data, length: 23
2016.04.06 15:28:09.369 5: TelegramBot_Callback remotebot: called from Polling
2016.04.06 15:28:09.369 5: TelegramBot_ParseUpdate remotebot: data returned :{"ok":true,"result":[]}:
2016.04.06 15:28:09.370 5: UpdatePoll remotebot: number of results 0
2016.04.06 15:28:09.370 5: TelegramBot_UpdatePoll remotebot: called
2016.04.06 15:28:09.370 4: TelegramBot_UpdatePoll remotebot: initiate polling with nonblockingGet with 30s
2016.04.06 15:28:09.371 4: HttpUtils url=<hidden>
2016.04.06 15:28:09.374 4: TelegramBot_Callback remotebot: resulted in :SUCCESS: from Polling
2016.04.06 15:28:10.772 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 96 Cmd: 101
2016.04.06 15:28:10.772 4: MyHMLAN: Event:HASH(0x58d38e8)
2016.04.06 15:28:10.772 5: MyHMLAN dispatch \FD`e\FF\FF\FF\FF\F8\00\00\B8\C5K\00\00\C6
2016.04.06 15:28:10.773 5: MyHMLAN: HM485_Parse: MsgId: 96
2016.04.06 15:28:10.773 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:10.773 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4B0000C6
2016.04.06 15:28:10.777 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 49
2016.04.06 15:28:10.777 5: HM485: HM485:Device:dataConversion: retVal = 49
2016.04.06 15:28:10.777 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 49
2016.04.06 15:28:10.778 5: HM485: Device:valueToControl: valName = press_short = 49
2016.04.06 15:28:10.778 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B0000C6 actionType = frame
2016.04.06 15:28:10.779 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:10.780 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:10.782 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:10.783 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:10.810 4: HMW01.I01: HM485_ChannelDoUpdate
2016.04.06 15:28:10.810 5: HMW01.I01: HM485_ChannelDoUpdate: valueKey = press_short value = 49 Alter Wert = 48
2016.04.06 15:28:10.810 4: HMW01.I01: press_short -> 49
2016.04.06 15:28:10.811 5: Triggering HMW01.I01 (2 changes)
2016.04.06 15:28:10.811 5: Starting notify loop for HMW01.I01, first event press_short: 49
2016.04.06 15:28:10.812 5: Triggering N_1.I01_short
2016.04.06 15:28:10.813 4: N_1.I01_short exec set HMW01.O04 toggle
2016.04.06 15:28:10.813 5: Cmd: >set HMW01.O04 toggle<
2016.04.06 15:28:10.817 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_SendCommand: 780FFF
2016.04.06 15:28:10.819 5: Notify from Device: HMW01.I01 recieved
2016.04.06 15:28:10.820 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short: 49 , Reading: press_short , Value: 49 , Unit:
2016.04.06 15:28:10.859 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short_49 , Reading: state , Value: press_short_49 , Unit:
2016.04.06 15:28:10.905 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 97 Cmd: 101
2016.04.06 15:28:10.905 4: MyHMLAN: Event:HASH(0x58d4798)
2016.04.06 15:28:10.905 5: MyHMLAN dispatch \FDae\FF\FF\FF\FF\FA\00\00\B8\C5A\00\00LEQ0016352
2016.04.06 15:28:10.906 5: MyHMLAN: HM485_Parse: MsgId: 97
2016.04.06 15:28:10.906 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:10.906 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4100120003064C455130303136333532
2016.04.06 15:28:10.907 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 15:28:10.909 5: Triggering HMW01.O04 (1 changes)
2016.04.06 15:28:10.909 5: Starting notify loop for HMW01.O04, first event set_toggle
2016.04.06 15:28:10.911 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:28:10.912 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: set_toggle , Reading: state , Value: set_toggle , Unit:
2016.04.06 15:28:10.939 5: MyHMLAN: HM485_LAN_Write TX: 101
2016.04.06 15:28:10.939 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000192
2016.04.06 15:28:10.940 5: SW: fd0f6553c80000b8c51800000001780fff
2016.04.06 15:28:10.941 4: MyHMLAN: MyHMLAN: TX: (101) I[0](0,F,B)(18) 00000001 -> 0000B8C5 [5] 78(x) 0FFF
2016.04.06 15:28:10.941 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_DoSendCommand: hmwId = 0000B8C5 data = 780FFF requestId = 101
2016.04.06 15:28:10.942 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:10.942 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:10.944 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:10.945 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:10.984 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 101 Cmd: 114
2016.04.06 15:28:10.984 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Response: (101) 690FC800
2016.04.06 15:28:10.984 5: MyHMLAN dispatch \FDeri\C8\00
2016.04.06 15:28:10.985 5: MyHMLAN: HM485_Parse: MsgId: 101
2016.04.06 15:28:10.985 5: MyHMLAN: HM485_Parse: ProcessResponse
2016.04.06 15:28:10.985 5: MyHMLAN: HM485_ProcessResponse: msgData = 690FC800
2016.04.06 15:28:10.985 5: MyHMLAN: HM485_ProcessResponse: deviceKey =  requestType = 78 requestData = 0FFF msgData = 690FC800
2016.04.06 15:28:10.988 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state_flags value1 = 0
2016.04.06 15:28:10.988 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:10.988 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 0
2016.04.06 15:28:10.988 5: HM485: Device:valueToControl: valName = working = 0
2016.04.06 15:28:10.990 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state value1 = 200
2016.04.06 15:28:10.990 5: HM485: HM485:Device:dataConversion: retVal = 1
2016.04.06 15:28:10.990 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 1
2016.04.06 15:28:10.990 5: HM485: Device:valueToControl: valName = state = 1
2016.04.06 15:28:10.991 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 16 msgData = 690FC800 actionType = response
2016.04.06 15:28:10.991 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Removing Queue 00000192
2016.04.06 15:28:10.992 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000192
2016.04.06 15:28:10.993 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:10.993 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:10.995 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:10.996 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:11.028 4: HMW01.O04: HM485_ChannelDoUpdate
2016.04.06 15:28:11.028 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = state value = on Alter Wert = set_toggle
2016.04.06 15:28:11.028 4: HMW01.O04: state -> on
2016.04.06 15:28:11.028 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = working value = off Alter Wert = off
2016.04.06 15:28:11.029 4: HMW01.O04: working -> off
2016.04.06 15:28:11.029 5: Triggering HMW01.O04 (2 changes)
2016.04.06 15:28:11.029 5: Starting notify loop for HMW01.O04, first event on
2016.04.06 15:28:11.031 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:28:11.032 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: on , Reading: state , Value: on , Unit:
2016.04.06 15:28:11.072 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: working: off , Reading: working , Value: off , Unit:
2016.04.06 15:28:15.187 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 98 Cmd: 101
2016.04.06 15:28:15.187 4: MyHMLAN: Event:HASH(0x592ccb0)
2016.04.06 15:28:15.188 5: MyHMLAN dispatch \FDbe\00\00\00^\00\00\9A+i\00;\CF
2016.04.06 15:28:15.188 5: MyHMLAN: HM485_Parse: MsgId: 98
2016.04.06 15:28:15.188 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:15.189 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003BCF
2016.04.06 15:28:15.191 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:15.193 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:15.196 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:15.197 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:15.198 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15311
2016.04.06 15:28:15.198 5: HM485: HM485:Device:dataConversion: retVal = 15311.00
2016.04.06 15:28:15.199 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15311.00
2016.04.06 15:28:15.199 5: HM485: Device:valueToControl: valName = frequency = 15311.00
2016.04.06 15:28:15.199 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003BCF actionType = frame
2016.04.06 15:28:15.201 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:15.201 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15311.00 Alter Wert = 15238.00
2016.04.06 15:28:15.201 4: HMW02.I07: frequency -> 15311.00
2016.04.06 15:28:15.205 5: Triggering HMW02.I07 (1 changes)
2016.04.06 15:28:15.205 5: Starting notify loop for HMW02.I07, first event literpromin: 17.5874093179897
2016.04.06 15:28:15.207 5: Notify from Device: HMW02.I07 recieved
2016.04.06 15:28:15.208 5: DbLog: logging of Device: HMW02.I07 , Type: HM485 , Event: literpromin: 17.5874093179897 , Reading: literpromin , Value: 17.5874093179897 , Unit:
2016.04.06 15:28:17.411 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 99 Cmd: 101
2016.04.06 15:28:17.412 4: MyHMLAN: Event:HASH(0x5936418)
2016.04.06 15:28:17.412 5: MyHMLAN dispatch \FDce\00\00\00X\00\00\9A+i\00;=
2016.04.06 15:28:17.412 5: MyHMLAN: HM485_Parse: MsgId: 99
2016.04.06 15:28:17.412 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:17.413 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003B3D
2016.04.06 15:28:17.415 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:17.416 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:17.420 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:17.421 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:17.422 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15165
2016.04.06 15:28:17.422 5: HM485: HM485:Device:dataConversion: retVal = 15165.00
2016.04.06 15:28:17.422 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15165.00
2016.04.06 15:28:17.423 5: HM485: Device:valueToControl: valName = frequency = 15165.00
2016.04.06 15:28:17.423 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003B3D actionType = frame
2016.04.06 15:28:17.424 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:17.425 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15165.00 Alter Wert = 15311.00
2016.04.06 15:28:17.425 4: HMW02.I07: frequency -> 15165.00
2016.04.06 15:28:17.429 5: Triggering HMW02.I07 (1 changes)
2016.04.06 15:28:17.429 5: Starting notify loop for HMW02.I07, first event leistung: 2744.66666666667
2016.04.06 15:28:17.431 5: Notify from Device: HMW02.I07 recieved
2016.04.06 15:28:17.432 5: DbLog: logging of Device: HMW02.I07 , Type: HM485 , Event: leistung: 2744.66666666667 , Reading: leistung , Value: 2744.66666666667 , Unit:
2016.04.06 15:28:19.193 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 100 Cmd: 101
2016.04.06 15:28:19.193 4: MyHMLAN: Event:HASH(0x5936838)
2016.04.06 15:28:19.193 5: MyHMLAN dispatch \FDde\FF\FF\FF\FF\9E\00\00\B8\C5K\00\00\CA
2016.04.06 15:28:19.194 5: MyHMLAN: HM485_Parse: MsgId: 100
2016.04.06 15:28:19.194 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:19.194 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4B0000CA
2016.04.06 15:28:19.197 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 50
2016.04.06 15:28:19.198 5: HM485: HM485:Device:dataConversion: retVal = 50
2016.04.06 15:28:19.198 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 50
2016.04.06 15:28:19.198 5: HM485: Device:valueToControl: valName = press_short = 50
2016.04.06 15:28:19.198 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B0000CA actionType = frame
2016.04.06 15:28:19.200 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:19.200 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:19.202 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:19.203 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:19.216 4: HMW01.I01: HM485_ChannelDoUpdate
2016.04.06 15:28:19.216 5: HMW01.I01: HM485_ChannelDoUpdate: valueKey = press_short value = 50 Alter Wert = 49
2016.04.06 15:28:19.217 4: HMW01.I01: press_short -> 50
2016.04.06 15:28:19.217 5: Triggering HMW01.I01 (2 changes)
2016.04.06 15:28:19.217 5: Starting notify loop for HMW01.I01, first event press_short: 50
2016.04.06 15:28:19.218 5: Triggering N_1.I01_short
2016.04.06 15:28:19.219 4: N_1.I01_short exec set HMW01.O04 toggle
2016.04.06 15:28:19.219 5: Cmd: >set HMW01.O04 toggle<
2016.04.06 15:28:19.223 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_SendCommand: 780FFF
2016.04.06 15:28:19.224 5: Notify from Device: HMW01.I01 recieved
2016.04.06 15:28:19.226 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short: 50 , Reading: press_short , Value: 50 , Unit:
2016.04.06 15:28:19.253 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short_50 , Reading: state , Value: press_short_50 , Unit:
2016.04.06 15:28:19.286 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 101 Cmd: 101
2016.04.06 15:28:19.287 4: MyHMLAN: Event:HASH(0x5936aa8)
2016.04.06 15:28:19.287 5: MyHMLAN dispatch \FDee\FF\FF\FF\FF\98\00\00\B8\C5A\00\00LEQ0016352
2016.04.06 15:28:19.287 5: MyHMLAN: HM485_Parse: MsgId: 101
2016.04.06 15:28:19.288 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:19.288 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4100120003064C455130303136333532
2016.04.06 15:28:19.289 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 15:28:19.290 5: Triggering HMW01.O04 (1 changes)
2016.04.06 15:28:19.291 5: Starting notify loop for HMW01.O04, first event set_toggle
2016.04.06 15:28:19.292 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:28:19.293 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: set_toggle , Reading: state , Value: set_toggle , Unit:
2016.04.06 15:28:19.330 5: MyHMLAN: HM485_LAN_Write TX: 102
2016.04.06 15:28:19.331 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000193
2016.04.06 15:28:19.331 5: SW: fd0f6653c80000b8c51a00000001780fff
2016.04.06 15:28:19.332 4: MyHMLAN: MyHMLAN: TX: (102) I[1](0,F,B)(1A) 00000001 -> 0000B8C5 [5] 78(x) 0FFF
2016.04.06 15:28:19.333 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_DoSendCommand: hmwId = 0000B8C5 data = 780FFF requestId = 102
2016.04.06 15:28:19.333 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:19.334 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:19.335 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:19.337 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:19.375 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 102 Cmd: 114
2016.04.06 15:28:19.376 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Response: (102) 690F0000
2016.04.06 15:28:19.376 5: MyHMLAN dispatch \FDfr:i\00\00
2016.04.06 15:28:19.376 5: MyHMLAN: HM485_Parse: MsgId: 102
2016.04.06 15:28:19.376 5: MyHMLAN: HM485_Parse: ProcessResponse
2016.04.06 15:28:19.377 5: MyHMLAN: HM485_ProcessResponse: msgData = 690F0000
2016.04.06 15:28:19.377 5: MyHMLAN: HM485_ProcessResponse: deviceKey =  requestType = 78 requestData = 0FFF msgData = 690F0000
2016.04.06 15:28:19.379 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state value1 = 0
2016.04.06 15:28:19.379 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:19.380 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 0
2016.04.06 15:28:19.380 5: HM485: Device:valueToControl: valName = state = 0
2016.04.06 15:28:19.381 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state_flags value1 = 0
2016.04.06 15:28:19.381 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:19.381 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 0
2016.04.06 15:28:19.382 5: HM485: Device:valueToControl: valName = working = 0
2016.04.06 15:28:19.382 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 16 msgData = 690F0000 actionType = response
2016.04.06 15:28:19.382 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Removing Queue 00000193
2016.04.06 15:28:19.383 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000193
2016.04.06 15:28:19.384 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:19.384 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:19.386 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:19.387 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:19.419 4: HMW01.O04: HM485_ChannelDoUpdate
2016.04.06 15:28:19.419 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = working value = off Alter Wert = off
2016.04.06 15:28:19.420 4: HMW01.O04: working -> off
2016.04.06 15:28:19.420 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = state value = off Alter Wert = set_toggle
2016.04.06 15:28:19.420 4: HMW01.O04: state -> off
2016.04.06 15:28:19.420 5: Triggering HMW01.O04 (2 changes)
2016.04.06 15:28:19.421 5: Starting notify loop for HMW01.O04, first event working: off
2016.04.06 15:28:19.422 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:28:19.423 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: working: off , Reading: working , Value: off , Unit:
2016.04.06 15:28:19.463 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: off , Reading: state , Value: off , Unit:
2016.04.06 15:28:19.695 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 102 Cmd: 101
2016.04.06 15:28:19.695 4: MyHMLAN: Event:HASH(0x5936ce8)
2016.04.06 15:28:19.696 5: MyHMLAN dispatch \FDfe\FF\FF\FF\FF\BC\00\00\B8\C5K\00\00\CE
2016.04.06 15:28:19.696 5: MyHMLAN: HM485_Parse: MsgId: 102
2016.04.06 15:28:19.696 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:19.697 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4B0000CE
2016.04.06 15:28:19.700 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 51
2016.04.06 15:28:19.700 5: HM485: HM485:Device:dataConversion: retVal = 51
2016.04.06 15:28:19.700 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 51
2016.04.06 15:28:19.700 5: HM485: Device:valueToControl: valName = press_short = 51
2016.04.06 15:28:19.701 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B0000CE actionType = frame
2016.04.06 15:28:19.702 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:19.702 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:19.704 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:19.705 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:19.717 4: HMW01.I01: HM485_ChannelDoUpdate
2016.04.06 15:28:19.717 5: HMW01.I01: HM485_ChannelDoUpdate: valueKey = press_short value = 51 Alter Wert = 50
2016.04.06 15:28:19.718 4: HMW01.I01: press_short -> 51
2016.04.06 15:28:19.718 5: Triggering HMW01.I01 (2 changes)
2016.04.06 15:28:19.718 5: Starting notify loop for HMW01.I01, first event press_short: 51
2016.04.06 15:28:19.720 5: Triggering N_1.I01_short
2016.04.06 15:28:19.720 4: N_1.I01_short exec set HMW01.O04 toggle
2016.04.06 15:28:19.720 5: Cmd: >set HMW01.O04 toggle<
2016.04.06 15:28:19.724 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_SendCommand: 780FFF
2016.04.06 15:28:19.726 5: Notify from Device: HMW01.I01 recieved
2016.04.06 15:28:19.727 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short: 51 , Reading: press_short , Value: 51 , Unit:
2016.04.06 15:28:19.762 5: DbLog: logging of Device: HMW01.I01 , Type: HM485 , Event: press_short_51 , Reading: state , Value: press_short_51 , Unit:
2016.04.06 15:28:19.776 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 103 Cmd: 101
2016.04.06 15:28:19.777 4: MyHMLAN: Event:HASH(0x5936f28)
2016.04.06 15:28:19.777 5: MyHMLAN dispatch \FDge\FF\FF\FF\FF\BE\00\00\B8\C5A\00\00LEQ0016352
2016.04.06 15:28:19.777 5: MyHMLAN: HM485_Parse: MsgId: 103
2016.04.06 15:28:19.778 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:19.778 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000B8C5 msgData = 4100120003064C455130303136333532
2016.04.06 15:28:19.779 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 15:28:19.780 5: Triggering HMW01.O04 (1 changes)
2016.04.06 15:28:19.781 5: Starting notify loop for HMW01.O04, first event set_toggle
2016.04.06 15:28:19.782 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:28:19.784 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: set_toggle , Reading: state , Value: set_toggle , Unit:
2016.04.06 15:28:19.814 5: MyHMLAN: HM485_LAN_Write TX: 103
2016.04.06 15:28:19.815 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000194
2016.04.06 15:28:19.815 5: SW: fd0f6753c80000b8c51c00000001780fff
2016.04.06 15:28:19.817 4: MyHMLAN: MyHMLAN: TX: (103) I[2](0,F,B)(1C) 00000001 -> 0000B8C5 [5] 78(x) 0FFF
2016.04.06 15:28:19.817 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_DoSendCommand: hmwId = 0000B8C5 data = 780FFF requestId = 103
2016.04.06 15:28:19.818 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:19.818 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:19.820 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:19.821 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:19.862 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 103 Cmd: 114
2016.04.06 15:28:19.863 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Response: (103) 690FC800
2016.04.06 15:28:19.863 5: MyHMLAN dispatch \FDgrXi\C8\00
2016.04.06 15:28:19.863 5: MyHMLAN: HM485_Parse: MsgId: 103
2016.04.06 15:28:19.864 5: MyHMLAN: HM485_Parse: ProcessResponse
2016.04.06 15:28:19.864 5: MyHMLAN: HM485_ProcessResponse: msgData = 690FC800
2016.04.06 15:28:19.864 5: MyHMLAN: HM485_ProcessResponse: deviceKey =  requestType = 78 requestData = 0FFF msgData = 690FC800
2016.04.06 15:28:19.866 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state value1 = 200
2016.04.06 15:28:19.867 5: HM485: HM485:Device:dataConversion: retVal = 1
2016.04.06 15:28:19.867 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 1
2016.04.06 15:28:19.867 5: HM485: Device:valueToControl: valName = state = 1
2016.04.06 15:28:19.868 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = state_flags value1 = 0
2016.04.06 15:28:19.869 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:19.869 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 0
2016.04.06 15:28:19.869 5: HM485: Device:valueToControl: valName = working = 0
2016.04.06 15:28:19.869 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 16 msgData = 690FC800 actionType = response
2016.04.06 15:28:19.870 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Removing Queue 00000194
2016.04.06 15:28:19.870 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000194
2016.04.06 15:28:19.871 5: Triggering HMW_IO_12_Sw7_DR_LEQ0016352 (1 changes)
2016.04.06 15:28:19.872 5: Starting notify loop for HMW_IO_12_Sw7_DR_LEQ0016352, first event ACK
2016.04.06 15:28:19.873 5: Notify from Device: HMW_IO_12_Sw7_DR_LEQ0016352 recieved
2016.04.06 15:28:19.874 5: DbLog: logging of Device: HMW_IO_12_Sw7_DR_LEQ0016352 , Type: HM485 , Event: ACK , Reading: state , Value: ACK , Unit:
2016.04.06 15:28:19.906 4: HMW01.O04: HM485_ChannelDoUpdate
2016.04.06 15:28:19.906 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = working value = off Alter Wert = off
2016.04.06 15:28:19.907 4: HMW01.O04: working -> off
2016.04.06 15:28:19.907 5: HMW01.O04: HM485_ChannelDoUpdate: valueKey = state value = on Alter Wert = set_toggle
2016.04.06 15:28:19.907 4: HMW01.O04: state -> on
2016.04.06 15:28:19.908 5: Triggering HMW01.O04 (2 changes)
2016.04.06 15:28:19.908 5: Starting notify loop for HMW01.O04, first event working: off
2016.04.06 15:28:19.909 5: Notify from Device: HMW01.O04 recieved
2016.04.06 15:28:19.911 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: working: off , Reading: working , Value: off , Unit:
2016.04.06 15:28:19.950 5: DbLog: logging of Device: HMW01.O04 , Type: HM485 , Event: on , Reading: state , Value: on , Unit:
2016.04.06 15:28:22.101 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 104 Cmd: 101
2016.04.06 15:28:22.102 4: MyHMLAN: Event:HASH(0x5938610)
2016.04.06 15:28:22.102 5: MyHMLAN dispatch \FDhe\00\00\00Z\00\00\9A+i\00;\CF
2016.04.06 15:28:22.102 5: MyHMLAN: HM485_Parse: MsgId: 104
2016.04.06 15:28:22.102 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:22.103 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003BCF
2016.04.06 15:28:22.105 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:22.106 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:22.110 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:22.111 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:22.112 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15311
2016.04.06 15:28:22.112 5: HM485: HM485:Device:dataConversion: retVal = 15311.00
2016.04.06 15:28:22.112 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: value2 = 15311.00
2016.04.06 15:28:22.113 5: HM485: Device:valueToControl: valName = frequency = 15311.00
2016.04.06 15:28:22.113 5: HMW_IO_12_Sw14_DR_JEQ0459634: HM485_ProcessChannelState: hmwId = 00009A2B Channel = 15 msgData = 690E003BCF actionType = frame
2016.04.06 15:28:22.114 4: HMW02.I07: HM485_ChannelDoUpdate
2016.04.06 15:28:22.115 5: HMW02.I07: HM485_ChannelDoUpdate: valueKey = frequency value = 15311.00 Alter Wert = 15165.00
2016.04.06 15:28:22.115 4: HMW02.I07: frequency -> 15311.00
2016.04.06 15:28:23.603 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 105 Cmd: 101
2016.04.06 15:28:23.604 4: MyHMLAN: Event:HASH(0x59474e0)
2016.04.06 15:28:23.604 5: MyHMLAN dispatch \FDie\00\00\00\\00\00\9A+i\00;\86
2016.04.06 15:28:23.604 5: MyHMLAN: HM485_Parse: MsgId: 105
2016.04.06 15:28:23.605 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.06 15:28:23.605 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E003B86
2016.04.06 15:28:23.607 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:23.609 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:23.612 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:23.613 5: HM485: HM485:Device:dataConversion: retVal = 0
2016.04.06 15:28:23.614 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW14_DR valId = state value1 = 15238
2016.04.06 15:28:23.614 5: HM485: HM485:Device:dataConversion: retVal = 15238.00
2016.04.06 15:28:23.614 5: HMW_IO_12_Sw14_DR_JEQ0459634: Device:con
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 06 April 2016, 15:41:39
Hi,
wie sehen denn Deine notifys aus?
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 15:48:11
Hi Thorsten, bezüglich der notifies:

Hier das erste notify als Beispiel:
HMW01.I01:press_long:.* set FS20_Garage onHMW01.I01:press_short:.* set FS20_Garage offSo habe ich das für jeden Eingang meines Homematic angelegt, mit verschiedenen set-Befehlen natürlich.
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 06 April 2016, 15:50:15
Hi,
hab die notifys grade gefunden. Allerdings wundere ich mich, wo dann das hier im Event-Monitor herkommt:
2016-04-06 13:24:09.825 HM485 HMW01.O04 set_toggle
Anyway...
Erst einmal sollten wir rausfinden, ob da tatsächlich Events verloren gehen. Ist es tatsächlich so, dass etwas im Event-Monitor fehlt?
Ansonsten: Taucht das Problem nur für HMW-Aktoren auf oder auch für FS20-Aktoren?
Das Problem ist: Das Toggle-Kommando ist für manche Aktoren in FHEM implementiert, da es der Aktor selbst nicht kann. Das Problem dabei ist, dass erst einmal die Rückmeldung vom Aktor kommen muss, bis man wieder korrekt "toggeln" kann.
Gruß,
   Thorsten

 
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 16:03:18
Hi,
hab die notifys grade gefunden. Allerdings wundere ich mich, wo dann das hier im Event-Monitor herkommt:
2016-04-06 13:24:09.825 HM485 HMW01.O04 set_toggle
Anyway...
define N_1.I01_short notify HMW01.I01:press_short:.* set HMW01.O04 toggle
attr N_1.I01_short group n_short
attr N_1.I01_short room _notify
define N_1.I01_long notify HMW01.I01:press_long:.* set FS20_Garage toggle
attr N_1.I01_long group n_long
attr N_1.I01_long room _notify

Hi Thorsten,
da ist mir wohl beim testen eine asynchronität unterlaufen.. vorher hat das drin gestanden, habs jetzt mal aus der fhem.cfg rausgeklaubt...

Ansonsten: Taucht das Problem nur für HMW-Aktoren auf oder auch für FS20-Aktoren?
Das Problem ist: Das Toggle-Kommando ist für manche Aktoren in FHEM implementiert, da es der Aktor selbst nicht kann. Das Problem dabei ist, dass erst einmal die Rückmeldung vom Aktor kommen muss, bis man wieder korrekt "toggeln" kann.

Das Problem taucht nicht nur für HMW-Aktoren auf.
Ich habe grade mal getestet:

define N_1.I01_short notify HMW01.I01:press_short:.* set HMW01.O04 off
attr N_1.I01_short group n_short
attr N_1.I01_short room _notify
define N_1.I01_long notify HMW01.I01:press_long:.* set HMW01.O04 on
attr N_1.I01_long group n_long
attr N_1.I01_long room _notify

auch hierbei treten die Probleme wie beschrieben auf.

dann habe ich getestet:
 
define N_1.I01_short notify HMW01.I01:press_short:.* set FS20_TEST off
attr N_1.I01_short group n_short
attr N_1.I01_short room _notify
define N_1.I01_long notify HMW01.I01:press_long:.* set FS20_TEST on
attr N_1.I01_long group n_long
attr N_1.I01_long room _notify

Hierbei treten die Verzögerungen im Schalten des FS20ST4 genauso auf, d.h. ich habe ein vergleichbares delay. Jetzt könnte ich noch HMW und FS20 gleichzeitig schalten lassen und schauen ob das synchron ist ...

Ob im Event-Monitor Events *fehlen*, weiss ich nicht. Die nummerierung ist durchgehend. Allerdings ist es tatsächlich so, dass Tastendrücke, die ich ausgeführt habe, nicht aufgelistet, nummeriert und bearbeitet werden.

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 17:37:29
Okay, nochmal ne Info von mir. Gerade habe ich den Taster im Bad ca. 60 Sekunden festgehalten. Vielleicht auch mehr, hatte mein Handy mit dem Event-Monitor nicht so schnell zur Hand. Habe auf jeden Fall nicht losgelassen. Taster siehe unten(Badezimmer.png). Dazu gehört das notify long, siehe notify_long.png
Im Eventmonitor sind folgende Zeilen aufgetaucht:

2016-04-06 17:31:06.858 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:06.905 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:06.949 HM485 HMW02.I07 leistung: 607.5
2016-04-06 17:31:06.994 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:08.816 OWDevice DS18B20_Ruecklauf_WT_EG temperature: 26.75
2016-04-06 17:31:09.784 HM485 HMW02.I07 leistung: 305.5
2016-04-06 17:31:12.800 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:13.089 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:13.391 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:13.690 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:13.991 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:14.292 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:14.593 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:14.916 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:15.195 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:18.178 HM485 HMW02.I07 leistung: 303.5
2016-04-06 17:31:20.333 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:20.610 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:20.910 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:21.210 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:21.511 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:21.814 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:21.964 HMS HMS100TF_5f5e temperature: 21.3
2016-04-06 17:31:21.964 HMS HMS100TF_5f5e humidity: 43.8
2016-04-06 17:31:21.964 HMS HMS100TF_5f5e battery: ok
2016-04-06 17:31:21.964 HMS HMS100TF_5f5e type: HMS100TF
2016-04-06 17:31:21.964 HMS HMS100TF_5f5e T: 21.3  H: 43.8  Bat: ok
2016-04-06 17:31:21.964 HMS HMS100TF_5f5e taupunkt: 8.49547065340043
2016-04-06 17:31:27.543 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:27.829 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:28.130 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:28.186 HM485 HMW02.I07 literpromin: 17.5442810364209
2016-04-06 17:31:28.186 HM485 HMW02.I07 leistung: 306
2016-04-06 17:31:28.432 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:28.731 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:29.033 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:29.334 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:29.634 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:29.934 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:30.334 HM485 HMW02.I07 leistung: 305
2016-04-06 17:31:32.190 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:32.220 HM485 HMW_IO_12_Sw7_DR_LEQ0016352 ACK
2016-04-06 17:31:38.516 HM485 HMW02.I07 leistung: 305.666666666667
2016-04-06 17:31:42.043 HM485 HMW02.I07 spreizung: 0.338235294117647
2016-04-06 17:31:44.457 CUL_WS S300TH T: 16.6  H: 64.9
2016-04-06 17:31:44.457 CUL_WS S300TH humidity: 64.9
2016-04-06 17:31:44.457 CUL_WS S300TH taupunkt: 9.9
2016-04-06 17:31:45.231 TelegramBot remotebot PollingLastError: NonBlockingGet timed out on read from  after 65s
2016-04-06 17:31:45.264 TelegramBot remotebot PollingErrCount: 46
2016-04-06 17:31:45.303 HM485 HMW02.I07 leistung: 339.666666666667
2016-04-06 17:31:48.972 HM485 HMW02.I07 literpromin: 17.5092420339057
2016-04-06 17:31:50.870 OWDevice DS18B20_RuecklaufHK temperature: 26.5
2016-04-06 17:31:51.754 OWDevice DS18B20_Ruecklauf_Zirkulation temperature: 37.5
2016-04-06 17:31:52.758 OWDevice DS18B20_Mischer_unten temperature: 25.25
2016-04-06 17:31:53.754 OWDevice DS18B20_Vorlauf_WW temperature: 45
2016-04-06 17:31:58.804 OWDevice DS18B20_Vorlauf_EG_Boden temperature: 24.5
2016-04-06 17:31:58.840 HM485 HMW02.I07 leistung: 364.6
2016-04-06 17:32:02.821 HM485 HMW02.I07 leistung: 611.5
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 06 April 2016, 17:39:01
Hi,
hmm... Also erst sagst Du, dass es nur bei HMW-Aktoren auftritt und dann, dass es auch bei FS20-Aktoren passiert. Ja was denn nun?
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 17:53:29
Hi,
da habe ich mich wohl nicht ganz korrekt artikuliert, davon abgesehen, dass ich bei FHEM gerade nicht mehr weiss, wo mir der Kopf steht... ich teste was, stelle fest dass es geht, und ne Stunde später geht das was vorher nicht ging und umgekehrt... Sorry wenn ich dich da durcheinandergebracht habe...

Was ich oben eigentlich sagen wollte ist, dass der S4(FS20-Taster) events produziert, und diese (vermutlich) alle im Eventlog protokolliert werden. Aber das hat ja wiederum nichts mit den Aktoren zu tun ...

Ist es jetzt klarer?   :-\ ::)

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 06 April 2016, 19:37:48
Hi,

Du scheinst mit event-on-change-reading zu arbeiten. Dann ist es schonmal kein Wunder, das bei den long_press nicht wie erwartet alle 300ms ein Event kommt. Komisch ist allerdings, dass auch der erste long_press nicht erscheint. Komisch ist dabei, dass das Device selbst anscheinend alle 300ms ein ACK schickt, d.h. es muss was empfangen haben. Es wird also irgendwas geschaltet, obwohl kein Event im Monitor angezeigt wird???

Ich würde mal sagen, dass wir jetzt aus den ganzen Problemen erst einmal genau eins herauspicken sollten. Könntest Du mal folgendes probieren:
Schalte mal das Log Level nur für das eine Device mit einem Taster (wahrscheinlich HMW_IO_12_Sw7_DR_LEQ0016352) auf 5. Das globale Log Level bitte maximal auf 3. Dann einen Taster ungefähr im Takt von 1 Mal pro Sekunde 10 Mal kurz drücken.
Am besten wäre es, wenn alle zugehörigen Notifys inaktiv wären.
Das Log würde ich gerne sehen.

Gruß,
   Thorsten


Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 06 April 2016, 22:24:27
attr HMW_IO_12_Sw7_DR_LEQ0016352 verbose 5 - check and set.
attr global loglevel 3 - check and set.
attr N_1.I01_long disable 1 - check and set
attr N_1.I01_short disable 1 - check and set



2016.04.06 22:20:08.432 1: Perfmon: possible freeze starting at 22:20:07, delay is 1.432
2016.04.06 22:21:01.769 1: Perfmon: possible freeze starting at 22:20:59, delay is 2.768
2016.04.06 22:21:03.266 1: Perfmon: possible freeze starting at 22:21:02, delay is 1.266
2016.04.06 22:21:05.004 1: Perfmon: possible freeze starting at 22:21:04, delay is 1.004
2016.04.06 22:21:07.275 1: Perfmon: possible freeze starting at 22:21:06, delay is 1.275
2016.04.06 22:21:13.833 3: backupCfg return value: Can't modify single ref constructor in scalar assignment at (eval 82241) line 1, at EOF
syntax error at (eval 82241) line 1, at EOF

2016.04.06 22:21:37.737 3: PID20 PID.FUBO: Calc.709 <set D_MischerPID  70>
2016.04.06 22:22:02.590 1: Perfmon: possible freeze starting at 22:22:01, delay is 1.59
2016.04.06 22:22:38.706 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 6
2016.04.06 22:22:38.706 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 6
2016.04.06 22:22:38.708 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B00001A actionType = frame
2016.04.06 22:22:38.809 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 22:22:40.347 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 7
2016.04.06 22:22:40.348 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 7
2016.04.06 22:22:40.348 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B00001E actionType = frame
2016.04.06 22:22:40.445 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 22:22:45.735 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 8
2016.04.06 22:22:45.735 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 8
2016.04.06 22:22:45.737 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B000022 actionType = frame
2016.04.06 22:22:45.739 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 22:22:46.123 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 9
2016.04.06 22:22:46.124 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 9
2016.04.06 22:22:46.124 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B000026 actionType = frame
2016.04.06 22:22:46.195 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 22:22:51.841 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 10
2016.04.06 22:22:51.842 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 10
2016.04.06 22:22:51.842 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B00002A actionType = frame
2016.04.06 22:22:51.917 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
2016.04.06 22:22:53.260 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 11
2016.04.06 22:22:53.260 5: HMW_IO_12_Sw7_DR_LEQ0016352: Device:convertFrameDataToValue: value2 = 11
2016.04.06 22:22:53.261 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 Channel = 01 msgData = 4B00002E actionType = frame
2016.04.06 22:22:53.347 5: HMW_IO_12_Sw7_DR_LEQ0016352: HM485_ProcessChannelState: hmwId = 0000B8C5 No channel
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 06 April 2016, 22:38:08
Hi,
für mich sieht das so aus, als ob das Device die Tastendrücke erst gar nicht rausschickt. Man sieht z.B. zwischen 22:22:40 und 22:22:45, dass hier eine Lücke von 5 Sekunden ist, aber der Counter der Tastendrücke springt von 7 nach 8. Hätte das Device dazwischen Deine Tastendrücke (im Sekundentakt) erfasst und rausgeschickt, dann würde man einen Sprung von 7 nach 12 oder so sehen.
Ich kann mir folgendes vorstellen:

Beim letzten Punkt kann ich mir vorstellen, dass das Device in dem Fall einfach wartet und daher auch die Tastendrücke nicht mitbekommt.
Hast Du den so genannten Abschlußwiderstand eingebaut? Ansonsten könnten irgendwelche Zufalls-Signale auf dem Bus für Chaos sorgen.
Ansonsten könnte ich mir noch vorstellen, dass der Lan-Adapter Probleme macht. Du hast doch das Original eq3-Teil, oder?
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 08 April 2016, 16:12:53
Hi Thorsten,
sorry dass die Antwort dieses mal so lange gedauert hat.
Ich dreh mich echt im Kreis: jedes mal, wenn ich irgendwas teste, ist es nicht so wie vorher, sondern verhält sich anders... *verzweifel*

also:

Wackelkontakt am Taster: ich habe folgendes gemacht:
Als erstes habe ich das event-on-change gelöscht.
Einen Draht an I1 und Masse geklemmt -> kein Wackelkontakt. Die Events haben Aussetzer. Meistens so 5-10 Sekunden, aber auch alle anderen Werte sind mal vertreten.
Der 12/7 ist kaputt:
Also habe ich den Draht an I1 von meinem anderen 12/7 geklemmt: Ergebnis: die Events haben deutlich weniger und kürzere Aussetzer, ABER: es gibt immer noch welche. Sporadisch, nicht nachvollziehbar.

Also kanns noch am Bus liegen. Einen Endwiderstand habe ich bestellt, aber irgendwie ist Prime heute nicht so eilig ... naja, kommts halt morgen - vielleicht.
Aber: Wenns am Bus liegt: warum dann nur bei einem Device?

An den LAN-Adapter habe ich auch schon gedacht, selbe Frage wie oben: warum nur bei einem Device?

Kann man die Devices resetten? eigentlich ist ja nichts drauf gespeichert ( bei mir, keine direkten peerings). Und der LAN-Gateway hat auch einen RESET. Weisst du, was passiert, wenn ich den drücke?

Viele Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 08 April 2016, 20:45:06
Also kanns noch am Bus liegen. Einen Endwiderstand habe ich bestellt, aber irgendwie ist Prime heute nicht so eilig ... naja, kommts halt morgen - vielleicht.
Aber: Wenns am Bus liegt: warum dann nur bei einem Device?
Wenn kein "Abschlusswiderstand" im Bus hängt, dann kannst Du irgendwelches Rauschen auf dem Bus haben. Was dann passiert ist purer Zufall.
Bau' erstmal das Teil ein. Wenn es dann noch Probleme gibt, dann sehen wir weiter.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 08 April 2016, 21:14:55
Okay, kommt hoffentlich morgen.

Ich hab gerade mal fhem auf nem raspi installiert:

5.7,
version:
Latest Revision: 11204

File                     Rev   Last Change

fhem.pl                  11191 2016-04-05 11:55:05Z rudolfkoenig
98_autocreate.pm         11094 2016-03-20 09:41:03Z rudolfkoenig
91_eventTypes.pm         10530 2016-01-16 19:27:21Z rudolfkoenig
01_FHEMWEB.pm            11178 2016-04-03 14:20:32Z rudolfkoenig
92_FileLog.pm            10530 2016-01-16 19:27:21Z rudolfkoenig
# $Id: 10_HM485.pm 0743 2016-02-26 09:02:00Z Thorsten Pferdekaemper $
No Id found for 00_HM485_LAN.pm
No Id found for hmw_io12_sw14_dr.pm
No Id found for hmw_io12_sw7_dr.pm
No Id found for hmw_io12_sw7_dr_V3_02.pm
No Id found for hmw_io_12_fm.pm
No Id found for hmw_sen_sc_12_dr.pm
91_notify.pm             11178 2016-04-03 14:20:32Z rudolfkoenig
99_SUNRISE_EL.pm         10569 2016-01-19 06:30:28Z rudolfkoenig
98_telnet.pm             10551 2016-01-17 11:03:44Z rudolfkoenig
99_Utils.pm              10570 2016-01-19 06:39:23Z rudolfkoenig
98_version.pm            11026 2016-03-08 07:25:45Z markusbloch

No Id found for ConfigurationManager.pm
No Id found for Constants.pm
No Id found for Device.pm
DevIo.pm                 11020 2016-03-07 12:01:43Z rudolfkoenig
No Id found for FhemWebHelper.pm
No Id found for hmw_central.pm
No Id found for hmw_generic.pm
No Id found for hmw_io_4_fm.pm
No Id found for hmw_io_4_fm_V3_02.pm
No Id found for hmw_io_sr_fm.pm
No Id found for hmw_lc_bl1_dr.pm
No Id found for hmw_lc_bl1_dr_V3_02.pm
No Id found for hmw_lc_dim1l_dr.pm
No Id found for hmw_lc_sw2_dr.pm
No Id found for hmw_lc_sw2_dr_V3_02.pm
No Id found for hmw_virtual.pm
HttpUtils.pm             11154 2016-03-30 08:57:41Z rudolfkoenig
No Id found for PeeringManager.pm
RTypes.pm                10476 2016-01-12 21:03:33Z borisneubert
TcpServerUtils.pm        10346 2016-01-03 12:34:27Z rudolfkoenig
No Id found for Util.pm

Den 14/12 hat er sofort erkannt, da der Impulsgeber noch dran ist.
Die 12/7er erkennt er nicht, ich hab auch im Wiki keine Hilfestellung gefunden.
Nachdem ich einen Eingang fest auf Masse verdrahtet hatte, finde ich folgende Fehlermeldungen im verbose 5 - log:

2016.04.08 19:11:05 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 100 Cmd: 101
2016.04.08 19:11:05 4: MyHMLAN: Event:HASH(0x10ddaf0)
2016.04.08 19:11:05 5: MyHMLAN dispatch <FD>^Ode<FF><FF><FF><FF><F8>^@^@<D1>%K^B^@S
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: MsgId: 100
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.08 19:11:05 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000D125 msgData = 4B020053
2016.04.08 19:11:05 4: MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreate
2016.04.08 19:11:05 5: MyHMLAN: HM485_QueueCommand68
2016.04.08 19:11:05 5: MyHMLAN: HM485_QueueStart: Num: 412
2016.04.08 19:11:05 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 101 Cmd: 101
2016.04.08 19:11:05 4: MyHMLAN: Event:HASH(0x10ddee0)
2016.04.08 19:11:05 5: MyHMLAN dispatch <FD>^Oee<FF><FF><FF><FF><FA>^@^@<D1>%K^B^@S
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: MsgId: 101
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.08 19:11:05 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000D125 msgData = 4B020053
2016.04.08 19:11:05 4: MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreate
2016.04.08 19:11:05 5: MyHMLAN: HM485_QueueCommand68
2016.04.08 19:11:05 5: MyHMLAN: HM485_QueueStart: Num: 413
2016.04.08 19:11:05 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 102 Cmd: 101
2016.04.08 19:11:05 4: MyHMLAN: Event:HASH(0x10d7c90)
2016.04.08 19:11:05 5: MyHMLAN dispatch <FD>^Pfe^@^@^@^A<FC>^@^@<9A>+i^N^@(<
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: MsgId: 102
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.08 19:11:05 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E00283C
2016.04.08 19:11:05 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 103 Cmd: 101
2016.04.08 19:11:05 4: MyHMLAN: Event:HASH(0x10ddc70)
2016.04.08 19:11:05 5: MyHMLAN dispatch <FD>^Oge<FF><FF><FF><FF><FC>^@^@<D1>%K^B^@S
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: MsgId: 103
2016.04.08 19:11:05 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.08 19:11:05 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000D125 msgData = 4B020053
2016.04.08 19:11:05 4: MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreate
2016.04.08 19:11:05 5: MyHMLAN: HM485_QueueCommand68
2016.04.08 19:11:05 5: MyHMLAN: HM485_QueueStart: Num: 414

ein set myHMLAN discovery start liefert
2016.04.08 19:19:28 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E0027E4
2016.04.08 19:19:32 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 53 Cmd: 101
2016.04.08 19:19:32 4: MyHMLAN: Event:HASH(0x11cbef0)
2016.04.08 19:19:32 5: MyHMLAN dispatch <FD>^P5e^@^@^@^A<F8>^@^@<9A>+i^N^@'<8E>
2016.04.08 19:19:32 5: MyHMLAN: HM485_Parse: MsgId: 53
2016.04.08 19:19:32 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.08 19:19:32 5: MyHMLAN: HM485_ProcessEvent: hmwId = 00009A2B msgData = 690E00278E
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_Write TX: 14
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000035
2016.04.08 19:19:34 5: SW: fd0d0e53c8ffffffff98000000017a
2016.04.08 19:19:34 4: MyHMLAN: MyHMLAN: TX: (14) I[0](0,Y,F,B)(98) 00000001 -> FFFFFFFF [3] 7A(z)
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_Write TX: 15
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_Write TX: 16
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000035
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000036
2016.04.08 19:19:34 5: SW: fd0d0f53c8ffffffff98000000017a
2016.04.08 19:19:34 4: MyHMLAN: MyHMLAN: TX: (15) I[0](0,Y,F,B)(98) 00000001 -> FFFFFFFF [3] 7A(z)
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 15 Cmd: 97
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (15) 02 AliveStatus: 02
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_DispatchNack: Start
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000036
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000037
2016.04.08 19:19:34 5: SW: fd04104400ff
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 14 Cmd: 97
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (14) 01 AliveStatus: 01
2016.04.08 19:19:34 5: MyHMLAN: HM485_LAN_DispatchNack: Start
2016.04.08 19:19:35 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000037
2016.04.08 19:19:35 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.08 19:19:35 5: MyHMLAN: HM485_LAN_DispatchNack: Start
2016.04.08 19:19:44 2: MyHMLAN: Discovery - canceled. No results found within 10 seconds!
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_Write TX: 17
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000038
2016.04.08 19:19:44 5: SW: fd0d1153c8ffffffff98000000015a
2016.04.08 19:19:44 4: MyHMLAN: MyHMLAN: TX: (17) I[0](0,Y,F,B)(98) 00000001 -> FFFFFFFF [3] 5A(Z)
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_Write TX: 18
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000038
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000039
2016.04.08 19:19:44 5: SW: fd0d1253c8ffffffff98000000015a
2016.04.08 19:19:44 4: MyHMLAN: MyHMLAN: TX: (18) I[0](0,Y,F,B)(98) 00000001 -> FFFFFFFF [3] 5A(Z)
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 18 Cmd: 97
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (18) 02 AliveStatus: 02
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_DispatchNack: Start
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000039
2016.04.08 19:19:44 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 54 Cmd: 101
2016.04.08 19:19:44 4: MyHMLAN: Event:HASH(0x11cbbf0)
2016.04.08 19:19:44 5: MyHMLAN dispatch <FD>^P6e^@^@^@^A<FA>^@^@<9A>+i^N^@$2
2016.04.08 19:19:44 5: MyHMLAN: HM485_Parse: MsgId: 54

Deutet das drauf hin, dass da irgendwas kaputt ist?
Falls nicht, ich melde mich wieder, wenn der Abschluss drin ist.



Viele Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 10 April 2016, 20:20:51
Hi,

ich nehme mal an, dass Du den Draht zwischen Masse und Eingang länger drangelassen hast. Dann schickt das Device alle 300ms ein "long press", wodurch der Autocreate-Mechanismus vielleicht durcheinanderkommt. Also einfach nur kurz Masse und Eingang verbinden.
Wenn das nicht klappt, dann das Ding per define manuell anlegen, also in etwa
define IrgendeinName HM485 0000D125
Danach dann ggf. manuell ein "get IrgendeinName config all" absetzen.

Der Discovery Mode funktioniert mit dem Original-eq3 Lan-Adapter nicht. Ich habe zwar so ein Teil, aber ich habe es nie zum Laufen gebracht. Möglicherweise ist es kaputt. Ich bin allerdings auch nicht so richtig motiviert, mir so ein Teil zu kaufen. Mit dem einfachen USB-Wandler geht bei mir alles.

Gruß,
  Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 11 April 2016, 00:11:56
Hi Thorsten,

gestern ist bei mir der Überspannungsschutz mit eingebautem Busabschluss angekommen. Eigentlich dachte ich, an dem wäre eine LED dran, auf der man BUS-Aktivität erkennt - naja egal.
Den habe ich auf jeden Fall jetzt eingebaut - Keine Änderung.
Dann habe ich mal einen anderen 12/7  ( bei mir #3) zum testen verwendet und den - vielleicht defekten - #1 vom Bus getrennt.
Daraufhin hatte ich bei einem ersten Test zwar recht schnelle Antworten, aber leider fehlt eine ( siehe Nummerierung der Events).

2016-04-11 00:04:23.894 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:23.908 FS20 FS20_TEST off
2016-04-11 00:04:23.956 HM485 HMW03.I01 press_short: 59
2016-04-11 00:04:23.956 HM485 HMW03.I01 press_short_59
2016-04-11 00:04:23.002 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:25.299 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:25.314 FS20 FS20_TEST off
2016-04-11 00:04:25.359 HM485 HMW03.I01 press_short: 60
2016-04-11 00:04:25.359 HM485 HMW03.I01 press_short_60
2016-04-11 00:04:25.389 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:26.323 HM485 HMW02.I07 literpromin: 11.9940383970905
2016-04-11 00:04:26.323 HM485 HMW02.I07 leistung: 207.666666666667
2016-04-11 00:04:26.356 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:27.077 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:27.091 FS20 FS20_TEST off
2016-04-11 00:04:27.139 HM485 HMW03.I01 press_short: 62
2016-04-11 00:04:27.139 HM485 HMW03.I01 press_short_62
2016-04-11 00:04:27.170 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:27.981 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:27.996 FS20 FS20_TEST off
2016-04-11 00:04:28.059 HM485 HMW03.I01 press_short: 63
2016-04-11 00:04:28.059 HM485 HMW03.I01 press_short_63
2016-04-11 00:04:28.092 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:28.882 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:28.897 FS20 FS20_TEST off
2016-04-11 00:04:28.957 HM485 HMW03.I01 press_short: 0
2016-04-11 00:04:28.957 HM485 HMW03.I01 press_short_0
2016-04-11 00:04:28.002 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:29.685 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:29.699 FS20 FS20_TEST off
2016-04-11 00:04:29.768 HM485 HMW03.I01 press_short: 1
2016-04-11 00:04:29.768 HM485 HMW03.I01 press_short_1
2016-04-11 00:04:29.813 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:30.688 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
2016-04-11 00:04:30.702 FS20 FS20_TEST off
2016-04-11 00:04:30.770 HM485 HMW03.I01 press_short: 2
2016-04-11 00:04:30.770 HM485 HMW03.I01 press_short_2
2016-04-11 00:04:30.803 HM485 HMW_IO_12_Sw7_DR_LEQ0233076 ACK
Werde mal noch weiter testen.

Habe einen USB-RS485-Adapter gefunden. Wenn ich dazu komme, teste ich damit auch mal.

Viele Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 11 April 2016, 10:25:04
So, habe eine komplett neue Baustelle eröffnet:
ein Raspberry-Pi mit Fhem, einem USB-RS485-Wandler und daran einen ! der 12/7er Bausteine (nämlich den 3er).

Habe laut Wiki den MyHMLAN angelegt, /dev/ttyUSB0, bind 1 und die HM485d.plk ausführbar gemacht.
HMLAN startet problemlos, allerdings scheint auch hier das discovery nicht zu funktionieren.
Nicht einmal auf Tastendruck wird das device automatisch angelegt:

016.04.11 08:19:55.107 4: MyHMLAN: Do action after discovery Found Devices: 0
2016.04.11 08:19:55.108 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Removing Queue 00000008
2016.04.11 08:19:55.108 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000008
2016.04.11 08:19:55.109 5: MyHMLAN: HM485_LAN_Write TX: 12
2016.04.11 08:19:55.109 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 00000009
2016.04.11 08:19:55.110 5: SW: fd0d0c53c8ffffffff98000000015a
2016.04.11 08:19:55.112 4: MyHMLAN: MyHMLAN: TX: (12) I[0](0,Y,F,B)(98) 00000001 -> FFFFFFFF [3] 5A(Z)
2016.04.11 08:19:55.113 5: MyHMLAN: HM485_LAN_Write TX: 13
2016.04.11 08:19:55.214 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 00000009
2016.04.11 08:19:55.214 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 0000000A
2016.04.11 08:19:55.214 5: SW: fd0d0d53c8ffffffff98000000015a
2016.04.11 08:19:55.219 4: MyHMLAN: MyHMLAN: TX: (13) I[0](0,Y,F,B)(98) 00000001 -> FFFFFFFF [3] 5A(Z)
2016.04.11 08:19:55.320 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 0000000A
2016.04.11 08:20:15.129 5: MyHMLAN: HM485_LAN_Write TX: 14
2016.04.11 08:20:15.131 5: SW: fd020e4b
2016.04.11 08:20:15.135 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 14 Cmd: 97
2016.04.11 08:20:15.136 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (14) 00 AliveStatus: 00
2016.04.11 08:20:28.271 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 0 Cmd: 101
2016.04.11 08:20:28.272 4: MyHMLAN: Event:HASH(0xf219c0)
2016.04.11 08:20:28.272 5: MyHMLAN dispatch <FD>^O^@e<FF><FF><FF><FF><F8>^@^@<D1>%K^@^@<EE>
2016.04.11 08:20:28.506 5: MyHMLAN: HM485_Parse: MsgId: 0
2016.04.11 08:20:28.507 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.11 08:20:28.507 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000D125 msgData = 4B0000EE
2016.04.11 08:20:28.507 4: MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreate
2016.04.11 08:20:28.508 5: MyHMLAN: HM485_QueueCommand68
2016.04.11 08:20:28.508 5: MyHMLAN: HM485_QueueStart: Num: 0
2016.04.11 08:20:28.508 5: MyHMLAN: HM485_QueueProcessStep: HASH(0x1115c38)
2016.04.11 08:20:28.510 5: MyHMLAN: HM485_LAN_Write TX: 15
2016.04.11 08:20:28.511 5: MyHMLAN: HM485_LAN_SendQueueNextItem: QID: 0000000B
2016.04.11 08:20:28.511 5: SW: fd0d0f53c80000d125980000000168
2016.04.11 08:20:28.515 4: MyHMLAN: MyHMLAN: TX: (15) I[0](0,Y,F,B)(98) 00000001 -> 0000D125 [3] 68(h)
2016.04.11 08:20:28.516 5: MyHMLAN: HM485_QueueSetRequestId start
2016.04.11 08:20:28.516 5: MyHMLAN: HM485_QueueSetRequestId: Id: 15
2016.04.11 08:20:28.517 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 0 Cmd: 101
2016.04.11 08:20:28.518 4: MyHMLAN: Event:HASH(0xd75450)
2016.04.11 08:20:28.518 5: MyHMLAN dispatch <FD>ESC^@e<FF><FF><FF><FF><FA>^@^@<D1>%A^@^R^@^C^FLEQ0233076
2016.04.11 08:20:28.518 5: MyHMLAN: HM485_Parse: MsgId: 0
2016.04.11 08:20:28.519 5: MyHMLAN: HM485_Parse: ProcessEvent
2016.04.11 08:20:28.519 5: MyHMLAN: HM485_ProcessEvent: hmwId = 0000D125 msgData = 4100120003064C455130323333303736
2016.04.11 08:20:28.519 4: MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreate
2016.04.11 08:20:28.520 5: MyHMLAN: HM485_QueueCommand68
2016.04.11 08:20:28.520 5: MyHMLAN: HM485_QueueStart: Num: 1
2016.04.11 08:20:29.126 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 15 Cmd: 97
2016.04.11 08:20:29.126 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (15) 3439 AliveStatus: 34
2016.04.11 08:20:29.127 5: MyHMLAN: HM485_LAN_DispatchNack: Start
2016.04.11 08:20:29.128 3: MyHMLAN: NACK: (15) 0000D125
2016.04.11 08:20:29.517 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: QID: 0000000B
2016.04.11 08:20:29.518 5: MyHMLAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.11 08:20:29.518 5: MyHMLAN: HM485_LAN_DispatchNack: Start
2016.04.11 08:20:49.131 5: MyHMLAN: HM485_LAN_Write TX: 16
2016.04.11 08:20:49.132 5: SW: fd02104b
2016.04.11 08:20:49.136 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 16 Cmd: 97
2016.04.11 08:20:49.137 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (16) 00 AliveStatus: 00
2016.04.11 08:21:09.157 5: MyHMLAN: HM485_LAN_Write TX: 17
2016.04.11 08:21:09.157 5: SW: fd02114b
2016.04.11 08:21:09.161 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 17 Cmd: 97
2016.04.11 08:21:09.161 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (17) 00 AliveStatus: 00
Waiting for data... (interrupt to abort)

attr global userattr cmdIcon devStateIcon devStateStyle icon sortby webCmd widgetOverride
attr global autoload_undefined_devices 1
attr global logfile ./log/fhem-%Y-%m.log
attr global modpath .
attr global motd Hallo Schatzi
attr global mseclog 1
attr global perlSyntaxCheck 1
attr global statefile ./log/fhem.save
attr global updateInBackground 1
attr global verbose 3

define telnetPort telnet 7072 global

define WEB FHEMWEB 8083 global
attr WEB JavaScripts codemirror/fhem_codemirror.js
attr WEB codemirrorParam { "lineWrapping":true }
attr WEB editConfig 1
attr WEB longpoll 1

define autocreate autocreate
attr autocreate filelog ./log/%NAME-%Y.log

#define eventTypes eventTypes ./log/eventTypes.txt

# Disable this to avoid looking for new USB devices on startup
#define initialUsbCheck notify global:INITIALIZED usb create
define MyHMLAN HM485_LAN localhost:2000
attr MyHMLAN HM485d_bind 1
attr MyHMLAN HM485d_device /dev/ttyUSB0
attr MyHMLAN hmwId 00000001
attr MyHMLAN verbose 5


dann versuche ich jetzt mal, das Device manuell anzulegen...

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 11 April 2016, 10:58:00
Wenn das nicht klappt, dann das Ding per define manuell anlegen, also in etwa
define IrgendeinName HM485 0000D125
Danach dann ggf. manuell ein "get IrgendeinName config all" absetzen.

okay, das ergibt nach mehreren versuchen lediglich die Fehlermeldung
2016.04.11 08:50:07.950 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 10 Cmd: 97
2016.04.11 08:50:07.950 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (10) 00 AliveStatus: 00
2016.04.11 08:50:27.951 5: MyHMLAN: HM485_LAN_Write TX: 11
2016.04.11 08:50:27.952 5: SW: fd020b4b
2016.04.11 08:50:27.955 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 11 Cmd: 97
2016.04.11 08:50:27.956 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (11) 00 AliveStatus: 00
2016.04.11 08:50:47.976 5: MyHMLAN: HM485_LAN_Write TX: 12
2016.04.11 08:50:47.977 5: SW: fd020c4b
2016.04.11 08:50:47.981 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 12 Cmd: 97
2016.04.11 08:50:47.982 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (12) 00 AliveStatus: 00
2016.04.11 08:50:58.662 2: MyHMLAN: Assigned 0000D125 as IrgendeinName
2016.04.11 08:50:58.666 5: MyHMLAN: HM485_GetNewMsgQueue
2016.04.11 08:50:58.667 3: MyHMLAN: Initialisierung von Modul 0000D125
2016.04.11 08:51:07.993 5: MyHMLAN: HM485_LAN_Write TX: 13
2016.04.11 08:51:07.994 5: SW: fd020d4b
2016.04.11 08:51:07.998 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 13 Cmd: 97
2016.04.11 08:51:07.999 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (13) 00 AliveStatus: 00
2016.04.11 08:51:24.606 3: IrgendeinName: Initialisierungsfehler 0000D125 ModelName noch nicht vorhanden
2016.04.11 08:51:24.607 5: MyHMLAN: HM485_GetNewMsgQueue
2016.04.11 08:51:24.607 5: IrgendeinName: HM485_QueueCommand68
2016.04.11 08:51:24.608 5: IrgendeinName: HM485_QueueCommand6E
2016.04.11 08:51:24.608 5: IrgendeinName: HM485_QueueCommand76
2016.04.11 08:51:24.608 5: IrgendeinName: HM485_QueueStart: Num: 3
2016.04.11 08:51:24.608 3: MyHMLAN: Initialisierung von Modul 0000D125
2016.04.11 08:51:28.004 5: MyHMLAN: HM485_LAN_Write TX: 14
2016.04.11 08:51:28.004 5: SW: fd020e4b
2016.04.11 08:51:28.008 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 14 Cmd: 97
2016.04.11 08:51:28.009 5: MyHMLAN: HM485_LAN_parseIncommingCommand: Alive: (14) 00 AliveStatus: 00


Ein Tastendruck erzeugt im Event-Monitor ein ACK, aber leider kein press-Event:
2016-04-11 08:33:30.824 HM485 IrgendeinName ACK
2016-04-11 08:33:30.846 HM485 IrgendeinName ACK

dann habe ich mal versucht, "model" selbst zu setzen, auf

model HMW_IO_12_Sw7_DR
wenn ich jetzt ein get config all mache, kommt im Log:
2016.04.11 08:53:25.661 3: IrgendeinName: Lese Eeprom 0000D125
2016.04.11 08:53:25.662 5: MyHMLAN: HM485_GetNewMsgQueue
2016.04.11 08:53:25.662 5: IrgendeinName: HM485_QueueCommand52000010
2016.04.11 08:53:25.662 5: IrgendeinName: HM485_QueueCommand52001010
2016.04.11 08:53:25.663 5: IrgendeinName: HM485_QueueCommand52002010
2016.04.11 08:53:25.663 5: IrgendeinName: HM485_QueueCommand52003010
2016.04.11 08:53:25.663 5: IrgendeinName: HM485_QueueCommand52004010
2016.04.11 08:53:25.663 5: IrgendeinName: HM485_QueueCommand52005010
2016.04.11 08:53:25.664 5: IrgendeinName: HM485_QueueCommand52006010
2016.04.11 08:53:25.664 5: IrgendeinName: HM485_QueueCommand52007010
2016.04.11 08:53:25.664 5: IrgendeinName: HM485_QueueCommand52008010
2016.04.11 08:53:25.664 5: IrgendeinName: HM485_QueueCommand52009010
2016.04.11 08:53:25.665 5: IrgendeinName: HM485_QueueCommand5200A010
2016.04.11 08:53:25.665 5: IrgendeinName: HM485_QueueCommand5200B010
2016.04.11 08:53:25.665 5: IrgendeinName: HM485_QueueCommand5200C010
2016.04.11 08:53:25.665 5: IrgendeinName: HM485_QueueCommand5200D010
2016.04.11 08:53:25.666 5: IrgendeinName: HM485_QueueCommand5200E010
2016.04.11 08:53:25.666 5: IrgendeinName: HM485_QueueCommand5200F010
2016.04.11 08:53:25.666 5: IrgendeinName: HM485_QueueCommand52010010
2016.04.11 08:53:25.666 5: IrgendeinName: HM485_QueueCommand52011010
2016.04.11 08:53:25.667 5: IrgendeinName: HM485_QueueCommand52012010
2016.04.11 08:53:25.667 5: IrgendeinName: HM485_QueueCommand52013010
2016.04.11 08:53:25.667 5: IrgendeinName: HM485_QueueCommand52014010
2016.04.11 08:53:25.667 5: IrgendeinName: HM485_QueueCommand52015010
2016.04.11 08:53:25.668 5: IrgendeinName: HM485_QueueCommand52016010
2016.04.11 08:53:25.668 5: IrgendeinName: HM485_QueueCommand52017010
2016.04.11 08:53:25.668 5: IrgendeinName: HM485_QueueCommand52018010
2016.04.11 08:53:25.668 5: IrgendeinName: HM485_QueueCommand52019010
2016.04.11 08:53:25.669 5: IrgendeinName: HM485_QueueCommand5201A010
2016.04.11 08:53:25.669 5: IrgendeinName: HM485_QueueCommand5201B010
2016.04.11 08:53:25.669 5: IrgendeinName: HM485_QueueCommand5201C010
2016.04.11 08:53:25.669 5: IrgendeinName: HM485_QueueCommand5201D010
2016.04.11 08:53:25.669 5: IrgendeinName: HM485_QueueCommand5201E010
2016.04.11 08:53:25.670 5: IrgendeinName: HM485_QueueCommand5201F010
2016.04.11 08:53:25.670 5: IrgendeinName: HM485_QueueCommand52020010
2016.04.11 08:53:25.670 5: IrgendeinName: HM485_QueueCommand52021010
2016.04.11 08:53:25.670 5: IrgendeinName: HM485_QueueCommand52022010
2016.04.11 08:53:25.671 5: IrgendeinName: HM485_QueueCommand52023010
2016.04.11 08:53:25.671 5: IrgendeinName: HM485_QueueCommand52024010
2016.04.11 08:53:25.672 5: IrgendeinName: HM485_QueueCommand52025010
2016.04.11 08:53:25.672 5: IrgendeinName: HM485_QueueCommand52026010
2016.04.11 08:53:25.672 5: IrgendeinName: HM485_QueueCommand52027010
2016.04.11 08:53:25.673 5: IrgendeinName: HM485_QueueCommand52028010
2016.04.11 08:53:25.673 5: IrgendeinName: HM485_QueueCommand52029010
2016.04.11 08:53:25.673 5: IrgendeinName: HM485_QueueCommand5202A010
2016.04.11 08:53:25.673 5: IrgendeinName: HM485_QueueCommand5202B010
2016.04.11 08:53:25.674 5: IrgendeinName: HM485_QueueCommand5202C010
2016.04.11 08:53:25.674 5: IrgendeinName: HM485_QueueCommand5202D010
2016.04.11 08:53:25.674 5: IrgendeinName: HM485_QueueCommand5202E010
2016.04.11 08:53:25.674 5: IrgendeinName: HM485_QueueCommand5202F010
2016.04.11 08:53:25.675 5: IrgendeinName: HM485_QueueCommand52030010
2016.04.11 08:53:25.675 5: IrgendeinName: HM485_QueueCommand52031010
2016.04.11 08:53:25.675 5: IrgendeinName: HM485_QueueCommand52032010
2016.04.11 08:53:25.675 5: IrgendeinName: HM485_QueueCommand52033010
2016.04.11 08:53:25.676 5: IrgendeinName: HM485_QueueCommand52034010
2016.04.11 08:53:25.676 5: IrgendeinName: HM485_QueueCommand52035010
2016.04.11 08:53:25.676 5: IrgendeinName: HM485_QueueCommand52036010
2016.04.11 08:53:25.676 5: IrgendeinName: HM485_QueueCommand52037010
2016.04.11 08:53:25.677 5: IrgendeinName: HM485_QueueCommand52038010
2016.04.11 08:53:25.677 5: IrgendeinName: HM485_QueueCommand52039010
2016.04.11 08:53:25.677 5: IrgendeinName: HM485_QueueCommand5203A010
2016.04.11 08:53:25.677 5: IrgendeinName: HM485_QueueCommand5203B010
2016.04.11 08:53:25.678 5: IrgendeinName: HM485_QueueCommand5203C010
2016.04.11 08:53:25.678 5: IrgendeinName: HM485_QueueCommand5203D010
2016.04.11 08:53:25.678 5: IrgendeinName: HM485_QueueCommand5203E010
2016.04.11 08:53:25.678 5: IrgendeinName: HM485_QueueCommand5203F010
2016.04.11 08:53:25.679 5: IrgendeinName: HM485_QueueStart: Num: 4
Allerdings tauchen keine Aus-, oder Eingänge in der Weboberfläche auf.. Was mache ich falsch?



btw: beim speichern der fhem.cfg in der WEB-Oberfläche bekomme ich oft die Fehlermeldung
Usage: setstate where is a single device name, a list separated by komma (,) or a regexp. See the devspec section in the commandref.html for details.Ich vermute, dass das mit dem stoppen und starten des HM485d zusammenhängt (passt zeitlich etwa mit dem LOG)

2016.04.11 08:36:43.213 0: HM485d: Server stopped ...
2016.04.11 08:36:43.215 3: MyHMLAN: HM485d with PID  3263 was terminated.
2016.04.11 08:36:43.243 1: Including fhem.cfg
2016.04.11 08:36:43.261 3: telnetPort: port 7072 opened
2016.04.11 08:36:43.267 3: WEB: port 8083 opened
2016.04.11 08:36:43.275 1: Including ./log/fhem.save
2016.04.11 08:36:43.312 3: MyHMLAN: Warte auf Initialisierung Gateway
2016.04.11 08:36:43.381 3: MyHMLAN: Start HM485d with command line: ./FHEM/lib/HM485/HM485d/HM485d.pl --hmwId 00000001 --serialNumber SGW0123456 --device /dev/ttyUSB0 --localPort 2000
2016.04.11 08:36:43.443 3: MyHMLAN: HM485d was started with PID:  3273
2016.04.11 08:36:43.444 3: MyHMLAN: Connect to HM485d delayed for 5 seconds

Wird der Server bei jedem speichern der fhem.cfg gestoppt und gestartet?
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 11 April 2016, 12:08:25
Daraufhin hatte ich bei einem ersten Test zwar recht schnelle Antworten, aber leider fehlt eine ( siehe Nummerierung der Events).
Ja, da ist tatsächlich ein Tastendruck verloren gegangen. Ich weiß auch nicht, warum. Auffällig ist nur, dass das Device HMW02.I07 da anscheinend dazwischen gefunkt hat. Möglicherweise halten sich da nicht alle HMW-Geräte hundertprozentig an's Protokoll. Es kann auch sein, dass der HMW03 nur ein Broadcast schickt und nicht gemerkt hat, dass es nicht durch ging. Bei Broadcasts gibt es kein ACK.
Wenn Du das einigermaßen sicher nachvollziehen kannst, dann wäre dafür ein eigener Thread im Homematic-Bereich interessant.


Habe laut Wiki den MyHMLAN angelegt, /dev/ttyUSB0, bind 1 und die HM485d.plk ausführbar gemacht.
Du meinst wahrscheinlich HM485d.pl, oder?

Zitat
HMLAN startet problemlos, allerdings scheint auch hier das discovery nicht zu funktionieren.
Nicht einmal auf Tastendruck wird das device automatisch angelegt:
Es sieht so aus, als ob das Device Daten sendet und FHEM die Daten auch im Prinzip empfängt. Allerdings scheint das Device keine Daten zu empfangen. ...oder der Kram geht nicht richtig aus FHEM raus. Da ist natürlich eine Ferndiagnose sehr schwierig.
So ein ähnliches Problem habe ich schon einmal gesehen, ich weiß aber nicht mehr, was damals die Lösung war.

okay, das ergibt nach mehreren versuchen lediglich die Fehlermeldung
Das ist auch sehr seltsam. Eigentlich geht das auch alles automatisch. Die einzige Fehlermeldung ist das mit dem ModelName, aber wie gesagt, auch das sollte automatisch kommen.

Zitat
Ein Tastendruck erzeugt im Event-Monitor ein ACK, aber leider kein press-Event:
Woher da ein ACK kommen soll finde ich sehr seltsam. Das Device sendet ja was, was genau wird dann "acknowledged"? Da läuft möglicherweise noch irgendwas parallel.

Zitat
dann habe ich mal versucht, "model" selbst zu setzen, auf
model HMW_IO_12_Sw7_DR
Das ist nie eine gute Idee. Wenn es nicht automatisch gesetzt wird, dann hilft das auch nichts.

Zitat
wenn ich jetzt ein get config all mache, kommt im Log:
2016.04.11 08:53:25.661 3: IrgendeinName: Lese Eeprom 0000D125
2016.04.11 08:53:25.662 5: MyHMLAN: HM485_GetNewMsgQueue
2016.04.11 08:53:25.662 5: IrgendeinName: HM485_QueueCommand52000010
2016.04.11 08:53:25.662 5: IrgendeinName: HM485_QueueCommand52001010
Allerdings tauchen keine Aus-, oder Eingänge in der Weboberfläche auf.. Was mache ich falsch?
Hast Du eine Weile gewartet, bis configStatus auf OK steht? ...und dann ein Refresh im Browser gemacht?
Ich vermute mal, dass das Device gar nichts von FHEM empfängt. Daher weiß FHEM auch nicht wirklich, was das eigentlich für ein Device ist. Hat das Device vorher schonmal irgendwo funktioniert?
Ach und nochwas: Hast Du in der neuen Installation einen Abschlusswiderstand drin?

Zitat
btw: beim speichern der fhem.cfg in der WEB-Oberfläche bekomme ich oft die Fehlermeldung
Usage: setstate where is a single device name, a list separated by komma (,) or a regexp. See the devspec section in the commandref.html for details.
Da gehe ich eher mal davon aus, dass in der fhem.cfg irgendwas mit setstate drinsteht oder dass was mit der fhem.save nicht stimmt.

Zitat
Wird der Server bei jedem speichern der fhem.cfg gestoppt und gestartet?
Das ist mir zumindest mal bisher nicht aufgefallen. Ich glaube, dass er das bei mir nicht tut.

Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 11 April 2016, 16:38:36
Hi Thorsten,
ja, ich habe den Abschlusswiderstand bekommen (HMW-Sys-OP-DR) und in alle meine Testumgebungen eingebaut.
Zitat
Woher da ein ACK kommen soll finde ich sehr seltsam. Das Device sendet ja was, was genau wird dann "acknowledged"? Da läuft möglicherweise noch irgendwas parallel.

ich habe den raspi komplett neu aufgesetzt, fhem installiert, upgedated und gestartet. Keine Ahnung, was da parallel laufen könnte.

Zitat
Hast Du eine Weile gewartet, bis configStatus auf OK steht? ...und dann ein Refresh im Browser gemacht?
Wie lange ist "eine Weile"? nach ca 10 Minuten stand es immer noch auf READING...

Zitat
Da gehe ich eher mal davon aus, dass in der fhem.cfg irgendwas mit setstate drinsteht oder dass was mit der fhem.save nicht stimmt.
Das dachte ich auch, deswegen habe ich die fhem.save gelöscht und die fhem.cfg.demo kopiert und alles rausgelöscht, was fhem nicht zwingend braucht. es war 100% kein Klartext-"setstate" drin. Ausser, irgendeine Funktion ruft was auf ... das weiss ich natürlich nicht.
War vorhin reproduzierbar, kann ich gerne nochmal versuchen, wenn du noch was wissen willst.

Kurz bevor ich los musste auf Arbeit habe ich noch das neue 12/7 am LAN-Gateway getestet und keine Probleme gefunden. ( also: LAN-GW, ÜS/Busabschluss und 12/7). Tastendrücke kamen alle im Event-Log an, Long-Press alle 300ms. notify schaltet Ausgang fast in Echtzeit. Funktioniert wie am ersten Tag :P

Werde später mal eines der Problem-Geräte dazuklemmen und sehen, ob sich was verändert. Hatte gehofft, ich käme noch drumrum, pro Gerät über 20 Verbindungen zu lösen.. hmpf

Melde mich dann mit weiteren Erkenntnissen:-)
Danke
Stephan

PS: wegen dem verlorenen Tastendruck: kann ich herausfinden, obs Broadcasts sind? Werden Tastendrücke als Broadcast gesendet?
Vielleicht ist es ja ein Problem mit dem Frequency_Input.. hast du selbst sowas in benutzung?
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 12 April 2016, 11:49:17
Hi Thorsten, habe mich zu früh gefreut.

Zum Thema "wie am ersten Tag":
Nachdem ich gestern nacht noch das funktionierende Gerät in den Aufbau aufgenommen habe, geht wieder nichts mehr.
ich habe Zur Zeit folgenden Zustand:

LAN-Gateway - 12/7 - Busabschluss. Das LAN-Gateway, mein PC und fhem sind direkt an die Fritzbox angeschlossen. Andere Geräte befinden sich nicht in meinem Netzwerk.

Der Bus hat keine Verdrahtungsfehler, A ist überall A, B ist überall B.
Ich habe einen Raspberry Pi 2 genommen und das Betriebssystem raspbian frisch installiert.
Auf Raspbian habe ich fhem installiert und upgedated.
Dann habe ich fhem gestartet und das LAN-Gateway definiert:

define HM485_LAN HM485_LAN 192.168.0.5:1000
attr HM485_LAN hmwId 00000001

dann habe ich einen taster gedrückt und gewartet, bis fhem den Baustein (12/7) erkannt hat

2016-04-12 09:41:15.168 Global global UNDEFINED HMW_IO_12_Sw7_DR_MEQ0278716 HM485 00012B7C HM485_LAN
2016-04-12 09:41:15.168 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716
2016-04-12 09:41:15.168 Global global SAVE
2016-04-12 09:41:15.173 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 configStatus: PENDING
2016-04-12 09:41:15.181 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 configStatus: READING
2016-04-12 09:41:15.262 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716 serialNr MEQ0278716
2016-04-12 09:41:15.266 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.307 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:15.311 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.356 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716 serialNr MEQ0278716
2016-04-12 09:41:15.360 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.400 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716 firmwareVersion 3.06
2016-04-12 09:41:15.435 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.437 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 configStatus: READING
2016-04-12 09:41:15.490 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.547 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.603 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.659 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.715 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.770 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.826 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.887 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.939 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:15.995 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.051 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.107 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.163 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.219 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.274 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.331 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.387 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.443 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.498 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.555 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.611 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.667 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.723 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.778 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.835 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.891 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:16.946 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.003 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.058 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.115 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.170 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.227 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.283 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.339 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.395 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.451 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.507 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.562 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.618 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.675 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.731 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.787 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.843 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.899 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:17.954 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.011 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.067 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.123 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.179 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.234 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.291 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.347 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.402 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.460 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.515 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.571 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.627 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.683 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.739 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.795 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.850 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.907 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:18.963 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.024 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_01
2016-04-12 09:41:19.026 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_01 subType key
2016-04-12 09:41:19.028 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_01 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.030 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_01 room HM485
2016-04-12 09:41:19.032 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_02
2016-04-12 09:41:19.034 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_02 subType key
2016-04-12 09:41:19.036 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_02 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.037 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_02 room HM485
2016-04-12 09:41:19.039 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_03
2016-04-12 09:41:19.041 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_03 subType key
2016-04-12 09:41:19.043 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_03 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.045 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_03 room HM485
2016-04-12 09:41:19.047 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_04
2016-04-12 09:41:19.048 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_04 subType key
2016-04-12 09:41:19.050 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_04 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.052 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_04 room HM485
2016-04-12 09:41:19.054 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_05
2016-04-12 09:41:19.057 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_05 subType key
2016-04-12 09:41:19.059 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_05 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.060 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_05 room HM485
2016-04-12 09:41:19.063 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_06
2016-04-12 09:41:19.064 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_06 subType key
2016-04-12 09:41:19.066 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_06 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.068 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_06 room HM485
2016-04-12 09:41:19.070 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_07
2016-04-12 09:41:19.072 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_07 subType key
2016-04-12 09:41:19.074 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_07 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.075 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_07 room HM485
2016-04-12 09:41:19.077 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_08
2016-04-12 09:41:19.079 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_08 subType key
2016-04-12 09:41:19.081 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_08 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.083 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_08 room HM485
2016-04-12 09:41:19.085 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_09
2016-04-12 09:41:19.086 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_09 subType key
2016-04-12 09:41:19.088 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_09 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.090 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_09 room HM485
2016-04-12 09:41:19.092 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_10
2016-04-12 09:41:19.094 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_10 subType key
2016-04-12 09:41:19.096 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_10 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.097 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_10 room HM485
2016-04-12 09:41:19.099 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_11
2016-04-12 09:41:19.101 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_11 subType key
2016-04-12 09:41:19.103 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_11 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.105 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_11 room HM485
2016-04-12 09:41:19.107 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_12
2016-04-12 09:41:19.108 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_12 subType key
2016-04-12 09:41:19.110 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_12 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.112 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_12 room HM485
2016-04-12 09:41:19.114 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_13
2016-04-12 09:41:19.116 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_13 subType switch
2016-04-12 09:41:19.118 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_13 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.119 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_13 room HM485
2016-04-12 09:41:19.122 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_14
2016-04-12 09:41:19.123 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_14 subType switch
2016-04-12 09:41:19.125 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_14 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.127 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_14 room HM485
2016-04-12 09:41:19.129 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_15
2016-04-12 09:41:19.131 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_15 subType switch
2016-04-12 09:41:19.132 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_15 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.134 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_15 room HM485
2016-04-12 09:41:19.136 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_16
2016-04-12 09:41:19.138 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_16 subType switch
2016-04-12 09:41:19.140 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_16 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.142 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_16 room HM485
2016-04-12 09:41:19.144 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_17
2016-04-12 09:41:19.146 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_17 subType switch
2016-04-12 09:41:19.147 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_17 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.149 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_17 room HM485
2016-04-12 09:41:19.151 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_18
2016-04-12 09:41:19.153 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_18 subType switch
2016-04-12 09:41:19.155 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_18 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.156 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_18 room HM485
2016-04-12 09:41:19.159 Global global DEFINED HMW_IO_12_Sw7_DR_MEQ0278716_19
2016-04-12 09:41:19.160 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_19 subType switch
2016-04-12 09:41:19.162 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_19 model HMW_IO_12_Sw7_DR
2016-04-12 09:41:19.164 Global global ATTR HMW_IO_12_Sw7_DR_MEQ0278716_19 room HM485
2016-04-12 09:41:19.265 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.267 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 R-central_address: 00000001
2016-04-12 09:41:19.268 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 R-logging_time: 2.00
2016-04-12 09:41:19.269 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_13 R-logging: on
2016-04-12 09:41:19.271 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_17 R-logging: on
2016-04-12 09:41:19.272 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_16 R-logging: on
2016-04-12 09:41:19.274 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_11 R-long_press_time: 1.00
2016-04-12 09:41:19.275 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_11 R-input_type: pushbutton
2016-04-12 09:41:19.276 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_11 R-input_locked: no
2016-04-12 09:41:19.278 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_14 R-logging: on
2016-04-12 09:41:19.279 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_18 R-logging: on
2016-04-12 09:41:19.280 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_19 R-logging: on
2016-04-12 09:41:19.282 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_09 R-input_type: pushbutton
2016-04-12 09:41:19.283 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_09 R-input_locked: no
2016-04-12 09:41:19.284 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_09 R-long_press_time: 1.00
2016-04-12 09:41:19.286 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_15 R-logging: on
2016-04-12 09:41:19.287 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_12 R-long_press_time: 1.00
2016-04-12 09:41:19.289 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_12 R-input_locked: no
2016-04-12 09:41:19.290 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_12 R-input_type: pushbutton
2016-04-12 09:41:19.291 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_06 R-input_locked: no
2016-04-12 09:41:19.293 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_06 R-input_type: pushbutton
2016-04-12 09:41:19.294 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_06 R-long_press_time: 1.00
2016-04-12 09:41:19.295 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_02 R-input_type: pushbutton
2016-04-12 09:41:19.297 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_02 R-input_locked: no
2016-04-12 09:41:19.298 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_02 R-long_press_time: 1.00
2016-04-12 09:41:19.299 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_01 R-long_press_time: 1.00
2016-04-12 09:41:19.301 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_01 R-input_locked: no
2016-04-12 09:41:19.302 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_01 R-input_type: pushbutton
2016-04-12 09:41:19.303 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_08 R-long_press_time: 1.00
2016-04-12 09:41:19.305 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_08 R-input_type: pushbutton
2016-04-12 09:41:19.306 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_08 R-input_locked: no
2016-04-12 09:41:19.307 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_05 R-input_type: pushbutton
2016-04-12 09:41:19.309 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_05 R-input_locked: no
2016-04-12 09:41:19.310 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_05 R-long_press_time: 1.00
2016-04-12 09:41:19.311 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_10 R-input_locked: no
2016-04-12 09:41:19.313 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_10 R-input_type: pushbutton
2016-04-12 09:41:19.314 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_10 R-long_press_time: 1.00
2016-04-12 09:41:19.315 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_07 R-long_press_time: 1.00
2016-04-12 09:41:19.317 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_07 R-input_locked: no
2016-04-12 09:41:19.318 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_07 R-input_type: pushbutton
2016-04-12 09:41:19.320 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_03 R-input_type: pushbutton
2016-04-12 09:41:19.321 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_03 R-input_locked: no
2016-04-12 09:41:19.322 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_03 R-long_press_time: 1.00
2016-04-12 09:41:19.324 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_04 R-input_type: pushbutton
2016-04-12 09:41:19.325 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_04 R-input_locked: no
2016-04-12 09:41:19.326 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_04 R-long_press_time: 1.00
2016-04-12 09:41:19.378 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.380 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_13 working: off
2016-04-12 09:41:19.380 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_13 off
2016-04-12 09:41:19.431 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.434 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_14 off
2016-04-12 09:41:19.434 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_14 working: off
2016-04-12 09:41:19.483 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.486 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_15 working: off
2016-04-12 09:41:19.486 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_15 off
2016-04-12 09:41:19.537 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.540 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_16 off
2016-04-12 09:41:19.540 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_16 working: off
2016-04-12 09:41:19.588 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.591 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_17 off
2016-04-12 09:41:19.591 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_17 working: off
2016-04-12 09:41:19.642 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.645 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_18 off
2016-04-12 09:41:19.645 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_18 working: off
2016-04-12 09:41:19.697 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:41:19.700 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_19 working: off
2016-04-12 09:41:19.700 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_19 off
2016-04-12 09:41:19.702 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 configStatus: OK


jetzt habe ich 12 Input- und 7 Outputdevices in meinem FHEM.
Wenn ich bei einem der Output-Devices auf "on" klicke, schaltet das Relais sofort. Mit sofort meine ich wirklich, dass das klicken im gleichen moment kommt wie das klicken von meiner Maus.

Wenn ich jetzt mit einem neuen Taster (keine Kosten und Mühen gescheut) an einem Eingang des Gerätes eine Verbindung zwischen dem Eingang und Masse herstelle ( das ist doch korrekt, oder? ) passiert - nichts.
Und das geschlagene 8 Sekunden lang ( ich habe gerade auf die Uhr geschaut.

Danach kommt im Event-Monitor:
2016-04-12 09:45:45.136 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK
2016-04-12 09:45:45.144 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_01 press_short: 13
2016-04-12 09:45:45.144 HM485 HMW_IO_12_Sw7_DR_MEQ0278716_01 press_short_13
2016-04-12 09:45:45.159 HM485 HMW_IO_12_Sw7_DR_MEQ0278716 ACK

und im Log (verbose 5)
2016.04.12 09:45:39.995 5: HM485_LAN: HM485_LAN_Write TX: 107
2016.04.12 09:45:39.996 5: SW: fd026b4b
2016.04.12 09:45:40.000 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 107 Cmd: 97
2016.04.12 09:45:40.001 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (107) 00 AliveStatus: 00
2016.04.12 09:45:45.110 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 4 Cmd: 101
2016.04.12 09:45:45.113 4: HM485_LAN: Event:HASH(0x1f73438)
2016.04.12 09:45:45.115 5: HM485_LAN dispatch �e�����+|K6
2016.04.12 09:45:45.116 5: HM485_LAN: HM485_Parse: MsgId: 4
2016.04.12 09:45:45.117 5: HM485_LAN: HM485_Parse: ProcessEvent
2016.04.12 09:45:45.118 5: HM485_LAN: HM485_ProcessEvent: hmwId = 00012B7C msgData = 4B000036
2016.04.12 09:45:45.126 5: HMW_IO_12_Sw7_DR_MEQ0278716: Device:convertFrameDataToValue: deviceKey = HMW_IO12_SW7_DR_V3_02 valId = counter value1 = 13
2016.04.12 09:45:45.127 5: HM485: HM485:Device:dataConversion: retVal = 13
2016.04.12 09:45:45.128 5: HMW_IO_12_Sw7_DR_MEQ0278716: Device:convertFrameDataToValue: value2 = 13
2016.04.12 09:45:45.129 5: HM485: Device:valueToControl: valName = press_short = 13
2016.04.12 09:45:45.130 5: HMW_IO_12_Sw7_DR_MEQ0278716: HM485_ProcessChannelState: hmwId = 00012B7C Channel = 01 msgData = 4B000036 actionType = frame
2016.04.12 09:45:45.133 5: Triggering HMW_IO_12_Sw7_DR_MEQ0278716 (1 changes)
2016.04.12 09:45:45.134 5: Starting notify loop for HMW_IO_12_Sw7_DR_MEQ0278716, first event ACK
2016.04.12 09:45:45.137 4: HMW_IO_12_Sw7_DR_MEQ0278716_01: HM485_ChannelDoUpdate
2016.04.12 09:45:45.138 5: HMW_IO_12_Sw7_DR_MEQ0278716_01: HM485_ChannelDoUpdate: valueKey = press_short value = 13 Alter Wert = empty
2016.04.12 09:45:45.139 4: HMW_IO_12_Sw7_DR_MEQ0278716_01: press_short -> 13
2016.04.12 09:45:45.140 5: Triggering HMW_IO_12_Sw7_DR_MEQ0278716_01 (2 changes)
2016.04.12 09:45:45.142 5: Starting notify loop for HMW_IO_12_Sw7_DR_MEQ0278716_01, first event press_short: 13
2016.04.12 09:45:45.145 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 5 Cmd: 101
2016.04.12 09:45:45.146 4: HM485_LAN: Event:HASH(0x208d200)
2016.04.12 09:45:45.147 5: HM485_LAN dispatch �e�����+|AMEQ0278716
2016.04.12 09:45:45.148 5: HM485_LAN: HM485_Parse: MsgId: 5
2016.04.12 09:45:45.150 5: HM485_LAN: HM485_Parse: ProcessEvent
2016.04.12 09:45:45.150 5: HM485_LAN: HM485_ProcessEvent: hmwId = 00012B7C msgData = 4100120003064D455130323738373136
2016.04.12 09:45:45.153 5: HMW_IO_12_Sw7_DR_MEQ0278716: HM485_ProcessChannelState: hmwId = 00012B7C No channel
2016.04.12 09:45:45.156 5: Triggering HMW_IO_12_Sw7_DR_MEQ0278716 (1 changes)
2016.04.12 09:45:45.157 5: Starting notify loop for HMW_IO_12_Sw7_DR_MEQ0278716, first event ACK

Ich weiss nicht mehr weiter...
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 12 April 2016, 12:16:15
Ah ja, und um nicht untätig rumzusitzen, habe ich nochmal (erfolglos) versucht mit meinem RS485-Stick verbindung aufzubauen:
define HM485_LAN HM485_LAN localhost:2000
attr HM485_LAN HM485d_bind 1
attr HM485_LAN HM485d_device /dev/ttyUSB0
attr HM485_LAN hmwId 00000001
define IrgendeinName HM485 00012B7C
attr IrgendeinName IODev HM485_LAN

2016.04.12 10:14:15.240 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:15.240 5: HM485_LAN: HM485_QueueSetRequestId: Id: 24
2016.04.12 10:14:15.847 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 24 Cmd: 97
2016.04.12 10:14:15.848 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (24) 3439 AliveStatus: 34
2016.04.12 10:14:15.849 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:15.850 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:15.851 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:15.853 5: HM485_LAN: HM485_Parse: MsgId: 24
2016.04.12 10:14:15.854 5: HM485_LAN: HM485_QueueStepFailed Request ID: 24
2016.04.12 10:14:15.855 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:15.856 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:15.859 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:15.860 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:15.865 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:15.866 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:16.240 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 00000017
2016.04.12 10:14:16.241 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:16.242 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:20.862 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:20.863 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:20.864 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:20.865 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:20.866 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:20.867 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x22412f8)
2016.04.12 10:14:20.868 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:20.869 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:20.881 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:20.883 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:20.887 5: HM485_LAN: HM485_LAN_Write TX: 25
2016.04.12 10:14:20.889 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 00000018
2016.04.12 10:14:20.890 5: SW: fd0d1953c800012b7c1e0000000168
2016.04.12 10:14:20.896 4: HM485_LAN: HM485_LAN: TX: (25) I[3](0,F,B)(1E) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:20.898 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 25
2016.04.12 10:14:20.899 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:20.900 5: HM485_LAN: HM485_QueueSetRequestId: Id: 25
2016.04.12 10:14:21.505 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 25 Cmd: 97
2016.04.12 10:14:21.506 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (25) 3439 AliveStatus: 34
2016.04.12 10:14:21.508 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:21.509 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:21.510 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:21.511 5: HM485_LAN: HM485_Parse: MsgId: 25
2016.04.12 10:14:21.512 5: HM485_LAN: HM485_QueueStepFailed Request ID: 25
2016.04.12 10:14:21.513 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:21.514 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:21.517 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:21.518 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:21.523 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:21.525 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:21.898 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 00000018
2016.04.12 10:14:21.900 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:21.901 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:26.520 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:26.524 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:26.525 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:26.526 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:26.527 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:26.528 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x22413a0)
2016.04.12 10:14:26.529 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:26.530 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:26.542 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:26.544 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:26.548 5: HM485_LAN: HM485_LAN_Write TX: 26
2016.04.12 10:14:26.550 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 00000019
2016.04.12 10:14:26.552 5: SW: fd0d1a53c800012b7c180000000168
2016.04.12 10:14:26.557 4: HM485_LAN: HM485_LAN: TX: (26) I[0](0,F,B)(18) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:26.559 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 26
2016.04.12 10:14:26.560 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:26.561 5: HM485_LAN: HM485_QueueSetRequestId: Id: 26
2016.04.12 10:14:27.166 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 26 Cmd: 97
2016.04.12 10:14:27.167 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (26) 3439 AliveStatus: 34
2016.04.12 10:14:27.169 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:27.170 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:27.170 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:27.172 5: HM485_LAN: HM485_Parse: MsgId: 26
2016.04.12 10:14:27.173 5: HM485_LAN: HM485_QueueStepFailed Request ID: 26
2016.04.12 10:14:27.174 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:27.175 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:27.178 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:27.179 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:27.184 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:27.185 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:27.559 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 00000019
2016.04.12 10:14:27.560 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:27.561 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:32.181 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:32.182 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:32.183 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:32.184 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:32.185 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:32.186 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x22406b0)
2016.04.12 10:14:32.187 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:32.188 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:32.200 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:32.201 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:32.206 5: HM485_LAN: HM485_LAN_Write TX: 27
2016.04.12 10:14:32.207 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 0000001A
2016.04.12 10:14:32.208 5: SW: fd0d1b53c800012b7c1a0000000168
2016.04.12 10:14:32.213 4: HM485_LAN: HM485_LAN: TX: (27) I[1](0,F,B)(1A) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:32.213 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 27
2016.04.12 10:14:32.214 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:32.214 5: HM485_LAN: HM485_QueueSetRequestId: Id: 27
2016.04.12 10:14:32.821 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 27 Cmd: 97
2016.04.12 10:14:32.822 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (27) 3439 AliveStatus: 34
2016.04.12 10:14:32.823 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:32.823 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:32.824 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:32.824 5: HM485_LAN: HM485_Parse: MsgId: 27
2016.04.12 10:14:32.825 5: HM485_LAN: HM485_QueueStepFailed Request ID: 27
2016.04.12 10:14:32.825 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:32.826 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:32.828 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:32.829 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:32.833 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:32.834 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:33.214 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 0000001A
2016.04.12 10:14:33.216 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:33.217 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:37.831 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:37.835 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:37.836 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:37.837 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:37.838 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:37.839 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x2241208)
2016.04.12 10:14:37.840 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:37.842 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:37.854 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:37.855 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:37.860 5: HM485_LAN: HM485_LAN_Write TX: 28
2016.04.12 10:14:37.862 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 0000001B
2016.04.12 10:14:37.863 5: SW: fd0d1c53c800012b7c1c0000000168
2016.04.12 10:14:37.869 4: HM485_LAN: HM485_LAN: TX: (28) I[2](0,F,B)(1C) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:37.871 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 28
2016.04.12 10:14:37.872 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:37.873 5: HM485_LAN: HM485_QueueSetRequestId: Id: 28
2016.04.12 10:14:38.478 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 28 Cmd: 97
2016.04.12 10:14:38.480 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (28) 3439 AliveStatus: 34
2016.04.12 10:14:38.481 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:38.482 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:38.482 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:38.484 5: HM485_LAN: HM485_Parse: MsgId: 28
2016.04.12 10:14:38.484 5: HM485_LAN: HM485_QueueStepFailed Request ID: 28
2016.04.12 10:14:38.485 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:38.486 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:38.488 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:38.489 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:38.495 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:38.495 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:38.872 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 0000001B
2016.04.12 10:14:38.872 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:38.873 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:43.492 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:43.493 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:43.495 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:43.496 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:43.497 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:43.499 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x22298a0)
2016.04.12 10:14:43.500 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:43.502 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:43.513 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:43.514 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:43.518 5: HM485_LAN: HM485_LAN_Write TX: 29
2016.04.12 10:14:43.519 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 0000001C
2016.04.12 10:14:43.520 5: SW: fd0d1d53c800012b7c1e0000000168
2016.04.12 10:14:43.521 4: HM485_LAN: HM485_LAN: TX: (29) I[3](0,F,B)(1E) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:43.522 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 29
2016.04.12 10:14:43.522 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:43.523 5: HM485_LAN: HM485_QueueSetRequestId: Id: 29
2016.04.12 10:14:44.134 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 29 Cmd: 97
2016.04.12 10:14:44.134 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (29) 3439 AliveStatus: 34
2016.04.12 10:14:44.135 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:44.135 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:44.136 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:44.136 5: HM485_LAN: HM485_Parse: MsgId: 29
2016.04.12 10:14:44.137 5: HM485_LAN: HM485_QueueStepFailed Request ID: 29
2016.04.12 10:14:44.137 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:44.138 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:44.140 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:44.140 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:44.145 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:44.145 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:44.523 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 0000001C
2016.04.12 10:14:44.523 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:44.524 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:49.142 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:49.143 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:49.144 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:49.145 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:49.146 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:49.147 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x2154c78)
2016.04.12 10:14:49.148 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:49.149 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:49.162 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:49.163 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:49.168 5: HM485_LAN: HM485_LAN_Write TX: 30
2016.04.12 10:14:49.170 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 0000001D
2016.04.12 10:14:49.171 5: SW: fd0d1e53c800012b7c180000000168
2016.04.12 10:14:49.175 4: HM485_LAN: HM485_LAN: TX: (30) I[0](0,F,B)(18) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:49.176 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 30
2016.04.12 10:14:49.178 5: HM485_LAN: HM485_QueueSetRequestId start
2016.04.12 10:14:49.179 5: HM485_LAN: HM485_QueueSetRequestId: Id: 30
2016.04.12 10:14:49.790 5: HM485_LAN: HM485_LAN_parseIncommingCommand: MsgId: 30 Cmd: 97
2016.04.12 10:14:49.792 5: HM485_LAN: HM485_LAN_parseIncommingCommand: Alive: (30) 3439 AliveStatus: 34
2016.04.12 10:14:49.793 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:49.794 5: HM485_LAN: HM485_LAN_DispatchNack: Message: �a+|
2016.04.12 10:14:49.795 5: HM485_LAN dispatch �a+|
2016.04.12 10:14:49.797 5: HM485_LAN: HM485_Parse: MsgId: 30
2016.04.12 10:14:49.798 5: HM485_LAN: HM485_QueueStepFailed Request ID: 30
2016.04.12 10:14:49.799 3: HM485_LAN: HM485_QueueStepFailed Call step
2016.04.12 10:14:49.800 3: IrgendeinName: RESPONSE TIMEOUT for 00012B7C
2016.04.12 10:14:49.802 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:49.803 5: Starting notify loop for IrgendeinName, first event configStatus: FAILED
2016.04.12 10:14:49.807 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:49.808 5: Starting notify loop for IrgendeinName, first event RESPONSE TIMEOUT
2016.04.12 10:14:50.177 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: QID: 0000001D
2016.04.12 10:14:50.179 5: HM485_LAN: HM485_LAN_CheckResendQueueItems: DispatchNack
2016.04.12 10:14:50.180 5: HM485_LAN: HM485_LAN_DispatchNack: Start
2016.04.12 10:14:54.802 5: HM485_LAN: HM485_GetNewMsgQueue
2016.04.12 10:14:54.805 5: IrgendeinName: HM485_QueueCommand68
2016.04.12 10:14:54.806 5: IrgendeinName: HM485_QueueCommand6E
2016.04.12 10:14:54.807 5: IrgendeinName: HM485_QueueCommand76
2016.04.12 10:14:54.808 5: IrgendeinName: HM485_QueueStart: Num: 0
2016.04.12 10:14:54.809 5: IrgendeinName: HM485_QueueProcessStep: HASH(0x2240758)
2016.04.12 10:14:54.810 5: IrgendeinName: HM485_SendCommand: 68
2016.04.12 10:14:54.811 3: HM485_LAN: Initialisierung von Modul 00012B7C
2016.04.12 10:14:54.824 5: Triggering IrgendeinName (1 changes)
2016.04.12 10:14:54.825 5: Starting notify loop for IrgendeinName, first event configStatus: READING
2016.04.12 10:14:54.830 5: HM485_LAN: HM485_LAN_Write TX: 31
2016.04.12 10:14:54.832 5: HM485_LAN: HM485_LAN_SendQueueNextItem: QID: 0000001E
2016.04.12 10:14:54.833 5: SW: fd0d1f53c800012b7c1a0000000168
2016.04.12 10:14:54.835 4: HM485_LAN: HM485_LAN: TX: (31) I[1](0,F,B)(1A) 00000001 -> 00012B7C [3] 68(h)
2016.04.12 10:14:54.837 5: IrgendeinName: HM485_DoSendCommand: hmwId = 00012B7C data = 68 requestId = 31
2016.04.12 10:14:54.838 5: HM485_LAN: HM485_QueueSetRequestId start

PS: habe jetzt bei Amazon den empfohlenen Adapter bestellt. Sind zwar auch wieder 15 Euro, aber wenns dann funktioniert..
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 12 April 2016, 12:26:34
Hi,
mal was ganz anderes: Wie ist der ganze Kram elektrisch angeschlossen? Also was für ein 24V-Netzteil, was genau meinst Du, wenn Du "Masse" schreibst etc.?
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 12 April 2016, 14:26:37
Leider hat das Forum beim Absenden meinen Mühsam erstellten Beitrag gelöscht, ich versuche es noch einmal zu rekonstruieren
Also:

Als Netzteil habe ich ein Meanwell DR-24-60. Dieses hat zwei Anschlussklemmen, V+ und V-. Von diesen aus gehe ich mit H07V-K 1,5mm² zu den Geräten. Von Gerät zu Gerät habe ich Doppel-Aderendhülsen verwendet.
Reihenfolge siehst du aus dem Schaltplan, den ich gezeichnet habe: Netzteil - Busabschluss- LGW-12/7-12/14-12/7-Dim1L
V+ ist rot ist +24V und kommt auf die Klemme .4
V- ist schwarz ist GND und kommt auf die Klemme .8

Den Bus habe ich mit J-Y(ST)Y 2x2x0,8 verdrahtet: Von A ( 1.3) zu A(1.3), ggf zu A vom RS485-USB-Wandler. B genauso, von 1.7 zu 1.7.
Auf Bus-Dreher habe ich überprüft, falls ein Dreher drin ist, klappt die Kommunikation überhaupt nicht mehr.

Die Ethernetverbindung wird hergestellt über eine Fritzbox 7390, ggf zu einem Raspi 2B oder einem Intel Atom.

Masse ist für mich GND vom Netzteil. Die Eingänge des 12/7 habe ich über einen Taster mit GND(V-, Masse) verbunden.
Den Ausgang ( nur der Vollständigkeit halber) habe ich von der Sicherung mit 230V mit der Klemme L1 verbunden (1.1) und die Ausgänge gehen dann an die Lampen o.ä.
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 12 April 2016, 14:35:45
Schaltplan
die unteren drei Geräte sind zur Zeit nicht in den Bus eingebunden.

 Ein Foto hätte ich dir auch gerne noch eingebunden, leider gibt das Forum immer einen Fehler zurück ...

https://owncloud.viertelvor.net/index.php/s/TkeDt6nXDQJQzEM
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 12 April 2016, 14:45:33
Hi,
laut dem Datenblatt hier: http://www.eq-3.de/Downloads/eq3/downloads_produktkatalog/homematic/pdb/Wired-RS485-Ueberspannungsschutz-Hutschienenmontage_85978_Produktdatenblatt_V3.2.pdf
...ist GND beim SYS-OP-DR der Anschluss 2.4 und nicht 2.8. Allerdings dürfte gar nichts funktionieren, wenn Du das tatsächlich vertauscht hättest.
Ansonsten fällt mir dazu auch nichts mehr ein. Mal sehen, vielleicht arbeitet mein Unterbewusstsein daran weiter. Ich melde mich nochmal, wenn mir was einfällt.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 12 April 2016, 15:04:49
Ja, du hast recht, ich habe im Plan nen Zahlendreher.. GND ist Anschluss 2.4 ::)
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 17 April 2016, 13:21:16
Hi,

also, ich habs nochmal getestet, dabei ergibt sich nun ein reproduzierbares Fehlerbild:

ich habe in meiner Installation sämtliche Taster mit NYM5x1,5 in meinen Elektroschrank gelegt. Diese sind auf WAGO-Reihenklemmen (2003-7641/42) aufgelegt. Masse/GND/V- habe ich per Steckbrücker auf alle Klemmen und somit die angeschlossenen Taster verteilt. Die Rückader vom Taster klemmt dann jeweils auf einem Eingang des 12/7er. Eingang 12 ist abweichend direkt per Klingelleitung aufgelegt und geht nicht über die Klemmsteine. Der zugehörige Taster befindet sich etwa seit anfang meiner Probleme direkt an meinem PC, zum einfacheren Testen.

Folgendes Vorgehen lässt sich jetzt reproduzieren:

Ich klemme Eingang 1 ab und drücke Taster 12 etwa 10 mal im Abstand von 1 Sekunde. Hierbei ist beispielsweise zu beobachten, dass die Tastendrücke bis zu 10 Sekunden delay aufweisen.
Nun klemme ich Eingang 1 wieder an und entferne Eingang zwei. Wieder drücke ich Taster 12 usw.
Bei irgendeinem Eingang (bsp 5) reagiert Fhem/HM dann abweichend sofort, so wie es ja eigentlich sein soll.
Lasse ich diesen Eingang 5 nun ab, funktioniert HM einwandfrei - für ca einen Tag. Dann haben die übrigen Taster wieder das delay, auch ohne den vorher entfernten Eingang 5.
Nun kann ich Eingang 5 wieder anklemmen und führe die Prozedur erneut durch.
Diesesmal ist bsp. Eingang 8 der problematische, klemme ich diesen ab, funktioniert alles einwandfrei ^^ für einen Tag.

Das ist zwar nicht zufriedenstellend, wäre aber als Ansatz zu gebrauchen gewesen, den Fehler weiter einzugrenzen.
Dann kam mit das nächste Problem dazwischen:

Ich wollte gerne die HMW-Eingänge sortieren - das Attribut room kann ich aber nicht mehr über das WEB-Interface ändern. Während es bei allen! sonstigen Geräten einwandfrei funktioniert (Dropdown "room", ins Textfeld klicken, aus der Liste einen zusätzlichen Raum anhaken, OK klicken, dann steht der Raum mit im Textfeld, dann attr klicken und der Raum wird beim attr "room" hinzugefügt und im entsprechenden Raum angezeigt) hapert es ausschließlich bei Homematic Wired an den letzten zwei Schritten: der klick auf attr tut einfach nichts.
Wenn ich im Input-Feld ( ganz oben) eingebe
attr HMW01.O02_Mischer_warm room HM485,Heizung funktioniert es. [/s]
edit: Fehler gefunden: es liegt am perlSyntaxCheck... sobald ich den rausnehme, geht alles wie gewohnt

AAAlso habe ich einen Fehler in meinem Fhem vermutet. Deshalb habe ich mal wieder meinen Raspi zu Hilfe genommen und dort fhem erneut komplett neu installiert (incl. purge und fhem update).

Dort wiederum bekomme ich das 12/14 einwandfrei erkannt ( an dem hängt mein Wärmemengenzähler), die 12/7er liefern den bekannten Fehler

MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreateKannst du mir erklären, welcher Fehler hier genau besteht? Was ist anders als bei dem 12/14er, bei dem das autocreate anscheinend funktioniert?
Damit ich es wenigstens verstehe, vielleicht können wir ja auch noch einen Fehler finden...


Wenn ich dann eingebe
define 1271 HM485 0000D125legt er das device an, lädt sich aber beim config-auslesen zu tode (habs über die ganze Nacht laufen lassen, am nächsten morgen stand immer noch "READING" drin...


Ich hab jetzt mal KNX bestellt, vielleicht bekomme ich damit schonmal etwas zuverlässig zum laufen. Abgesehen davon jedoch ist KNX verdammt teuer, und ich habe auch eigentlich wenig lust, die mittlerweile über 700 Euro, die ich in HMW investiert habe, einfach wegzuwerfen... Wäre von daher trotzdem begeistert, wenn wir nen Fehler finden würden.

Hast du ne Testumgebung? Dann könnte ich dir ggf. mal ein paar der Teile zukommen lassen, wenn du Interesse hast könntest du die dann mal bei dir testen... melde dich per PN, wenn du willst :-)

Danke auf jeden Fall nochmal für den Support bis hierher, ohne dich hätte ich vermutlich schon längst aufgegeben...  ;)

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 17 April 2016, 22:09:10
Ich klemme Eingang 1 ab und drücke Taster 12 etwa 10 mal im Abstand von 1 Sekunde. Hierbei ist beispielsweise zu beobachten, dass die Tastendrücke bis zu 10 Sekunden delay aufweisen.
Das mit den 10 Sekunden Delay kommt mir sehr seltsam vor. Ein HMW-Device probiert es niemals so lange. Entweder sendet es den Tastendruck genau einmal oder maximal dreimal. Letzteres dauert höchstens etwa 600ms. Dann gibt das Device auf. Ich denke also, dass alles, was mehr als eine Sekunde Delay erzeugt nicht wirklich am HM-Wired liegen kann.
Höchstens vielleicht, wenn der Bus die ganze Zeit belegt ist, aber da bin ich mir nicht wirklich sicher.

Zitat
Nun klemme ich Eingang 1 wieder an und entferne Eingang zwei. Wieder drücke ich Taster 12 usw.
Bei irgendeinem Eingang (bsp 5) reagiert Fhem/HM dann abweichend sofort, so wie es ja eigentlich sein soll.
Lasse ich diesen Eingang 5 nun ab, funktioniert HM einwandfrei - für ca einen Tag. Dann haben die übrigen Taster wieder das delay, auch ohne den vorher entfernten Eingang 5.
Nun kann ich Eingang 5 wieder anklemmen und führe die Prozedur erneut durch.
Diesesmal ist bsp. Eingang 8 der problematische, klemme ich diesen ab, funktioniert alles einwandfrei ^^ für einen Tag.
Ich würde mal vermuten, dass es nicht die An/Abklemmerei ist. Lässt Du bei der Aktion alles am Strom oder schaltest Du vorher irgendwas ab?

Früher gab es öfter Probleme mit dem automatischen Lesen der Device-Konfiguration. Kannst Du mal probieren, ob diese Probleme auch noch auftreten, wenn Du das Attribut autoReadConfig für alle HMW-Devices auf "never" stellst? ...oder Du stellst sicher, dass es für keins der Devices gesetzt ist und setzt es im HM485_LAN auf "never". 

Zitat
MyHMLAN: Device 0000D125 not defined yet. We need the type for autocreateKannst du mir erklären, welcher Fehler hier genau besteht?
Das ist kein Fehler. Es bedeutet nur, dass FHEM ein Device mit der Hmwid 0000D125 gefunden hat, aber den Typ nicht kennt. Daraufhin müsste FHEM ein Kommando ans Device schicken, um den Typ zu erfahren. Ich denke, dass es danach schief geht. 

Zitat
Was ist anders als bei dem 12/14er, bei dem das autocreate anscheinend funktioniert?
Tja, wahrscheinlich antwortet der 12/14 er auf die Frage nach dem Gerätetyp und der 0000D125 tut es eben nicht. Möglicherweise ist mit dem 0000D125 etwas faul.

Zitat
Wenn ich dann eingebe
define 1271 HM485 0000D125legt er das device an, lädt sich aber beim config-auslesen zu tode (habs über die ganze Nacht laufen lassen, am nächsten morgen stand immer noch "READING" drin...
Ich vermute mal, dass der 0000D125 auf gar nichts antwortet.

Zitat
Ich hab jetzt mal KNX bestellt, vielleicht bekomme ich damit schonmal etwas zuverlässig zum laufen. Abgesehen davon jedoch ist KNX verdammt teuer, und ich habe auch eigentlich wenig lust, die mittlerweile über 700 Euro, die ich in HMW investiert habe, einfach wegzuwerfen... Wäre von daher trotzdem begeistert, wenn wir nen Fehler finden würden.
Ja, das wäre schade. Bei mir funktioniert mit HMW alles, außer eben dem HMW-LAN-Adapter.

Zitat
Hast du ne Testumgebung? Dann könnte ich dir ggf. mal ein paar der Teile zukommen lassen, wenn du Interesse hast könntest du die dann mal bei dir testen... melde dich per PN, wenn du willst :-)
Ja, eine Testumgebung habe ich. Ich schreib' Dir mal ne PM.
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 18 April 2016, 09:01:43
Lässt Du bei der Aktion alles am Strom oder schaltest Du vorher irgendwas ab?
ich ... hm. glaube dass ich den Strom anlasse. Hab aber schon so viel gemacht, dass es auch gut anders sein kann. Okay, auf noch eine Runde kommts ja jetzt auch nicht mehr an.


Zitat
Früher gab es öfter Probleme mit dem automatischen Lesen der Device-Konfiguration. Kannst Du mal probieren, ob diese Probleme auch noch auftreten, wenn Du das Attribut autoReadConfig für alle HMW-Devices auf "never" stellst? ...oder Du stellst sicher, dass es für keins der Devices gesetzt ist und setzt es im HM485_LAN auf "never". 
Okay, war nicht gesetzt hab ich gemacht. Schauen wir mal, obs nach meiner "Runde" jetzt stabiler bleibt.

Zitat
Das ist kein Fehler. Es bedeutet nur, dass FHEM ein Device mit der Hmwid 0000D125 gefunden hat, aber den Typ nicht kennt. Daraufhin müsste FHEM ein Kommando ans Device schicken, um den Typ zu erfahren. Ich denke, dass es danach schief geht.
Du hattest in einem anderen Thread geschrieben, dass es "kein Problem" oder "ziemlich leicht" wäre, den Bus mitzusniffen?
Dann könnte ich schonmal herausfinden, ob und was er antwortet.


Zitat
Tja, wahrscheinlich antwortet der 12/14 er auf die Frage nach dem Gerätetyp und der 0000D125 tut es eben nicht. Möglicherweise ist mit dem 0000D125 etwas faul.
Ich vermute mal, dass der 0000D125 auf gar nichts antwortet.
Ich glaube, dass er zb auf Schaltbefehle (die er noch relativ zuverlässig ausführt, wenn sie vom FHEMWEB kommen), ein ACK o.ä. sendet, da die Glühbirne zuerst ein Ausrufezeichen bekommt und nach einer halben bis ganzen Sekunde das Ausrufezeichen wieder verschwindet. Auch dafür wärs ja cool, den Bus mal mitzuschneiden..

Danke

Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 18 April 2016, 15:18:03
Okay, war nicht gesetzt hab ich gemacht. Schauen wir mal, obs nach meiner "Runde" jetzt stabiler bleibt.
Du hattest in einem anderen Thread geschrieben, dass es "kein Problem" oder "ziemlich leicht" wäre, den Bus mitzusniffen?
Dann könnte ich schonmal herausfinden, ob und was er antwortet.
Dazu braucht man einen Arduino und einen RS485-Transceiver. Dann noch einen kleinen Sketch, der alles anzeigt, was über den Transceiver kommt.

Zitat
Ich glaube, dass er zb auf Schaltbefehle (die er noch relativ zuverlässig ausführt, wenn sie vom FHEMWEB kommen), ein ACK o.ä. sendet, da die Glühbirne zuerst ein Ausrufezeichen bekommt und nach einer halben bis ganzen Sekunde das Ausrufezeichen wieder verschwindet.
Das ist jetzt etwas seltsam. Wie sendest Du denn von FHEM aus ein Kommando, wenn FHEM das Device gar nicht erkennt?
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 18 April 2016, 15:36:47
Dazu braucht man einen Arduino und einen RS485-Transceiver. Dann noch einen kleinen Sketch, der alles anzeigt, was über den Transceiver kommt.
Das hätte ich soweit vorrätig. Ich schau mal, ob ich so nen sketch finde. In welchem Format kannst du was mit den Infos anfangen?

Zitat
Das ist jetzt etwas seltsam. Wie sendest Du denn von FHEM aus ein Kommando, wenn FHEM das Device gar nicht erkennt?
Wenn ich eine neue Config anlege, erkennt er die Geräte nicht. Ich habe aber noch meine alte config, in der die Geräte bereits definiert sind. Und die funktioniert dann (mit den bekannten Einschränkungen) ... Und in dieser alten Config habe ich jetzt das autoReadConfig auf never gestellt. Bisher läufts ... :-)
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 18 April 2016, 20:38:53
Das hätte ich soweit vorrätig. Ich schau mal, ob ich so nen sketch finde. In welchem Format kannst du was mit den Infos anfangen?
Wenn man den Busverkehr im Hex-Format hat, dann kann man damit schon was anfangen.

Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 23 April 2016, 09:52:13
Bis heute läuft alles einwandfrei.  ::)
Ich habe lediglich zwei Schalter aus dem Schlafzimmer abgeklemmt.
Sobald ich diese wieder verbinde, habe ich wieder Probleme.
Wenn ich dazu komme, werde ich noch einmal probieren, ob jetzt auch das erkennen der Geräte besser klappt.

Danke jedenfalls für alle Antworten und Hilfen!

Viele Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 30 April 2016, 08:11:41
so, nachdem es die ganze zeit einwandfrei lief, habe ich seit gestern, 20:57 wieder folgende Meldungen im Log.
Gemacht habe ich zu dem Änderungen an diversen notifies, aber nichts, was mit HMW zu tun gehabt hätte.

2016.04.29 20:57:10.971 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.04.29 20:57:11.897 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.04.29 20:57:12.743 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.

Seitdem kann ich z.B. keine Attribute mehr über die WEB-Oberfläche setzen, die Schalter und Taster gehen nicht mehr usw...
Mal schauen, ob ich dieses Wochenende Zeit finde, den Sniffer zu bauen...
Oder hat dieser Fehler ne neue Bedeutung?  Den hatte ich ja schließlich noch nicht  ;D

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 30 April 2016, 11:51:26
Hi,
ich habe mir gerade mal die Sourcen angeschaut. Ich habe im ganzen HM485-Kram keine solche Meldung gefunden. Keine Ahnung, wo die herkommt.
Kann es sein, dass Du direkt in der fhem.cfg rumbastelst und Dir damit manchmal was kaputt haust?
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 30 April 2016, 13:13:14
Kann es sein, dass Du direkt in der fhem.cfg rumbastelst und Dir damit manchmal was kaputt haust?

 ;D Obwohl sich das ja schon sehr vorwurfsvoll anhört  ::) nein, ich habe nicht "rumgebastelt" *fg*

Nein, schon okay, muss man ja auch ausschließen ;), aber ich habe vor einigen Monaten einen Hinweis bekommen, dass das editieren der fhem.cfg nicht erwünscht wäre ( ah ja, das war bei der "editConfig 1"-Diskussion) und seitdem nutze ich schön brav ausschließlich!! den DEF-Editor und die Input-Leiste...
Ich häng mal meine fhem.cfg an, genau so, wie sie jetzt gerade ist. Darin sind noch ein paar Reste von OWDevice, die ich mal ausgelagert hatte und dann nach obigem Entschluss wieder zusammengeführt habe, danach kommt dann nur noch fhem-Erzeugnis...


root@mediacenter:/opt/fhem# ll
total 508K
drwxr-xr-x 11 root root    4,0K Apr 26 21:00 ./
drwxr-xr-x  7 root root    4,0K Apr 17 14:49 ../
drwxr-xr-x  2 fhem dialout 4,0K Apr 25 10:17 backup/
-rw-r--r--  1 fhem dialout  45K Apr 17 14:55 backup.fhem.cfg
-rw-r--r--  1 fhem dialout 143K Apr 25 10:18 CHANGED
-rw-r--r--  1 fhem dialout  33K Apr 17 14:23 configDB.pm
drwxr-xr-x 39 fhem dialout 4,0K Apr 29 17:35 contrib/
-rw-r--r--  1 root root     916 Apr 25 10:40 db_fhem.conf
drwxr-xr-x  3 fhem dialout 4,0K Apr 17 14:23 demolog/
drwxr-xr-x  4 fhem dialout 4,0K Apr 17 14:23 docs/
drwxr-xr-x  5 fhem dialout  20K Apr 29 17:49 FHEM/
-rw-r--r--  1 fhem dialout  61K Apr 30 08:06 fhem.cfg
-rw-r--r--  1 fhem dialout  16K Apr 17 14:23 fhem.cfg.demo
-rw-r--r--  1 fhem dialout 9,6K Apr 17 14:23 fhem.cfg.orig
-rwxr-xr-x  1 fhem dialout 120K Apr 25 10:18 fhem.pl*
drwxr-xr-x  2 fhem dialout 4,0K Apr 30 00:00 log/
-rw-r--r--  1 fhem dialout  935 Apr 17 14:23 README_DEMO.txt
drwxr-xr-x  5 fhem dialout 4,0K Apr 25 10:18 restoreDir/
drwxr-xr-x  2 fhem dialout 4,0K Apr 17 14:23 unused/
drwxr-xr-x  8 fhem dialout 4,0K Apr 17 14:23 www/
root@mediacenter:/opt/fhem#

Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 30 April 2016, 14:00:14
Hi,
sorry, hätte nicht so rüberkommen sollen.
Bei Deiner fhem.cfg fällt mir auf, dass Du eine gar nicht so kleine Installation hast. Außerdem ist anscheinend so ziemlich alles über notify und DOIF gesteuert. Möglicherweise kommt das System nicht mehr so ganz mit. ...aber das ist auch nur eine wilde Vermutung.
Jetzt sollte man vielleicht erst einmal herausfinden, wor diese Meldung herkommt. Vom HM485-Modul meiner Meinung nach nicht. Kannst Du mal in Deiner Installation nach "define one to get detailed information" suchen? Dann wüssten wir wenigstens, wie das ausgelöst wird.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 30 April 2016, 18:13:54
Außerdem ist anscheinend so ziemlich alles über notify und DOIF gesteuert.
Frage: wie sonst? Hab ich nen wichtigen Punkt übersehen? Wird das "üblicherweise" anders gemacht?

Zitat
Jetzt sollte man vielleicht erst einmal herausfinden, wor diese Meldung herkommt. Vom HM485-Modul meiner Meinung nach nicht. Kannst Du mal in Deiner Installation nach "define one to get detailed information" suchen? Dann wüssten wir wenigstens, wie das ausgelöst wird.

root@mediacenter:/opt/fhem# grep -r "define one to get detailed information" * -n --exclude-dir=log
fhem.pl:3348:                        "define one to get detailed information.";
restoreDir/2016-04-25/fhem.pl:3348:                        "define one to get detailed information.";
root@mediacenter:/opt/fhem#

fhem.pl, Zeile 3348. im SVN checkout ists fhem.pl Zeile 3349.
Hilft das?

fhem.pl:
  if(!int(@found) || !defined($found[0])) {
3329     my $h = $hash->{MatchList}; $h = $module->{MatchList} if(!$h);
3330     if(defined($h)) {
3331       foreach my $m (sort keys %{$h}) {
3332         if($dmsg =~ m/$h->{$m}/) {
3333           my ($order, $mname) = split(":", $m);
3334
3335           if($attr{global}{autoload_undefined_devices}) {
3336             my $newm = LoadModule($mname);
3337             $mname = $newm if($newm ne "UNDEFINED");
3338             if($modules{$mname} && $modules{$mname}{ParseFn}) {
3339               no strict "refs"; $readingsUpdateDelayTrigger = 1;
3340               @found = &{$modules{$mname}{ParseFn}}($hash,$dmsg);
3341               use strict "refs"; $readingsUpdateDelayTrigger = 0;
3342               last if(defined($found[0]));
3343             } else {
3344               Log 0, "ERROR: Cannot autoload $mname";
3345             }
3346
3347           } else {
3348             Log3 $name, 3, "$name: Unknown $mname device detected, " .
3349                         "define one to get detailed information.";
3350             return undef;
3351
3352           }
3353         }
3354       }
3355     }
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 30 April 2016, 19:35:27
Frage: wie sonst? Hab ich nen wichtigen Punkt übersehen? Wird das "üblicherweise" anders gemacht?
Wenn möglich, dann ist es bei HMW gut, wenn man direkte Peerings verwendet. Das schont die Systemperformance.

Zitat
fhem.pl:
  if(!int(@found) || !defined($found[0])) {
3329     my $h = $hash->{MatchList}; $h = $module->{MatchList} if(!$h);
3330     if(defined($h)) {
3331       foreach my $m (sort keys %{$h}) {
3332         if($dmsg =~ m/$h->{$m}/) {
3333           my ($order, $mname) = split(":", $m);
3334
3335           if($attr{global}{autoload_undefined_devices}) {
3336             my $newm = LoadModule($mname);
3337             $mname = $newm if($newm ne "UNDEFINED");
3338             if($modules{$mname} && $modules{$mname}{ParseFn}) {
3339               no strict "refs"; $readingsUpdateDelayTrigger = 1;
3340               @found = &{$modules{$mname}{ParseFn}}($hash,$dmsg);
3341               use strict "refs"; $readingsUpdateDelayTrigger = 0;
3342               last if(defined($found[0]));
3343             } else {
3344               Log 0, "ERROR: Cannot autoload $mname";
3345             }
3346
3347           } else {
3348             Log3 $name, 3, "$name: Unknown $mname device detected, " .
3349                         "define one to get detailed information.";
3350             return undef;
3351
3352           }
3353         }
3354       }
3355     }
Das sieht so aus, als ob das Attribut autoload_undefined_devices vom Device global nicht gesetzt ist. Mach mal ein "list global" und schau nach autoload_undefined_devices.
Gruß,
  Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 01 Mai 2016, 11:42:55

Zitat
Das sieht so aus, als ob das Attribut autoload_undefined_devices vom Device global nicht gesetzt ist. Mach mal ein "list global" und schau nach autoload_undefined_devices.
stimmt, steht auf 0. Soll ich es auf 1 stellen?

Zitat
autoload_undefined_devices
wenn dieses Attribut gesetzt ist, werden die zu einer neu empfangenen Nachricht zugehörigen Module automatisch geladen.  Dies erfolgt vom autocreate Gerät, um so automatisch ein FHEM-Gerät bei erreichen einer entsprechenden Nachricht zu erstellen.

will ich ja eigentlich auch gar nicht.. Alle meine Geräte sind definiert und funktionieren. Was ist das also für eine Meldung, die von einem definierten Gerät kommt und trotzdem ein autocreate auslöst?
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 01 Mai 2016, 12:51:01
stimmt, steht auf 0. Soll ich es auf 1 stellen?
Na dann müsste zumindest die Meldung verschwinden.

Zitat
will ich ja eigentlich auch gar nicht.. Alle meine Geräte sind definiert und funktionieren. Was ist das also für eine Meldung, die von einem definierten Gerät kommt und trotzdem ein autocreate auslöst?
Ich dachte, dass etwas nicht funktioniert. Du hattest ja geschrieben:
Zitat
Seitdem kann ich z.B. keine Attribute mehr über die WEB-Oberfläche setzen, die Schalter und Taster gehen nicht mehr usw...
Also scheint es doch nicht so Recht zu funktionieren.
Na wie dem auch sei. Wenn Du wissen willst, was da ein autocreate auslöst, dann müsstest Du mal das Loglevel erhöhen und nachschauen, welche HMWID da was will.
Möglicherweise gibt es da auch ein Timing-Problem. Es ist vorstellbar, dass da ein Gerät noch nicht ganz angelegt ist (beim Ausführen der fhem.cfg), aber trotzdem schon irgendwas sendet. ...aber das müsste sich dann eigentlich von selbst reparieren.
Auf jeden Fall glaube ich nicht, dass das autoload_undefined_devices etwas schadet.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 01 Mai 2016, 13:33:40
Wir haben einen reproduzierbaren Zusammenhang gefunden:
a)
autoload_undefined_devices = 1 -> Funktion HMW ist vorhanden. Taster drücken, Licht geht an, attr-Schaltfläche im WEB funktioniert

b)
autoload_undefined_devices = 0 -> Fehlermeldung im Log, Funktion ist nicht mehr vorhanden, attr-Schaltfläche funktioniert nicht mehr.
Habe ich mehrfach getestet, funktioniert immer wieder so.


Womit wir tatsächlich bei der Frage wären:

Zitat
Zitat
Alle meine Geräte sind definiert und funktionieren. Was ist das also für eine Meldung, die von einem definierten Gerät kommt und trotzdem ein autocreate auslöst?
aber das müsste sich dann eigentlich von selbst reparieren.
tut es dann ja anscheinend nicht, wenn der Fehler reproduzierbar ist.

Drehen wir uns (ich mich) im Kreis?

Im Log steht:

2016.05.01 13:07:24.768 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 160 Cmd: 101
2016.05.01 13:07:24.769 4: MyHMLAN: Event:HASH(0x49d3e90)
2016.05.01 13:07:24.769 5: MyHMLAN dispatch <FD>^P<A0>e^@^@^@^A^^@^@<9A>+i^N^@^CESC
2016.05.01 13:07:24.769 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 13:07:25.034 5: OWServer child ID for reading '/28.2B308A060000/temperature10' is 18968
2016.05.01 13:07:25.362 5: OWServer child read /28.2B308A060000/temperature10: 24.25
2016.05.01 13:07:25.836 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 161 Cmd: 101
2016.05.01 13:07:25.836 4: MyHMLAN: Event:HASH(0x456a620)
2016.05.01 13:07:25.836 5: MyHMLAN dispatch <FD>^P<A1>e^@^@^@^AX^@^@<9A>+i^N^@^C0
2016.05.01 13:07:25.836 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 13:07:26.034 5: OWServer child ID for reading '/28.36FE4F070000/temperature10' is 18972
2016.05.01 13:07:26.362 5: OWServer child read /28.36FE4F070000/temperature10: 24.5
2016.05.01 13:07:26.835 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 162 Cmd: 101
2016.05.01 13:07:26.836 4: MyHMLAN: Event:HASH(0x49588c0)
2016.05.01 13:07:26.836 5: MyHMLAN dispatch <FD>^P<A2>e^@^@^@^AZ^@^@<9A>+i^N^@^C

2016.05.01 13:07:26.836 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 13:07:27.031 5: OWServer child ID for reading '/28.22F389060000/temperature10' is 18976
2016.05.01 13:07:27.297 5: OWServer child read /28.22F389060000/temperature10: 40.5
2016.05.01 13:07:27.917 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 163 Cmd: 101
2016.05.01 13:07:27.917 4: MyHMLAN: Event:HASH(0x456a3e0)
2016.05.01 13:07:27.918 5: MyHMLAN dispatch <FD>^P<A3>e^@^@^@^A\^@^@<9A>+i^N^@^C)
2016.05.01 13:07:27.918 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.


Zum Thema "System überlastet".. Ich bekomme verstärkt perfmon-Alarme, wenn ich im WEB-Interface was anklicke. (was üblicherweise nicht gleichzeitig mit einem Tastendruck passiert)
Grund habe ich aber noch nicht gefunden..

Du sagst, direkte peerings entlasten das System. Ist es nicht so, dass die notifies eigentlich nur Speicherplatz belegen(fhem.cfg),und wenn ein Event kommt (Taster gedrückt) schaut FHEM, ob es ein matchendes notify findet und führt es aus (set out_xy on)?
Wäre es dann nicht (fast) irrelevant, wieviele HMW-Module und notifies ich habe? Höhere Belastung des Systemes (und des Busses) würde dann ja nur durch mehrere, parallele Aktionen hervorgerufen...


Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 01 Mai 2016, 14:19:47
Also, nochmal.
Raspi, aktuellstes FHEM. Config verändert (ja, jetzt hab ich von Hand gepfuscht, hängt an).
Sobald ich autoload_undefined_devices auf 0 stelle, kommen die Fehler, wenn ichs auf 1 stelle, ist alles i.o. Ist das ein Fehler oder soll das so sein?

2016.05.01 12:08:21.506 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 24 Cmd: 101
2016.05.01 12:08:21.506 4: MyHMLAN: Event:HASH(0x2d56410)
2016.05.01 12:08:21.507 5: MyHMLAN dispatch <FD>^P^Xe^@^@^@^A\^@^@<9A>+i^N^@^C^\
2016.05.01 12:08:21.507 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 12:08:24.992 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 25 Cmd: 101
2016.05.01 12:08:24.993 4: MyHMLAN: Event:HASH(0x2da7308)
2016.05.01 12:08:24.993 5: MyHMLAN dispatch <FD>^P^Ye^@^@^@^A^^@^@<9A>+i^N^@^C1
2016.05.01 12:08:24.993 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 12:08:29.018 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 26 Cmd: 101
2016.05.01 12:08:29.019 4: MyHMLAN: Event:HASH(0x2d7cb00)
2016.05.01 12:08:29.019 5: MyHMLAN dispatch <FD>^P^Ze^@^@^@^AX^@^@<9A>+i^N^@^C)
2016.05.01 12:08:29.020 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 12:08:30.521 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 27 Cmd: 101
2016.05.01 12:08:30.522 4: MyHMLAN: Event:HASH(0x2da7ee0)
2016.05.01 12:08:30.522 5: MyHMLAN dispatch <FD>^PESCe^@^@^@^AZ^@^@<9A>+i^N^@^CESC
2016.05.01 12:08:30.523 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 12:08:31.544 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 28 Cmd: 101
2016.05.01 12:08:31.545 4: MyHMLAN: Event:HASH(0x2d56410)
2016.05.01 12:08:31.545 5: MyHMLAN dispatch <FD>^P^\e^@^@^@^A\^@^@<9A>+i^N^@^C-
2016.05.01 12:08:31.546 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 12:08:35.513 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 29 Cmd: 101
2016.05.01 12:08:35.514 4: MyHMLAN: Event:HASH(0x2ad3360)
2016.05.01 12:08:35.514 5: MyHMLAN dispatch <FD>^P^]e^@^@^@^A^^@^@<9A>+i^N^@^CESC
2016.05.01 12:08:35.515 3: MyHMLAN: Unknown HM485 device detected, define one to get detailed information.
2016.05.01 12:08:36.713 5: MyHMLAN: HM485_LAN_parseIncommingCommand: MsgId: 30 Cmd: 101

Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 02 Mai 2016, 10:05:13
Hi,
tja, da weiß ich jetzt auch erstmal nicht weiter. Ich würde an Deiner Stelle einfach autoload_undefined_devices auf 1 lassen, es schadet ja nichts, oder?
Ansonsten werde ich mal versuchen, das bei mir nachzuvollziehen. Ich komme aber frühestens morgen Abend dazu.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Ralf9 am 02 Mai 2016, 12:55:59
Also, nochmal.
Raspi, aktuellstes FHEM. Config verändert (ja, jetzt hab ich von Hand gepfuscht, hängt an).
Sobald ich autoload_undefined_devices auf 0 stelle, kommen die Fehler, wenn ichs auf 1 stelle, ist alles i.o. Ist das ein Fehler oder soll das so sein?

2016.05.01 12:08:21.506 4: MyHMLAN: Event:HASH(0x2d56410)

Interessant wäre zu sehen was vom HMW Lan Gateway ankommt.
Durch einen Fehler in der 00_HM485_LAN.pm wird das ankommende event nicht angezeigt (Event:HASH(0x2d56410))
Es müsste ungefähr so aussehen:
2016.04.17 13:54:51 4 : HM485_LAN2: Event: I[1](3,Y,F,B)(FA) 42ABCDEF -> FFFFFFFF [15] 69(i) 08 {0000}siehe hier:
https://forum.fhem.de/index.php/topic,52261.msg440613.html#msg440613 (https://forum.fhem.de/index.php/topic,52261.msg440613.html#msg440613)

Vielleicht kann Thorsten diese Korrektur/Ergänzung übernehmen.

Gruß Ralf
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: tkempken am 13 Juli 2016, 12:54:53
Hallo,

ich habe hin und wieder exakt die gleichen Symptome.
Damals hatte ich das Original EQ3 LAN Gateway und bin umgestiegen auf einen RS485 USB Stick der meine Probleme gelöst hat. Jetzt kommen die Probleme wieder (ohne Softwareupdate).

Gab es hier eine Lösung?

Grüße
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 13 Oktober 2016, 17:21:19
Hi, mittlerweile bin ich fast komplett auf KNX umgestiegen ;-)

Ich habe HM allerdings immer noch im Betrieb (für unwichtige sachen).
Beim Testen habe ich manchmal den Eindruck, dass irgendwelche Induktionsspannungen an den Eingängen diese problematik verursachen. Läuft bei mir so:

- neuer Taster angeschlossen -> Probleme
- neuer Taster wieder abgeklemmt -> keine Probleme
- nächsten Tag Taster wieder genau gleich angeklemmt -> keine Probleme
- zwei Tage später -> Probleme
- Taster wieder abgeklemmt -> immer noch Probleme
- Taster nebendran abgeklemmt -> keine Probleme mehr
- Beide Taster wieder angeklemmt -> keine Probleme

So kanns dann Stunden und Tage laufen, ohne dass was passiert (ausser dem, was passieren soll:-)
und dann irgendwann wieder -> Probleme.

Konnte das (ohne Oszi und größeren Versuchsaufbau) aber leider nicht weiter eingrenzen...

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 13 Oktober 2016, 18:19:30
Hi,
passiert das, wenn die Taster direkt an die Geräte angeschlossen sind, oder liegen da Kabel quer durch die Wände?
...möglicherweise ist da auch einfach nur ein einzelnes Gerät defekt.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: tkempken am 13 Oktober 2016, 19:56:02
Ich kenne das Problem ja leider auch (manchmal).
Seit meinem Post hatte ich es für mehrere Monate gehabt, von heute auf morgen war es plötzlich verschwunden.
Da ich an manchen Stellen die Taster Kabel und Stromführenden Leitungen eng liegen habe, könnte ich die Theorie mit der Induktion gefühlt bestätigen.
Möglicherweise habe ich durch verlegen eines anderen Kabels diese Induktion entfernt und seitdem geht es wieder - wer weiß.
Einen Grund habe ich leider nicht gefunden.
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 13 Oktober 2016, 20:00:29
Hi,
ich glaube, dass bei mir alle Kabel zu den Tastern geschirmt sind. Ansonsten liegen sie auch recht dicht bei allem anderen...
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 14 Oktober 2016, 07:29:06
...möglicherweise ist da auch einfach nur ein einzelnes Gerät defekt.

Es passiert an allen meinen HMW-Geräten ...
Die Kabel liegen durch alle Wände, mit direkt angeschlossenen Tastern ist mir das Problem nicht aufgefallen, hatte ich aber auch nicht sooo oft.

Zitat
ich glaube, dass bei mir alle Kabel zu den Tastern geschirmt sind. Ansonsten liegen sie auch recht dicht bei allem anderen...

hatte das Problem nachweislich bei einem J-Y(ST)Y, welches nicht mal so richtig nah an anderen Kabeln lag( ca.. 4 meter parallel mit ca 50 cm Abstand, Rest der Strecke Freiflug, provisorisch halt.  Das ist auch geschirmt. Hatte aber den Schirm auch nicht wirklich an Erde angeschlossen...

Die anderen Leitungen sind NYM 5x1,5, da kann ich mir das schon eher vorstellen.

Die KNX-Komponenten schalten laut Datenblatt ab 2V Spannung, AC oder DC. Bei denen habe ich nicht eine Störung festgestellt (wie ich sie mir durch Induktion vorstellen könnte). Naja, ich weiss ja auch nicht, wieviel Strom da fließen muss.

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 14 Oktober 2016, 21:35:05
Hatte aber den Schirm auch nicht wirklich an Erde angeschlossen...
...das habe ich auch nicht.

Zitat
Die KNX-Komponenten schalten laut Datenblatt ab 2V Spannung, AC oder DC.
Schalten die High-Aktiv? Soweit ich weiß (und ich das auch bei mir angeschlossen habe), schalten die HMW-Sachen Low-Aktiv. D.h. die Taster verbinden GND mit dem Eingang. Ist das bei Dir vielleicht anders?
Mit AC kann HMW gar nichts anfangen, denke ich.
Gruß,
   Thorsten
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: abc2006 am 14 Oktober 2016, 23:45:31
KNX schaltet High-Active, korrekt. L1 wird über den Taster an den Eingang gelegt.

HMW schaltet Low-Active, ebenfalls korrekt. GND bzw 0V bzw Masse wird über den Taster an den Eingang gelegt.

HMW ist ja auch nur für 24VDC ausgelegt... soweit sind wir uns glücklicherweise einig ;-)

Grüße
Stephan
Titel: Antw:ungewünschtes Delay bei Ausführung von Schaltbefehlen
Beitrag von: Thorsten Pferdekaemper am 15 Oktober 2016, 13:24:34
soweit sind wir uns glücklicherweise einig ;-)
Das ist ja schön, aber es hilft uns gerade nicht wirklich weiter.
Ich weiß jetzt auch nicht mehr, was da sein könnte...
Gruß,
   Thorsten