Periodische StatusRequests (alle 30 Min)

Begonnen von Rampler, 09 Juli 2015, 18:09:40

Vorheriges Thema - Nächstes Thema

Rampler

Hallo zusammen,
habe neuerdings festgestellt das FHEM den Funkgong und die 16fach LED Anzeige periodisch alle 30 min mit einem Statusrequest abfragt:

2015.07.09 17:01:30 3: CUL_HM set FL.EG.anzeige statusRequest
2015.07.09 17:01:31 3: CUL_HM set FL.EG.gong statusRequest
2015.07.09 17:31:36 3: CUL_HM set FL.EG.anzeige statusRequest
2015.07.09 17:31:37 3: CUL_HM set FL.EG.gong statusRequest
2015.07.09 18:01:42 3: CUL_HM set FL.EG.anzeige statusRequest
2015.07.09 18:01:43 3: CUL_HM set FL.EG.gong statusRequest

Der actcycle ist bei beiden Geräten NICHT gesetzt !
Wurde das was geändert, habe ich da was versäumt ?
Hat das einen tieferen Sinn ?

3 HMUART (2 via ESP8266), 1 DUOFERN, 12 ESP8266, SolvisBen, GoodWE WR, RPI2 (Bullseye), ZWAVE, HM-Classic, und hoch zufrieden ...
Danke an alle, die was dazu beigetragen haben !!

ucm73

Diese beiden Abfragen ( für HM-OU-CFM-PL und HM-OU-LED16) werden auch bei mir alle 30min ausgeführt.
Es scheint, dass bei statusrequest nicht alles (korrekt) ausgelesen wird.
Es erfolgt das erneute statusrequest auch nach setzen von autoReadReg auf 5.

Log LED16

015.09.02 21:43:41.960 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:1
2015.09.02 21:43:41.990 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:2
2015.09.02 21:43:42.020 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:3
2015.09.02 21:43:42.050 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:4
2015.09.02 21:43:42.080 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:5
2015.09.02 21:43:42.110 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:6
2015.09.02 21:43:42.139 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:7
2015.09.02 21:43:42.169 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:8
2015.09.02 21:43:42.199 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:9
2015.09.02 21:43:42.229 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:10
2015.09.02 21:43:42.259 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:11
2015.09.02 21:43:42.289 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:12
2015.09.02 21:43:42.319 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:13
2015.09.02 21:43:42.349 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:14
2015.09.02 21:43:42.378 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:15
2015.09.02 21:43:42.408 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_pending pending:16
2015.09.02 21:43:42.409 3: CUL_HM set CUL_HM_HM_OU_LED16_1DFF78 statusRequest
2015.09.02 21:43:42.414 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:15
2015.09.02 21:43:42.589 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:15
2015.09.02 21:43:42.590 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:42.697 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:42.703 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:14
2015.09.02 21:43:43.108 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:14
2015.09.02 21:43:43.109 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:43.220 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:43.226 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:13
2015.09.02 21:43:43.626 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:13
2015.09.02 21:43:43.627 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:43.734 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:43.741 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:12
2015.09.02 21:43:44.142 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:12
2015.09.02 21:43:44.142 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:44.251 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:44.255 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:11
2015.09.02 21:43:44.659 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:11
2015.09.02 21:43:44.660 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:44.769 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:44.773 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:10
2015.09.02 21:43:45.179 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:10
2015.09.02 21:43:45.179 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:45.287 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:45.292 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:9
2015.09.02 21:43:45.696 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:9
2015.09.02 21:43:45.697 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:45.805 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:45.809 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:8
2015.09.02 21:43:46.215 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:8
2015.09.02 21:43:46.216 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:46.329 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:46.333 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:7
2015.09.02 21:43:46.733 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:7
2015.09.02 21:43:46.734 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:46.842 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:46.846 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:6
2015.09.02 21:43:47.252 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:6
2015.09.02 21:43:47.253 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:47.361 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:47.365 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:5
2015.09.02 21:43:47.771 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:5
2015.09.02 21:43:47.772 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:47.880 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:47.884 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:4
2015.09.02 21:43:48.294 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:4
2015.09.02 21:43:48.294 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:48.399 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:48.403 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:3
2015.09.02 21:43:48.808 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:3
2015.09.02 21:43:48.809 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:48.917 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:48.922 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:2
2015.09.02 21:43:49.326 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:2
2015.09.02 21:43:49.327 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:49.435 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:49.438 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:1
2015.09.02 21:43:49.845 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:1
2015.09.02 21:43:49.846 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:49.954 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process
2015.09.02 21:43:49.958 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_processing... pending:0
2015.09.02 21:43:50.363 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 protEvent:CMDs_done
2015.09.02 21:43:50.364 5: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 sent ACK:2
2015.09.02 21:43:50.473 4: CUL_HM CUL_HM_HM_OU_LED16_1DFF78 dupe: dont process


Beim MP3-Funkgong erscheint im state dann "unreachable", im LED16 aber "CMDs_done".
Nachvollziehbar ist das für mich nicht, da im log des MP3-Funkgongs auch ein "CMDs_done" steht.

Log MP3-Funkgong

015.09.02 22:01:02.104 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC protEvent:CMDs_pending pending:1
2015.09.02 22:01:02.136 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC protEvent:CMDs_pending pending:2
2015.09.02 22:01:02.136 3: CUL_HM set CUL_HM_HM_OU_CFM_PL_217FCC statusRequest
2015.09.02 22:01:02.141 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC protEvent:CMDs_processing... pending:1
2015.09.02 22:01:02.322 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC protEvent:CMDs_processing... pending:1
2015.09.02 22:01:02.323 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC sent ACK:2
2015.09.02 22:01:02.432 4: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC dupe: dont process
2015.09.02 22:01:02.438 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC protEvent:CMDs_processing... pending:0
2015.09.02 22:01:02.848 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC protEvent:CMDs_done
2015.09.02 22:01:02.849 5: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC sent ACK:2
2015.09.02 22:01:02.995 4: CUL_HM CUL_HM_HM_OU_CFM_PL_217FCC dupe: dont process


Völlig dubios und unverständlich ist, dass wenn ich den statusrequest manuell auslöse, bei gleichem Log ein "CMDs_done" im state erhalte; mehrfach überprüft.

Könnte sich das nochmal jemand ansehen und mir sagen, wonach ich suchen kann, damit diese "statusrequests" funktionieren/aufhören.
AutoReadReg auf 0 setzen, möchte ich vermeiden.

ucm73

Vielleicht könnt ihr mit den HMLAN Daten etwas anfangen.

LOG MP3-Funkgong

2015.09.04 19:18:40.710 3: CUL_HM set CUL_HM_HM_OU_CFM_PL_217FCC statusRequest
2015.09.04 19:18:40.712 0: HMLAN_Send:  HMLAN1 S:+217FCC,00,00,00
2015.09.04 19:18:40.713 0: HMLAN_Send:  HMLAN1 S:S995E0996 stat:  00 t:00000000 d:01 r:995E0996 m:06 A001 1EA01F 217FCC 010E
2015.09.04 19:18:40.949 0: HMLAN_Parse: HMLAN1 R:E217FCC   stat:0000 t:010D1383 d:FF r:FFC1     m:06 A410 217FCC 1EA01F 060100003D
2015.09.04 19:18:41.013 0: HMLAN_Parse: HMLAN1 R:R995E0996 stat:0001 t:010D1388 d:FF r:FFC1     m:06 A410 217FCC 1EA01F 060100003D
2015.09.04 19:18:41.020 0: HMLAN_Send:  HMLAN1 S:+217FCC,00,00,00
2015.09.04 19:18:41.021 0: HMLAN_Send:  HMLAN1 S:S995E0AC9 stat:  00 t:00000000 d:01 r:995E0AC9 m:07 A001 1EA01F 217FCC 020E
2015.09.04 19:18:41.412 0: HMLAN_Parse: HMLAN1 R:E217FCC   stat:0000 t:010D1592 d:FF r:FFC1     m:07 A410 217FCC 1EA01F 060200003C
2015.09.04 19:18:41.529 0: HMLAN_Parse: HMLAN1 R:R995E0AC9 stat:0001 t:010D1597 d:FF r:FFC1     m:07 A410 217FCC 1EA01F 060200003C
2015.09.04 19:18:44.613 0: HMLAN_Send:  HMLAN1 I:K
2015.09.04 19:18:44.617 0: HMLAN_Parse: HMLAN1 V:03C4 sNo:JEQ0706786 d:1EA01F O:1EA01F t:010D2224 IDcnt:0045 L:88 %


Das muss doch rauszubekommen sein, warum der statusrequest nicht funktioniert.

franky08

#3
kann ich für HM-OU-LED16 bestätigen:

2015.09.04 21:06:22 3: CUL_HM set 16_LED statusRequest
......
2015.09.04 21:36:28 3: CUL_HM set 16_LED statusRequest
.....
2015.09.04 22:06:34 3: CUL_HM set 16_LED statusRequest
.....
2015.09.04 22:36:40 3: CUL_HM set 16_LED statusRequest




Version

# $Id: 10_CUL_HM.pm 9190 2015-09-01 18:52:12Z martinp876 $


VG
Frank
Debian Bookworm auf HUNSN / Debian Bullseye auf 2.ter HUNSN F2F an 2x RaspiB
mit FHEM aktuell
22Zoll ViewSonic als Infodislay (WVC)
3xHMLAN mit vccu, raspmatic_rpi3, HMIP-HCU1

franky08

Könnte Martin mal drüber schauen  :)

VG
Frank
Debian Bookworm auf HUNSN / Debian Bullseye auf 2.ter HUNSN F2F an 2x RaspiB
mit FHEM aktuell
22Zoll ViewSonic als Infodislay (WVC)
3xHMLAN mit vccu, raspmatic_rpi3, HMIP-HCU1

martinp876

bei einem LED16 werden 16 Kanäle geprüft, daher 16 messages. Wenn eine Antwort kommt sollte auch ruhe sein.

autoReadReg ist das Attribut zum Abschalten, nicht actCycle.

die Kommandos sollten aber gleich gesendet werden - passiert das nicht? In meiner simulation schon.

franky08

#6
Ist alles OK mit 5_readMissing. Stand noch auf 4
Bin jetzt mal auf 0_off gegangen, mal sehen ob es was bringt.

VG
Frank
Debian Bookworm auf HUNSN / Debian Bullseye auf 2.ter HUNSN F2F an 2x RaspiB
mit FHEM aktuell
22Zoll ViewSonic als Infodislay (WVC)
3xHMLAN mit vccu, raspmatic_rpi3, HMIP-HCU1

ucm73

Hallo,
vielleicht noch einmal zusammengefasst.
Statusrequest wird alle 30min ausgeführt.
Es kommt von beiden Geräten, wie oben im Log angeführt eine Antwort.
Ist klar beim LED16 von allen Kanälen.
Der MP3 Funkgong steht danach aber auf state "unreachable" der LED16 auf "done".
Und dann nach 30min ein neues statusrequest.
Die Geräte funktionieren weiterhin einwandfrei.

Das "dupe: dont process" wie in den logs zu sehen, kann nicht dazu führen, dass der status nicht als korrekt "hinterlegt" wird  und deshalb ein neuer statusrequest kommt?

ucm73

So, das hat mir keine Ruhe gelassen.
Beide Geräte noch einmal resetet und neu gepaired, wie zu erwarten keine Änderung.
Dann bin ich die alten Filelogs der beiden Geräte durchgegangen.
Die Fehler traten zuerst am 02.08.15 auf.
Mit
Zitat
$Id: 10_CUL_HM.pm 8976 2015-07-26 06:22:16Z martinp876 $
war alles ok.
Danach die statusrequest Fehler.
Und, auch wenn ich mich wiederhole, bei einem manuellen auslösen des statusrequest für diese beiden Geräte, kommt kein "unreachable".
Ich habe auch herausgefunden, warum beim LED16 bei state kein "unreachable" steht, das "unreachable" bei der 30min Abfrage erscheint immer in den 16 Abfragen der einzelnen LED Anzeigen, sodass am Ende ein "done" erscheint, was eine korrekte Verarbeitung vorgaukelt.

Martin kannst du dir das bitte noch einmal anschauen?

franky08

Bei mir tritt die Logmeldung definitiv nicht mehr auf

# $Id: 10_CUL_HM.pm 9190 2015-09-01 18:52:12Z martinp876 $



Debian Bookworm auf HUNSN / Debian Bullseye auf 2.ter HUNSN F2F an 2x RaspiB
mit FHEM aktuell
22Zoll ViewSonic als Infodislay (WVC)
3xHMLAN mit vccu, raspmatic_rpi3, HMIP-HCU1

martinp876

Die nächsten Wochen komme ich nicht an meine Anlage.... Bin unterwegs.
Der automatische statusrequest wird alle 30min probiert bis er klappt. Dann sollte Ruhe sein bis ein neues Ereignis einen weiteren triggert. Das ist das Erste, was zu klären ist.., warum werden die antworten ( die kommen doch.....) nicht erkannt.

Ein automatischer request sollte sich vom manuellen nicht unterscheiden. Sowohl die message sollte identisch sein, als auch der Sendezeit Punkt( falls es einen speziellen sendemode hat, burst, wakeup)
Ich werde noch einmal prüfen, was passiert. Loge sollten da sein

RalfP

Hallo,

ich würde gern dieses Thema neu aufgreifen, habe auch dieses Problem. Allerdings erst seit dem ich auf einen neuen Pi 2 und Fhem 5.7 umgestiegen bin. Gibt es evtl. neue Erkenntnisse?

Meine Logs sehen genauso aus, wie von ucm73.

Gruß
Ralf

martinp876

kannst du die rohmessags einmal aufzeichnen - über min 1 h, also 2 mal wiederholen

caldir65

Hallo,
hat sich da etwas Neues ergeben?
Ich habe, seit dem ich von einem BananaPi auf einen HP Proliant Microserver umgestiegen bin, bei einem HM-LC-Bl1PBU-FM RolladenAktor. Funktion ist aber gegeben.

Gruß, Christoph
Alte Techniker-Regel: "kaum macht man es richtig, funktioniert es auch"
------
Dell Wyse5070 ThinClient 16GBRam, 128GB SSD, Lubuntu 24.04.01LTS, fhem (aktuell), debmatic, Homematic-Devs, ConBee II und deConz, viele Shellys, Rademacher, NextCloud-Anbindung, FullyKioskBrowser+FUIP uvm.

martinp876