Hallo,
ich betrieb über FHEM den HM-LC-Sw2-FM 2 fach Aktor, geschaltet werden zwei LED Leisten. Mit den kleben bleiben der Relais habe ich kein Problem.
Jedoch bei Ausschalten der Lampen. Nachdem ich den Web Button gedrückt habe wird die Lampe erst mit ca 5 Sekunden Verspätung ausgeschaltet.
Zwecks Fehlersuche habe ich den "Verbose" Wert auf 5 gestellt, zwischen den Auschaltbefehl und der Rückmeldung kommen gefühlte 10.000 Zeilen, jedoch ist mir ein Zeitlicher hänger aufgefallen:
,,2017.05.31 12:46:40 4: DbLog logdb -> check Device: Kuechenschraenke , Event: CMDs_pending
2017.05.31 12:46:40 5: CUL_HM Kuechenschraenke protEvent:CMDs_pending pending:1
2017.05.31 12:46:40 4: name: /fhem?cmd.Schrank_Links=set%20Schrank_Links%20off&room=K%C3%BCche&XHR=1&fw_id=1229 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2017.05.31 12:46:40 4: WEB_80.187.101.108_6691 GET /fhem/images/default/set_off.png; BUFLEN:0
2017.05.31 12:46:44 4: HMLAN_ack: timeout - clear queue
2017.05.31 12:46:44 5: HMLAN_Send: HMLAN1 S:S5E1D6B40 stat: 00 t:00000000 d:01 r:5E1D6B40 m:55 A011 200db8 53A44B 0201000000
2017.05.31 12:46:44 5: CUL_HM Kuechenschraenke protEvent:CMDs_processing... pending:0
2017.05.31 12:46:45 5: HMLAN/RAW: /R5E1D6B40,0001,176B7654,FF,FFB1,55800253A44B200DB80101000057
"
Wie kommt das " HMLAN_ack: timeout - clear queue" zustande und was kann ich dagegen tun?
Beim Einschalten kommt diese Meldung nicht....
Vielen dank für die Hilfe
Gruß
Thorsten
HMLAN_ack: timeout - clear queue
Heißt soviel, Komando zum ausschalten ging raus, vom Aktor keine Bestätigung erhalten (ACK)
da du einen HMLAN hast hat er im Normalfall die Nachricht 9 mal!! gesendet.
eine Nachricht wurde vom hmlan mit -87 db empfangen vom HMlan.
diese Zeitverzögerungen von 5 sekunden sprechen für miserable Funkverbindung
Ich sags mal pauschal Funk verbessern.
Du siehst das auch normal in deinem Device unter rssi
Hallo,
komisch der HMLAN hängt mit externe Antenne ca 10m entfernt eine Etage höher auf dem Dachboden. Dann muss ich mal schauen, wo ich den HMLAN besser platzieren kann. Er muss ja auch noch nach Drausen zum Nebengebäude funken, da hängt noch das Türschloss....
Das sind die Werte von dem Aktor:
lst:-78 cnt:78 avg:-80.02 max:-75 min:-95
Welchen Wert sollte der haben?
Gruß und danke
thorsten
Tendez sagt schlechter wie -80 , kann gehen muß aber nicht. und -95 ist richtig schlecht.
mach dir doch ein weiteres i/o von hm dazu, habe inzwischen selber auch 3 stück. da ich hier 4 Stockwerke überbrücken muß.
wäre aber schon seltsam, dass ein- und ausschalten vom funk unterschiedlich behandelt wird.
Zitatzwischen den Auschaltbefehl und der Rückmeldung kommen gefühlte 10.000 Zeilen
ich würde mal apptime/perfmon starten.
Hallo,
@Frank: Das mit dem "apptime/perfmon" werde ich mir am Wochenende mal anschauen.....
Habe den HMLAN jetzt in der Wohnung plaziert, das sind nun die Werte:
"rssi_at_HMLAN1 lst:-63...."
Jedoch immer noch eine starke Verzögerung:
"2017-05-31 20:59:50 CUL_HM Schrank_Links set_on
2017-05-31 20:59:51 CUL_HM Schrank_Links deviceMsg: on (to 200DB8)
2017-05-31 20:59:51 CUL_HM Schrank_Links level: 100
2017-05-31 20:59:51 CUL_HM Schrank_Links pct: 100
2017-05-31 20:59:51 CUL_HM Schrank_Links on
2017-05-31 20:59:51 CUL_HM Schrank_Links timedOn: off
2017-05-31 20:59:56 CUL_HM Schrank_Links set_off
2017-05-31 21:00:01 CUL_HM Schrank_Links deviceMsg: off (to 200DB8)
2017-05-31 21:00:01 CUL_HM Schrank_Links level: 0
2017-05-31 21:00:01 CUL_HM Schrank_Links pct: 0
2017-05-31 21:00:01 CUL_HM Schrank_Links off
2017-05-31 21:00:01 CUL_HM Schrank_Links timedOn: off"
Gruß
Thorsten
ist die verzögerung auch an der leuchte so extrem, oder nur die rückmeldung in fhem?
die rückmeldung kommt mit default konfiguration der register sowieso etwas verzögert.
hast du notify, doif, ... ,die auf "set_off" triggern?
Ich habe nur ein notify bei "Schrank_Links:off und wenn ich das disable ist die gleiche Verzögerung.... Ich habe auch noch den HM-LC-Sw4-W... da ist das gleiche Problem.....
Das meiste im Haus steure ich über die Logo, nur die Ergänzungen mache ich dann über Homematic und FHEM
Gruß
Thorsten
Die Verzögerung an der Lampe ist auch so stark....
Hallo,
perfmon hat auch nicht ergeben...... Nur wie kommt diese Verzögerung zwischen 08:30:26 und 08:30:29 hier unten zu stande? An der Funkverbindung kann es ja nicht mehr liegen.....
Gruß
Thorsten
2017.06.01 08:30:26 3: CUL_HM set Schrank_Rechts off
2017.06.01 08:30:26 5: Triggering Kuechenschraenke (1 changes)
2017.06.01 08:30:26 5: Starting notify loop for Kuechenschraenke, 1 event(s), first is CMDs_pending
2017.06.01 08:30:26 5: ZE.Activity: not on any display, ignoring notify
2017.06.01 08:30:26 5: ZE.Batterie: not on any display, ignoring notify
2017.06.01 08:30:26 4: DbLog logdb -> ################################################################
2017.06.01 08:30:26 4: DbLog logdb -> ### start of new Logcycle ###
2017.06.01 08:30:26 4: DbLog logdb -> ################################################################
2017.06.01 08:30:26 4: DbLog logdb -> amount of events received: 1 for device: Kuechenschraenke
2017.06.01 08:30:26 4: DbLog logdb -> check Device: Kuechenschraenke , Event: CMDs_pending
2017.06.01 08:30:26 5: CUL_HM Kuechenschraenke protEvent:CMDs_pending pending:1
2017.06.01 08:30:26 4: name: /fhem?cmd.Schrank_Rechts=set%20Schrank_Rechts%20off&room=K%c3%bcche&XHR=1&fw_id=429 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2017.06.01 08:30:29 4: Connection closed for WEBphone_80.187.106.221_1630: EOF
2017.06.01 08:30:31 4: HMLAN_ack: timeout - clear queue
Hallo,
kann mir da keiner tips geben? Nachdem ich den verbose Wert auf 5 gestellt habe. Und die Lampe ausschalte stehen in der Log Datei mehrer Hundert Zeilen von den ausschalten bis zur Rückmeldung der Lampe. Doch mitten drin ist eine Pause von 5 Sekunden...... Warum?????
Hier habe ich nochmal einen Auszug aus der Logdatei.
2017.06.03 20:10:03 4: DbLog logdb -> check Device: Schrank_Links , Event: pct: 100
2017.06.03 20:10:03 4: DbLog logdb -> check Device: Schrank_Links , Event: on
2017.06.03 20:10:03 4: DbLog logdb -> check Device: Schrank_Links , Event: timedOn: off
2017.06.03 20:10:03 5: End notify loop for Schrank_Links
2017.06.03 20:10:08 4: WEB_192.168.1.160_59564 POST /fhem?cmd.Schrank_Links=set%20Schrank_Links%20off&room=K%C3%BCche&XHR=1&fw_id=295; BUFLEN:0
2017.06.03 20:10:08 5: Cmd: >set Schrank_Links off<
2017.06.03 20:10:08 5: Starting notify loop for Kuechenschraenke, 1 event(s), first is CMDs_pending
Unten der Link für zu den kompletten Auszug aus der Log Datei von den Ausschalten der Lampe bis zur Rückmeldung das Sie aus ist....
http://www.wetter-keppeln.de/Share/fhem.txt
Vielen Vielen dank
Gruß
Thorsten
wie schnell ist die Datenbank? Blockiert die FHEM?
wie schnell sind die web-kommandos? Immer gefährlich. Sollte man mit non-blocking laufen lassen
apptime schon probiert? Timing Analyse ist gefragt.
Hey,
ich habe jetzt mal alle notify die mit den Küchenschränken und HTTP aufrufen zu tun haben deaktiviert sowie auch die Datenbank, immer noch ein Versatz von 4 Sekunden
"2017.06.05 09:30:06 5: End notify loop for Schrank_Links
2017.06.05 09:30:10 4: WEB_192.168.1.160_52620 POST /fhem?cmd.Schrank_Links=set%20Schrank_Links%20off&room=K%C3%BCche&XHR=1&fw_id=466; BUFLEN:0"
Bei Apptime ist nicht aufälliges .....
hier das Ergebnis:
http://www.wetter-keppeln.de/Share/apptime.txt
Also Datenbank und HTTP aufrufe, obwohl ich da nicht wenig habe ( Statusänderung an Siemens Logo) kann man dann wohl ausschließen....
Laut Log Datei finden zwischen ausschalten und Rückmeldung auch keine weiteren HTTP aufrufe statt....
Gruß und danke
Thorsten
die max verzoeberung ist 5sec- oder lese ich das falsch?
einen dauerbrenner sehe ich nicht. apptime prueft allerdings nur fhem. was dein Computer sonst noch treibt ist verborgen.
ist das log von oben komplett, oder sind es nur auszüge?
mir fallen folgende dinge auf:
beim einschalten wird umgehend der befehl an den hmlan gesendet.
2017.06.03 20:10:03 3: CUL_HM set Schrank_Links on\
2017.06.03 20:10:03 5: HMLAN_Send: HMLAN1 S:S6F265A2E stat: 00 t:00000000 d:01 r:6F265A2E m:AF A011 200db8 53A44B 0201C80000\
beim ausschalten erfogt die verzögerung.
2017.06.03 20:10:08 3: CUL_HM set Schrank_Links off\
2017.06.03 20:10:08 5: Starting notify loop for Kuechenschraenke, 1 event(s), first is CMDs_pending\
2017.06.03 20:10:08 5: ZE.Activity: not on any display, ignoring notify\
2017.06.03 20:10:08 5: ZE.Batterie: not on any display, ignoring notify\
2017.06.03 20:10:08 4: DbLog logdb -> ################################################################\
2017.06.03 20:10:08 4: DbLog logdb -> ### start of new Logcycle ###\
2017.06.03 20:10:08 4: DbLog logdb -> ################################################################\
2017.06.03 20:10:08 4: DbLog logdb -> amount of events received: 1 for device: Kuechenschraenke\
2017.06.03 20:10:08 4: DbLog logdb -> check Device: Kuechenschraenke , Event: CMDs_pending\
2017.06.03 20:10:08 5: End notify loop for Kuechenschraenke\
2017.06.03 20:10:08 5: CUL_HM Kuechenschraenke protEvent:CMDs_pending pending:1\
2017.06.03 20:10:08 4: WEB: /fhem?cmd.Schrank_Links=set%20Schrank_Links%20off&room=K%C3%BCche&XHR=1&fw_id=295 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip\
/ \
2017.06.03 20:10:09 5: HMLAN_Send: HMLAN1 I:K\
2017.06.03 20:10:09 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0641348,2CD788,200DB8,1011432C,0011,0D\
\
2017.06.03 20:10:09 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0641348 d:2CD788 O:200DB8 t:1011432C IDcnt:0011 L:13 %\
2017.06.03 20:10:09 5: Starting notify loop for HMLAN1, 1 event(s), first is loadLvl: low\
2017.06.03 20:10:09 5: ZE.Activity: not on any display, ignoring notify\
2017.06.03 20:10:09 5: ZE.Batterie: not on any display, ignoring notify\
2017.06.03 20:10:09 4: DbLog logdb -> ################################################################\
2017.06.03 20:10:09 4: DbLog logdb -> ### start of new Logcycle ###\
2017.06.03 20:10:09 4: DbLog logdb -> ################################################################\
2017.06.03 20:10:09 4: DbLog logdb -> amount of events received: 1 for device: HMLAN1\
2017.06.03 20:10:09 4: DbLog logdb -> check Device: HMLAN1 , Event: loadLvl: low\
2017.06.03 20:10:09 5: End notify loop for HMLAN1\
2017.06.03 20:10:09 1: HMLAN setting owner to 200db8 from 200DB8\
2017.06.03 20:10:09 5: HMLAN_Send: HMLAN1 I:A200db8\
2017.06.03 20:10:13 4: HMLAN_ack: timeout - clear queue\
2017.06.03 20:10:13 5: HMLAN_Send: HMLAN1 S:S6F2681A6 stat: 00 t:00000000 d:01 r:6F2681A6 m:B0 A011 200db8 53A44B 0201000000\
anscheinend gibt es ein problem mit der gross-/kleinschreibung bei den hex adressen. hier wird der owner gewechselt, obwohl er nicht zu wechseln wäre. dabei kommt es dann zum timeout.
2017.06.03 20:10:09 1: HMLAN setting owner to 200db8 from 200DB8\
2017.06.03 20:10:09 5: HMLAN_Send: HMLAN1 I:A200db8\
2017.06.03 20:10:13 4: HMLAN_ack: timeout - clear queue\
poste mal je ein list von hmlan, aktorhauptdevice und beiden channels.
passiert der ownerwechsel immer (nur) beim ausschalten?
dein fhem ist aktuell? was zeigt der fhem befehl "version"?
Hallo,
Was bedeutet genau "ownerwechsel" das hört sich aufjedenfall nicht richtig an. Die Verzögerung ist nur beim Ausschalten.
Unten habe ich die links zu den Von dir genannten Dateien eingefügt. Darunter auch die vollständige Logdatei von Juni.
Vielen Dank für deine Mühe !!!
Gruß
Thorsten
http://46.41.1.201/share/schrank_links.txt
http://46.41.1.201/share/schrank_rechts.txt
http://46.41.1.201/share/kuechenschraenke.txt
http://46.41.1.201/share/hmlan1.txt
http://46.41.1.201/share/log_juni.txt
die hex adresse vom attribut hmid beim hmlan muss schon mal grosse buchstaben haben. wäre allerdings putzig, wenn das immer nur beim ausschalten probleme macht.
hmId 200db8
ausserdem würde ich die aktuelle fw 0.965 nutzen.
Hallo,
Ich glaube es nicht.....dann lag das wirklich an Groß und Kleinschreibung ..... Jetzt schaltet er ohne Verzögerung aus.... Vielen Dank für die Hilfe.... bis ich das selber gefunden hätte währe ich wahrscheinlich wahnsinnig geworden.
Gruß
Thorsten
prima. dann kann ich mir dein log ja ersparen. :)
Cooler Effekt!