(gelöst) Fehler E5 - Danfoss Thermostate - nach Update 23.08.2015

Begonnen von cnkru, 30 August 2015, 12:18:47

Vorheriges Thema - Nächstes Thema

cnkru

Alle Thermostate gestorben  :(

Setze erst einmal zurück auf 9041,
Morgen ist auch noch ein Tag  ...

letzter Log anbei
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

cnkru

Noch ein Versuch für diese Nacht  ;)

Version 9094 eingespielt und Zeile 1610 auskommentiert
#    IOWrite($hash, "00", "13${nodeId}02840805");

Ich werde morgen berichten ...

Nachtigall
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

cnkru

#17
Ein schöner Morgen hat begonnen und siehe da alle Themostate verrichten ordnungsgemäß ihren Job  :D
Die letzte Version mit oben auskommentierter Zeile funktioniert.

Aber weiterhin regelmäßig Errors oder Telegrammverluste ...

Die Logs, wie gewohnt anbei

@Christian bzgl. Danfoss und  wakeupnoinformation wurde ich nicht fündig, Informationen sind spärlich
                 Das Themostat unterstützt die  Wake Up Klasse Version 2 
                 Implementiert ist SDK 4.55, alle TH auf dem selben Stand
                 Der Beipackzettel gibt auch nicht viel her.
CUL8R
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

krikan

Zitatbzgl. Danfoss und  wakeupnoinformation wurde ich nicht fündig, Informationen sind spärlich
Das ist leider normal. So wirklich verstehen würde ich das Fehlen von WU-NMI auch nicht. Aber es wäre schön einfach gewesen.
Das Danfoss E5 ist allgemein wohl recht zickig, wenn man sich durch die verschiedenen Foren liest.
Dann geht das Rätselraten wohl ein wenig weiter. Insgesamt sind die Logs mMn sehr von Problemen geprägt...

Es wäre prima, wenn auch noch andere Danfoss-User mit aktuellem Fhem hier Infos hätten, ob sie das Problem auch haben.

cnkru

#19
Ich bin mir nicht ganz sicher, ob wir auf der richtigen Spur sind.
Ich werde die 9094 heute Abend ohne kommentierte IOWrite Zeile starten, also den Kommentar entfernen
                         #    IOWrite($hash, "00", "13${nodeId}02840805");
um auszuschließen, daß WU-NMI der Störenfried ist
Mal sehen was die THs dazu sagen ...
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

cnkru

So bin wieder am Start
Version 9094 mit aktiven WU-NMI seit über 2 Stunden stabil

9102 führt zu Fehler E5 - der Übertäter scheint identifiziert

Irgendeine Idee?

ggf. lass ich 9102 noch mal eine Stunde laufen bis E5 auftaucht

CU
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

A.Harrenberg

Hi,

ich habe mir die Logs jetzt noch nicht angesehen, aber die Änderungen zwischen den beiden Revisionen 9094 und 9102 betreffen natürlich wie von Rudi beschrieben den WU-Sendstack.

Eine ganz dumme Idee hierzu, die Umstellung beeinhaltet auch eine Umstellung der verwendeten Systemzeiten von time() auf gettimeofday(), Grund hierfür war soweit ich das verstanden habe die Auflösung zu verbessern.

Wäre es evtl. möglich das bei Dir das gettimeofday() aus irgendwelchen Gründen (sehr alte oder sehr neue PERL Version, fehlendes Modul, ...) nicht funktioniert und das dadurch die neuen Timer, die jetzt das Versenden der WU-NoMoreInformation steuern Amok laufen?

@Rudi: vielleicht an der Stelle mal ein paar Logmessages für die Timer einbauen?

Gruß,
Andreas.

FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

A.Harrenberg

Hallo Rudi,

In ZWave_wakupTimer wird das Senden der WU-NMI ausgelöst wenn der Zeitstempel älter als 1 Sekunde ist, falls nicht wird per Timer darauf gewartet das er älter als 1 Sekunde ist, zumindest wenn ich den Code richtig verstanden habe.

2015.09.01 19:00:30.178 4: ZWDongle_Read ZWAVE1: sending ACK, processing 00040006028407
2015.09.01 19:00:30.178 5: SW: 06
2015.09.01 19:00:30.180 5: ZWAVE1 dispatch 00040006028407
2015.09.01 19:00:30.181 4: ZWAVE1 CMD:APPLICATION_COMMAND_HANDLER ID:06 ARG:028407
2015.09.01 19:00:30.182 5: ZWDongle_Write 00 13060280022506
2015.09.01 19:00:30.183 5: SW: 0109001306028002250640
2015.09.01 19:00:30.185 4: Sending stored command: 13060280022506
2015.09.01 19:00:30.185 5: ZWDongle_Write 00 130605430101011b2506
2015.09.01 19:00:30.186 4: Sending stored command: 130605430101011b2506
2015.09.01 19:00:30.187 4: Sending wakeupNoMoreInformation to node: 06
2015.09.01 19:00:30.187 5: ZWDongle_Write 00 130602840805
2015.09.01 19:00:30.193 5: ACK received, removing 0109001306028002250640 from sendstack
2015.09.01 19:00:30.194 4: ZWDongle_Read ZWAVE1: sending ACK, processing 011301
2015.09.01 19:00:30.194 5: SW: 06
2015.09.01 19:00:30.196 5: ZWAVE1 dispatch 011301
2015.09.01 19:00:30.198 5: SW: 010c00130605430101011b250699
2015.09.01 19:00:30.200 5: ACK received, removing 010c00130605430101011b250699 from sendstack
2015.09.01 19:00:30.201 5: SW: 01080013060284080569
2015.09.01 19:00:30.205 4: ZWDongle_Read ZWAVE1: sending ACK, processing 011301
2015.09.01 19:00:30.205 5: SW: 06
2015.09.01 19:00:30.207 5: ZWAVE1 dispatch 011301
2015.09.01 19:00:30.208 4: ZWDongle_Read ZWAVE1: CAN received
2015.09.01 19:00:30.209 5: SW: 01080013060284080569

Das Eintreffen der 8407 WU-Notification sollte ja den Zeitstempel in ZWave_Parse setzen
2015.09.01 19:00:30.181 4: ZWAVE1 CMD:APPLICATION_COMMAND_HANDLER ID:06 ARG:028407

Das Senden der WU-NMI wird aber bereits nach 0.06 sekunden ausgelöst
2015.09.01 19:00:30.187 4: Sending wakeupNoMoreInformation to node: 06

und nach 0.19s bereits gesendet
2015.09.01 19:00:30.201 5: SW: 01080013060284080569

Abgesehen davon das hier gesendet wird bevor die RM von dem letzten Befehl da ist, kann hier irgendwas mit den Timer nicht in Ordnung sein.

Gruß,
Andreas.

FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

cnkru

Hallo Andreas,

anbei meine  Version

perl -v

This is perl 5, version 14, subversion 2 (v5.14.2) built for arm-linux-gnueabihf-thread-multi-64int
(with 89 registered patches, see perl -V for more detail)

Copyright 1987-2011, Larry Wall

Perl may be copied only under the terms of either the Artistic License or the
GNU General Public License, which may be found in the Perl 5 source kit.

Complete documentation for Perl, including FAQ lists, should be found on
this system using "man perl" or "perldoc perl".  If you have access to the
Internet, point your browser at http://www.perl.org/, the Perl Home Page.
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

A.Harrenberg

Hi cnkru,

sorry, aber mit der Versionsnummer kann ich nichts anfangen, ich bin auch kein PERL Guru der da was dran erkennen könnte.

Mir ist nur aufgefallen das die Änderung den Timer betrifft und das die WakeUp-NoMoreInformation Nachrichten viel zu schnell freigeben werden.

Im Listing aus Deinem ersten Post ist
Internals:
   ... einiges gelöscht ...
   lastMsgTimestamp 1440929696

der Zeitstempel bei Dir auch ohne "Punkt" und Nachkommastelle.

Bei mir ist das z.B. lastMsgTimestamp 1441313295.57628

Ich habe mal "offline" eine Version mit ein paar Logmessages für die Timer beim WakeUpStack gemacht und angehängt. Das kann ich aber momentan nicht probieren da ich hier momentan eine andere "Baustelle" habe ,-)

Vielleicht sieht man dadurch ja schon was. Vielleicht bin ich auch völlig auf dem Holzweg, dann musst Du auf Rudi warten.

Gruß,
Andreas.

FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

cnkru

Hallo Andreas,

guter Tipp, das bringt mich auf eine Idee ...
Ich werde heute prüfen, ob das high-resolution Paket von Perl installiert ist
und ggf. updaten ( ... install Time::HiRes )

Deine Logs aus der aktuellen 10_ZWave.pm werde ich erst mal in der lauffähigen Version 9094 implementieren.
Ich wünsche uns viel Erfolg.

Werde später berichten

Gruß,
Carsten
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

rudolfkoenig

Hab die Daten nicht naeher angeschaut, aber:
Das Time::HiRes muss da sein, sonst kann fhem.pl nicht starten. Die Log Ausgaben mit msec und InternalTimer verwenden die gleiche Funktion.

A.Harrenberg

Hallo,
Zitat von: rudolfkoenig am 04 September 2015, 12:14:33
Hab die Daten nicht naeher angeschaut, aber:
Das Time::HiRes muss da sein, sonst kann fhem.pl nicht starten. Die Log Ausgaben mit msec und InternalTimer verwenden die gleiche Funktion.
das wird ja mit use eingebunden und müsste auch einen Fehler werfen wenn nicht installiert. Soweit sehe ich das auch so, allerdings finde ich es auffällig das lastMsgTimestamp 1440929696 bei cnkru keine Nachkommastellen ausweist und die WU-NMI früher ausgelöst wird als man anhand des Codes und des Timers erwarten würde. Es sieht so aus als ob der immer der Meinung ist das der Timestamp immer älter als eine Sekunde ist.

Wäre es vielleicht noch denkbar das verschiede Versionen mal in sekunden und mal in millisekunden ausgeben?

Gruß,
Andreas.
FB 7360, Homematic und ZWave
Support for ZWave-SECURITY

cnkru

Hallo Andreas

habe doch die ZWave.pm 9176 eingespielt seit 20:40 Uhr
habe mir erlaubt in Zeile 1773 noch ein Dezimalpunkt zu spendieren
anbei ein erster Log, warte gespannt auf E5

Gruß Carsten
RPi4, Razberry, ZWAVE (Thermostate, Dimmer, Schalter, Multisensor), Milight-LED, Wifi (IPCAM, Fritz!DECT, Sonoff), alexa, Hombridge, Velux-Rollos, Viessman-API, iobroker, SENEC

A.Harrenberg

Hi cnkru,
Zitat von: cnkru am 04 September 2015, 21:01:55
habe doch die ZWave.pm 9176 eingespielt seit 20:40 Uhr
habe mir erlaubt in Zeile 1773 noch ein Dezimalpunkt zu spendieren
anbei ein erster Log, warte gespannt auf E5
Log schaue ich mir gleich an, ob da in Zeile 1773 nun 1 oder 0.1 drin steht sollte keinen wirklichen Unterschied machen, das ist "nur " die Stelle an der die Abfrage das erst mal kommt.

Gruß,
Andreas.
FB 7360, Homematic und ZWave
Support for ZWave-SECURITY