[Problem] Sonoff verzögerte Anzeige Datenpunkt

[AingTii]

Hallo Zusammen,

mir ist neuerdings ein Problem aufgefallen, evtl. ein Bug?
da ich in letzter Zeit viel aktualisiert habe und mit Tasmota Geräten unter anderem
den Stromzähler auslesen zu tun hatte ist mit aufgefallen, dass die Werte in der VIS
nicht den aktuellen Werten in der TasmotaWeboberfläche entsprechen.
Daraufhin habe ich mal den Datenpunkt direkt angeschaut.

Anscheinend sind hier etlich Verzögerungen .

Tasmota sendet alle 2 Sekunden aufgrund des Impulses des Stromzählers die werte raus.

:57 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:57","OBIS":{"Power_p1":43}}
17:44:57 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:57","OBIS":{"Power_p2":1384}}
17:44:57 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:57","OBIS":{"Power_p3":356}}
17:44:57 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:57","OBIS":{"Power_curr":1783}}
17:44:59 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:59","OBIS":{"Total_in":47183.8248}}
17:44:59 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:59","OBIS":{"Power_p1":43}}
17:44:59 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:59","OBIS":{"Power_p2":1383}}
17:44:59 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:59","OBIS":{"Power_p3":348}}
17:44:59 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:44:59","OBIS":{"Power_curr":1773}}
17:45:01 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:01","OBIS":{"Total_in":47183.8256}}
17:45:01 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:01","OBIS":{"Power_p1":43}}
17:45:01 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:01","OBIS":{"Power_p2":1309}}
17:45:01 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:01","OBIS":{"Power_p3":346}}
17:45:01 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:01","OBIS":{"Power_curr":1698}}
17:45:03 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:03","OBIS":{"Total_in":47183.8262}}
17:45:03 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:03","OBIS":{"Power_p1":43}}
17:45:03 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:03","OBIS":{"Power_p2":715}}
17:45:03 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:03","OBIS":{"Power_p3":344}}
17:45:03 MQT: tele/Stromzaehler183/SENSOR = {"Time":"2020-05-11T17:45:03","OBIS":{"Power_curr":1103}}

auch sind im gleichen Zeitstempel im Datenpunkt die Werte vollkommen hinterher, z.B. Total_in
ist mit aktuellem Zeitstempel im Datenpunkt 47181.8715 sollte aber 47183.8262 sein.

Wie kann das sein, normal sollte doch das gesendete auch das sein was dann im Datenpunkt steht.?!?!?
Habe grad noch mal geschaut, dass ist bei allen Sonoff/Tasmota geräten ob die ESP-01er oder auch die GosundSp111er

Hat noch jemand das Problem?

Danke
Gruß Jens

[AingTii]

habe noch mal den gesamten ioBroker neu aufgesetzt in der Hoffnung das irgendetwas da
hakt und es dadurch zu Verzögerungen kommt.

Leider ohne Erfolg.

Irgendetwas kann da doch nicht stimmen, wenn der aktuell über MQTT gesendete Wert
etliche kW im total_In mehr ist als das was im ioBroker ankommt oder angezeigt wird..

Hat kein anderer diese Probleme?

Danke
Gruß Jens

[AingTii]

Guten Morgen,

anscheinend hatten einige ein ähnliches Problem, wobei ich da nicht wirklich eine Lösung rausgelesen habe.
1.Thema
2.Thema

habe Gestern noch etwas getestet.
Beim Neustart des Sonoff Adapters ist es erstmal aktuell und läuft dann langsam raus.

Wobei ich festgestellt habe, dass beim überfahren des Datenpunktes das Aktualisierungsdatum im Popup
aktuell ist, aber weiter unten gibt es noch einen Datenpunkt mit Datum und Uhrzeit der ebenfalls immer grün mitblinkt.
Hier ist teilweise der Zeitpunkt um Stunden zurück, wenn das der Zeitpunkt ist an dem der grad geschriebene Wert
empfangen wurde, ist das ja schon mal ein Hinweis dafür dass der Wert veraltet ist.

Wobei ich jetzt nur gern wüsste, wie ich das ausmärzen kann, und der wert nicht verzögert geschrieben wird.

Hat da jemand eine Lösung oder einen Ansatz?

Danke

Gruß Jens

[Xsev]

Mir ist etwas ähnliches bei einem neuen und frisch geflashten Sonoff POW aufgefallen (Tasmota 8.2.0). Bin mir ned sicher ob das auch dein geschildertes Problem ist..
Ich bekomme teilweise auch nicht alle Änderungen übertragen, in meinem Fall logge ich die Wattzahl u.a. für ein Skript welches auf Veränderungen reagiert. Das Problem ist, ich sehe im Webif vom Sonoff kleine Änderungen in Watt, diese werden aber nicht bzw. nicht immer via MQTT übertragen, sieht man dann auch in der Konsole das nichts gesendet wurde. Ich bin jetzt unter Einstellungen in Logging, auf MQTT Log Level "4 mehr Details" hochgegangen (gestern) es scheint als ob jetzt zumindest mehr Daten ankommen aber ob es wirklich alle Änderungen sind weiß ich aktuell noch nicht.

[Jan1]

@Xsev
frisch geflasht mit 8.2? Wir sind bei 8.3!

[Xsev]

@Jan1
lol ja vor ca 8 Tagen.... meinte nur das es keine uralt Version ist

[Jan1]

@Xsev
Wenn es die 8.2 Master war, dann war die uralt

[Xsev]

@Jan1
Naja also je nachdem wie man uralt definiert. Vor einem Jahr hab ich noch 6er Versionen geflasht (für mich uralt), die erste 8er (die ich geflasht hab) vor paar Wochen, war die im offiziellen Release... Wie auch immer... Scheint ja dann nicht das Problem zu sein.

[Jan1]

@Xsev
Ich habe 30 Geräte und alle Werte sind sobald sie in der Konsole auftauchen auch im IOBroker, wobei ich immer die dev. Version verwende. Wo das Problem jetzt liegt, kann ich auch nicht sagen.

[AingTii]

@Jan1 sagte in [Problem] Sonoff verzögerte Anzeige Datenpunkt:

@Xsev
frisch geflasht mit 8.2? Wir sind bei 8.3!

wie so alt, wir sind bei 8.3.1

Scherz beiseite, denn frisch flashen kann man auch einer 6er Version....

Ich glaube kaum dass es zur Sache beiträgt welche Tasmota Version verwendet wird, wenn die Daten raus gehen und die Konsolen anschließend den Wert protokoliert.
Dennoch im ioBroker der Wert nicht der aktuelle sondern teils Stunden alt ist.

Bilder in der Reihenfolge gemacht erst Konsole, dann iobroker….

Konsolen ausgabe etwa eine Minute vor dem iobroker screenshot

wobei hier in dem kleinen popup Fenster die aktuelle Zeit drin ist und es grad grün wurde

Ist diese Zeit Momentan 5min zurück
(ist noch wenig, da ich vor kurzer Zeit den Adapter neu gestartet habe)

also scheint doch etwas im ioBroker oder Sonoff Adapter zu verzögern dabei.

Noch ergänzend wenn der Sonoff Adapter neu gestartet ist, ist erst alles synchron!
es läuft dann immer weiter raus....
Und ich starte den schon Nachts neu...

Danke

Gruß Jens

[Wal]

Stellt doch den Sonoff-Adapter auf Debug, dann kann man die Logs von Tasmota und Adapter vergleichen. Wenn Tasmota MQTT sendet, muß ja der Adapter im ms Bereich gleich was empfangen.

[Jan1]

@AingTii
Konsole:

18:29:24 MQT: stat/tasmota/RESULT = {"POWER":"OFF"}
18:29:24 MQT: stat/tasmota/POWER = OFF

Adapter:
code_text

Wie Du siehst, ist mein Adapter sogar schneller als meine Sonoff. Da läuft wohl die Zeit nicht 100% synchron und da könntest bei Dir mal nachsehen, ob Dein IOBroker die selbe Zeit wie das Tasmota Device hat. Wenn die zeit passt, dann bremst irgendwas Dein IOBroker aus und das kann viel sein.

Welche Adapter Version läuft bei Dir überhaupt?

[AingTii]

@Wal sagte in [Problem] Sonoff verzögerte Anzeige Datenpunkt:

Stellt doch den Sonoff-Adapter auf Debug, dann kann man die Logs von Tasmota und Adapter vergleichen. Wenn Tasmota MQTT sendet, muß ja der Adapter im ms Bereich gleich was empfangen.

ok, das ist so erst mal nicht auffällig, Problem dabei habe ich grad gesehen, wenn ich das umstelle
wird der Adapter neu gestartet....
Heißt dass ist dann aktuell.
ich könnte den nun heute Nach mal mit debug durchlaufen lassen, aber das wird ein riesen log.
Aber wenn es dem iobroker nichts ausmacht die Mengen über einen Tag zu verarbeiten sollte
es keine Probleme geben...

@Jan1 sagte in [Problem] Sonoff verzögerte Anzeige Datenpunkt:

Konsole:
18:29:24 MQT: stat/tasmota/RESULT = {"POWER":"OFF"} 18:29:24 MQT: stat/tasmota/POWER = OFF
Adapter:
code_text
Wie Du siehst, ist mein Adapter sogar schneller als meine Sonoff. Da läuft wohl die Zeit nicht 100% synchron und da könntest bei Dir mal nachsehen, ob Dein IOBroker die selbe Zeit wie das Tasmota Device hat. Wenn die zeit passt, dann bremst irgendwas Dein IOBroker aus und das kann viel sein.
Welche Adapter Version läuft bei Dir überhaupt?

Ja das funktioniert bei mir auch, da wird ja nur ein punkt kurz gesendet und geschrieben.

Beim Stromzähler werden alle 2 Sekunden 5 werte gesendet.

Und ja am Anfang ist es bei mir im ioBroker auch schon da, bevor es im der Konsole von Tasmota
zu sehen ist.
nach etlichen Stunden sieht es aber anders aus.

schauen wir mal was der debug aussagt Morgen.

p.s.: sonoff 2.3.3
ist kein Update zu sehen, also alles auf aktuellem stand

Danke
Gruß Jens

[Jan1]

@AingTii
Wenn es einmal synchron läuft, dann sollte das auch so bleiben. Auf debug solltest eigentlich nur umschalten, damit Du das sofort kontrollieren kannst. Auf debug gibt das ein sehr großes Log und darin wirst wohl eher nichts mehr finden. Wenn der DP annähernd (paar ms Differenz ist normal) den selben Zeitstempel hat wie in der Tasmota Konsole, dann sollte das dauerhaft so sein und nicht abweichen.
Wenn man das Adapter Log Level ändert, ist es auch normal, dass der Adapter neu starte.

[AingTii]

ja richtig.
nützt aber nichts wenn es nach Stunden mehr und mehr verzögert.
daher denke ich werde ich evtl. Morgen früh vor der Arbeit den debug mode starten
und nach der Arbeit prüfen, da nach dem Neustart erst mal alles gut ist, geht es
wohl nicht anders.

[Jan1]

@AingTii
Da bin ich mal gespannt was dabei rauskommt.

[AingTii]

Moin,

weitere Erkenntnisse:

Anscheinend gibt es bei der Verarbeitung der Empfangenen Daten unterscheide im Debug zu Info Modus
beim Sonoff Adapter.

ich habe nicht die ganze Nacht sondern 3h den Sonoff Adapter auf Debug gestellt, geschaut und alles super
sychron, die daten sind kurz vor der Konsolen Ausgabe im Tasmota im ioBroker bereits aktuell.

Jetzt habe ich seit einer halben Stunde etwa wieder auf Info stehen und ich liege im ioBroker bereits
bei beiden Stromzählern etwa 2min zurück.
Woran sehe ich das, ganz einfach, der Datenpunkt Time im ioBroker liegt etwa 2min hinter dem von der Tasmota
Konsole und ebenfalls sind die Verbrauchs-Daten wieder veraltet.

Es scheint, als würden die Stromzähler im 2 Sekunden takt senden, iobroker packt es in einem Puffer der von hinten
aufgefüllt wird und vorn ruft er nur alle 2,1 sekunden ab und der Rest staut sich im Puffer
Auch wenn der Stromzähler nicht mehr sendet, blinken die Datenpunkte noch weiter.

Ich denke so wir verhindert, dass daten verloren gehen, ist für den Zweck allerdings eher nicht so toll,
da hier besser Daten fehlen dürften als verzögert darzustellen.

Gibt es dazu eien Lösung?

Danke

Gruß Jens

[FredF]

@AingTii
Evtl. hilft dir dieser Beitrag

[AingTii]

wäre evtl. eine Lösung, aber mal ehrlich alle 5min neue Daten zu bekommen... Puh...

Aber es gibt Neuigkeiten, man gibt ja nicht auf....

Da ich noch mit einem Entwickler der Treiber für Tasmota Programmiert in Kontakt bin
hatte ich ihn auch noch mal gefragt.

Er hatte den Hinweis MQTT Adpter und Sonoff laufen nicht parallel, er meinte dabei allerdings nur wenn
auf dem gleichen Port.

Ich habe es dennoch getestet und habe mal den MQTT Broker Adapter ausgeschaltet.
Siehe da seit ner Stunde keine Verzögerung.

Das ist für mich aber ziemlich suboptimal, da ich grad die Ansteuerung meines RF433-Arduino für die
Rollläden und 433Mhz Steckdosen von udp auf MQTT umgestellt hatte um nicht bei jeder neuen Steckdose
etc immer gleich den Sketch anzupassen, sondern die Befehlcodes direkt übermittel.

MQTT habe ich auf Port 1885
Sonoff auf Port 1884 laufen

Hat jemand ebenfalls beides laufen und auch die Probleme?

Danke
Gruß Jens

[crunchip]

@AingTii Den Versatz hatte ich auch festgestellt durch mein Grafana dashborad. Da ich den Stromzähler logge und einzelne Geräte.
Schalte ich einen Grösseren Verbraucher an, wird es sofort im Zähler Dashboard angezeigt. Aber der Verbrauch vom Gerät selber kam erst ca 20 min später im Dashboard.Hatte da immer einen Versatz von rund 20 Min.
Zudem kam bei mir noch dazu, das der state.power "überschrieben" wurde durch telePeriod.
War auch ewig auf der Suche, seitdem ich meine Vm von strech auf buster angehoben habe, läuft es wieder.
Vorher hatte ich auch sämtliche tasmota Versionen und auch den sonoff Adapter hoch und runter gespielt, ohne Erfolg.
Mein mqtt läuft auf 1883 und den sonoff hab ich auf 1885
Soviel ich weiss, sollten beide verwendeten Ports nicht unmittelbar nebeneinander liegen, führt unter Umständen zu Problemen