Home Connect: Tausende Logeinträge

[moskito]

Hab´s erst seit gestern wieder aktiviert, aber um 01:16 bin ich auch dabei:

Code: [Auswählen]

2018.05.11 01:16:30 2: WAYHxxxx event channel read failed, len:"0", received:""

[Pf@nne]

Moin, hier mein LOG von heute:

Code: [Auswählen]

2018.05.11 18:28:52 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.11 18:28:57 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.11 21:04:38 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.11 21:04:48 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.11 23:34:38 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.11 23:34:48 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.12 00:16:26 2: SN878D26PE event channel read failed, len:"0", received:""
2018.05.12 00:16:26 2: HN878G4B6 event channel read failed, len:"0", received:""
2018.05.12 01:16:30 2: SN878D26PE event channel read failed, len:"0", received:""
2018.05.12 01:16:30 2: CS858GRB6 event channel read failed, len:"0", received:""
2018.05.12 01:16:30 2: EX907KXX1E event channel read failed, len:"0", received:""
2018.05.12 01:16:30 2: HN878G4B6 event channel read failed, len:"0", received:""
2018.05.12 04:10:26 2: SN878D26PE event channel timeout, two keep alive messages missing
2018.05.12 04:10:26 2: CS858GRB6 event channel timeout, two keep alive messages missing
2018.05.12 04:10:26 2: EX907KXX1E event channel timeout, two keep alive messages missing
2018.05.12 04:10:26 2: HN878G4B6 event channel timeout, two keep alive messages missing
2018.05.12 08:05:09 3: hcconn refreshing token
2018.05.12 08:59:09 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.12 09:07:34 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.12 10:25:59 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.12 10:26:09 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.12 10:54:21 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.12 10:54:46 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.12 12:44:08 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.12 12:44:18 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.12 13:04:45 3: ENIGMA2 set VU_WZ msg info 2 GS%5fAUF
2018.05.12 13:04:55 3: ENIGMA2 set VU_WZ msg info 2 GS%5fZU
2018.05.12 14:06:19 2: SN878D26PE event channel read failed, len:"0", received:""
2018.05.12 14:06:19 2: CS858GRB6 event channel read failed, len:"0", received:""
2018.05.12 14:06:19 2: EX907KXX1E event channel read failed, len:"0", received:""
2018.05.12 14:06:19 2: HN878G4B6 event channel read failed, len:"0", received:""

Bisher keine Abstürze!! 

[Schlimbo]

Also, was alle gemeinsam haben ist der Eintrag um 01:16Uhr die anderen Einträge decken sich nur vereinzelt.
Sieht für mich irgendwie so aus, als würde der HomeConnect Server die Event Channels ab und an selbst beenden, richtig erklären kann ich mir das aber auch nicht.

Da die Version ja bei allen ohne Abstürze läuft, würde ich vorschlagen diese offiziell einzuchecken.
Das Loglevel für "event channel read failed" sollte vorher wieder auf 5 gesetzt werden, damit das Logfile "sauber" bleibt.

@swhome: könntest du das bitte einchecken?

 

[Pf@nne]

Ich habe Stefan angeschrieben,  weiß aber nicht wie zeitnah er dazu kommt.

Wie schaut es denn mit den Verzögerungen der Events aus, kann man diese Verzögerung noch wegbekommen oder muss man damit leben?

Gruß und vielen Dank für deine Unterstützung!
Pf@nne

[Schlimbo]

Hast du mit der Version noch immer Verzögerungen bei den Events? Ich hatte mit Stefan's letzten Version damit keine Probleme mehr.

[Pf@nne]

Wie schnell reagiert das Modul den bei dir?
Hast du etwas mit Tür?

Bei mir liegen immer noch gut 3-6 Sekunden zwischen dem Öffnen/Schließen der Ofentür und dem Eventuell im Modul.
Zum Ableiten einer direkten Reaktion z.B. anschalten der Küchenbeleuchtung ist das eher ungeeignet.

Was  bei laufendem Programm passiert habe ich noch nicht getestet.
Das ging bisher garniert, das Event ist Teilweise 10 Minuten später gekommen.

Leider kommen wir nicht lokal auf die Geräte.....
Die HomeConnect App greift auch lokal zu, das ist dann auch ohne merkliche Verzögerung.

Gruß
Pf@nne   

[Schlimbo]

@all:
Habe die Version in meinem Beitrag noch mal aktualisiert. Habe noch einen Fehler gefunden, der zum Absturz bei "set SelectedProgram" führen konnte.

@Pf@nne:
Habe leider kein Gerät mit Tür, bei meiner Kaffeemaschine kommen die Events ohne spürbare Verzögerung an, allerdings kann es sein, dass mir hier die 3-6 Sekunden auch bloß nicht auffallen.
Vielleicht kann Stefan hierzu noch was sagen, habe mir die Event Geschichte noch nicht im Detail angesehen.


 

[Pf@nne]

Kannst du bitte für die geänderte Version einen PullRequest erstellen.
Stefan Check das dann ein.

Vielen Dank für deinen Einsatz!

Gruß
Pf@nne

[swhome]

Ich teste es noch kurz und checke es dann ein. Pull Request machen wir nächstes Mal 

...Update: Die Änderungen von Schlimbo sind in der master Version eingecheckt. VIELEN DANK!

Das mit der Tür probiere ich aus sobald die Sonne untergegangen ist. Erst dann ist meine Küchenbeleuchtung mit der Spülertür synchronisiert 

[Schlimbo]

super, Danke!
Freut mich, dass ich helfen konnte 

[swhome]

Hab gerade noch eine Mini-Änderung nachgeschossen, damit das Attribut "updateTimer" wieder setzbar ist.

Für das Thema mit der Tür noch folgende Anmerkung:
Die letzten Änderungen von Schlimbo und mir hatten zum Ziel, bei Geräten mit sehr vielen Updates zeitnah und schnell genug verarbeiten zu können. Beispiele waren der Backofen oder die Kaffeemaschine mit laufendem Programm. Eine geringere Verzögerung bei einzelnen Updates hingegen kann durch das Setzen des Attributs updateTimer bei der betreffenden Maschine erzielt werden. Hier steht der Standardwert auf 5 Sekunden um in FHEM Ressourcen für wichtigere Dinge freizuhalten. Wenn schneller reagiert werden soll, ist auch der Wert 1 ok.

[Schlimbo]

Hab gerade noch eine Mini-Änderung nachgeschossen, damit das Attribut "updateTimer" wieder setzbar ist.

Der gleiche Fehler hat sich auch bei "accessScope" in der 48_HomeConnectConnection.pm eingeschlichen.

[swhome]

Stimmt. Hab ich nun auch behoben. Merci!