MQTT-Server flutet das LOG nach Update auf RC2

Hallo,

nach dem Update auf den RC2 flutet der MQTT-Server das LOG-File:

Kann das jemand bestätigen?
Gibt es Abhilfe?

Viele Grüße aus dem Unterallgäu
Harry

Diese Meldungen kann nur ein externes Gerät auslösen welches sich ständig neu verbindet :wink:

paresy

Hallo paresy,

danke für die schnelle Antwort und den Tipp. Aktuell weiß ich nicht welches Gerät das sein könnte.

Momentan experimentiere ich mit Node-RED, IOBroker und RedMatic.
Insgesamt habe ich 13 Tasmota-Geräte und 6 SHELLY-Module, aber die haben bisher über IPS und die Module von Kai prima funktioniert.

Den Raspberry Pi habe ich abgeschalten, aber das LOG wird noch immer geflutet.
Momentan haben die Einträge einen Zeitversatz von 35 Minuten!

Das Deaktivieren des Server-Sockets wird in der Console nicht angenommen und nicht gespeichert.
Debug-Anzeigen bekomme ich beim MQTT-Server zur Zeit auch keine.

Ich werde das System durchstarten und dann eine nach der anderen Steckdose rausnehmen.
Vielleicht deinstalliere ich das RedMatic-Addon in der CCU3 auch wieder?

Viele Grüße aus dem Unterallgäu
Harry

Schau mal in die Konsole von den einzelnen Tasmota Modulen, ob da ein Gerät so oft verbindet.
Wenn es das ist, Gerät neu starten.
Das hatte ich schon einmal so.

Bei den Shelly, einfach mal neu starten.

Dabei immer die IPS Console (Meldungen) im Blick haben.

Hallo Tom,

danke für den Hinweis.

Ich habe nun durchgestartet, aber das LOG wird immer noch geflutet.

Aber ich habe jetzt ein Stück Debug-Log vom MQTT-Server: dump.txt (109 KB)

Viele Grüße aus dem Unterallgäu
Harry

tele/gosund-5E3C7B/SENSOR
Da kommt was im Sekundentakt, schalt die mal ab, bzw schau mal was da eingestellt ist.
1 Sekunde ist ein bischen viel.

Das die Geräte die Verbindung nicht permanent halten ist bei MQTT ja so vorgesehen. Doof aber wenn da jede Sekunde ein Gerät etwas sendet und sich jedesmal neu verbindet.
Michael

harry, was hast du bei Telemetrieperiode in den Tasmota eingestellt ?

Hallo Tom,

Volltreffer - zwei meiner GOSUND-Steckdosen hatten da 10 eingestellt, alle anderen 300 (msec, Sekunden oder 1/10 sec ?). Da ich hier beim Logging bzw. der Telemetrie-Periode nie etwas konfiguriert habe, haben sich die Default-Werte vielleicht irgendwann geändert.

Danke für Eure Hilfe, im Moment kommen wieder „normale“ LOG-Einträge und ich kann auch wieder Debug-Daten beim MQTT-Server sehen. Danke.

Viele Grüße aus dem Unterallgäu
Harry

PS
Es sind Sekunden und laut Doku ist der Default-Wert 300.

Zu früh gefreut:
Heute wird das LOG wieder mit den selben Meldungen überflutet und Schaltbefehle kommen mit ca. 2 Minuten Verzögerung an.

Ich dachte die beiden GOSUND-Steckdosen waren die Übeltäter, aber vermutlich ist es der IOBroker als zweiter MQTT-Server. Ich schalte den mal wieder ab.

Irgendetwas muss sich mit dem RC2 aber auch am Logging geändert haben, denn bisher hatte ich diese LOG-Einträge noch gar nicht.

Oder nur ein blöder Zufall?

Viele Grüße aus dem Unterallgäu
Harry

Blöder Zufall. Die Änderungen bei den RC2 sind ja (wie du am Changelog siehst) nur noch sehr minimal.

paresy

Die LOG-Einträge kommen mit 6 Minuten Verspätung:

Der Server-Socket ist deaktiviert und dennoch die Meldungen:

fehler4.jpg

Nach dem Deaktivieren des MQTT-Servers sieht es so aus:

Ich habe nun nach dem Tipp von Tom alle GOSUND-Steckdosen neu gestartet.
Sie senden im 5 - 10 Sekundentakt ihre Energiewerte:

14:28:54 MQT: tele/gosund-2AD2CE/SENSOR = {"Time":"2019-08-28T14:28:54","ENERGY":{"TotalStartTime":"2019-08-18T12:25:40","Total":1.225,"Yesterday":0.123,"Today":0.076,"Power":6,"ApparentPower":57,"ReactivePower":56,"Factor":0.11,"Voltage":227,"Current":0.250}}

Löst dass die LOG-Einträge zum Serversocket des MQTT-Servers aus?

Am Wochenende habe ich die Tasmotas auf Firmware 6.6.0 aktualisiert - kann das der Auslöser sein?
Dann könnte ich zurück auf die 6.5.0.

Viele Grüße aus dem Unterallgäu
Harry

Moin Harry,
ich habe die Tasmota 6.6.0.3 drauf und keine Probleme.
Telemetrieperiode hab ich bei vielen auf 15 Sekunden, das macht hier keine Probleme,
Bei manchen Modulen brauch ich die kurze Abfrage, da ich damit KaiS Solarpumpe für den Pool schalte.
Ich vermute jetzt mal eher ein Gerät, welches ev. schlechten Wlan Empfang hat.
Ich würde mal bei allen Tasmota Modulen in de Konsole schauen, ob da auch Meldungen zu sehen sind.

Hallo Tom,

ich habe bei allen GOSUND mal in die Console geguckt, aber auf die Schnelle nichts Auffälliges gesehen. Da muss ich nochmals genauer schauen.

WLAN-Probleme hab ich eigentlich nicht, da auf jeder Etage ein AccessPoint ist. Vielleicht eine „schwache“ Steckdose - ich habe letzte Woche zwei GOSUNDs ergänzt.

Außer Tasmota 6.6.0 und RC2 habe ich am System nichts geändert, allerdings viel mit Node-RED und dem IOBroker auf einem Raspberry Pi experimentiert - momentan ist da aber alles abgeschaltet. Mal sehen was ich heute Abend noch finde.

Viele Grüße aus dem Unterallgäu
Harry

Die letzte hinzugefügte Steckdose zeigt dies in der Konsole:

19:00:46 MQT: Verbindungsversuch…
19:00:51 MQT: verbunden
19:00:51 MQT: tele/gosund-5A06D7/LWT = Online (beibehalten)
19:00:51 MQT: cmnd/gosund-5A06D7/POWER =
19:01:41 MQT: Verbindungsversuch…
19:01:54 MQT: verbunden
19:01:54 MQT: tele/gosund-5A06D7/LWT = Online (beibehalten)
19:01:54 MQT: cmnd/gosund-5A06D7/POWER =
19:02:00 MQT: tele/gosund-5A06D7/UPTIME = {„Time“:„2019-08-28T19:02:00“,„Uptime“:„0T04:14:05“}
19:02:51 MQT: Verbindungsversuch…
19:03:06 MQT: Verbindung fehlgeschlagen aufgrund von 10.1.1.11:1883, rc -4. Wiederversuch in 10 s
19:03:16 MQT: Verbindungsversuch…
19:03:31 MQT: Verbindung fehlgeschlagen aufgrund von 10.1.1.11:1883, rc -4. Wiederversuch in 10 s
19:03:42 MQT: Verbindungsversuch…
19:03:57 MQT: Verbindung fehlgeschlagen aufgrund von 10.1.1.11:1883, rc -4. Wiederversuch in 10 s
19:04:08 MQT: Verbindungsversuch…
19:04:15 MQT: verbunden
19:04:15 MQT: tele/gosund-5A06D7/LWT = Online (beibehalten)
19:04:15 MQT: cmnd/gosund-5A06D7/POWER =
19:04:55 MQT: Verbindungsversuch…
19:05:10 MQT: Verbindung fehlgeschlagen aufgrund von 10.1.1.11:1883, rc -4. Wiederversuch in 10 s
19:05:21 MQT: Verbindungsversuch…
19:05:21 MQT: verbunden
19:05:21 MQT: tele/gosund-5A06D7/LWT = Online (beibehalten)
19:05:21 MQT: cmnd/gosund-5A06D7/POWER =
19:06:22 MQT: Verbindungsversuch…
19:06:37 MQT: Verbindung fehlgeschlagen aufgrund von 10.1.1.11:1883, rc -4. Wiederversuch in 10 s
19:06:48 MQT: Verbindungsversuch…
19:06:56 MQT: verbunden
19:06:56 MQT: tele/gosund-5A06D7/LWT = Online (beibehalten)
19:06:56 MQT: cmnd/gosund-5A06D7/POWER =
19:07:11 MQT: tele/gosund-5A06D7/STATE = {„Time“:„2019-08-28T19:07:11“,„Uptime“:„0T04:19:16“,„Vcc“:3.222,„SleepMode“:„Dynamic“,„Sleep“:50,„LoadAvg“:19,„POWER“:„ON“,„Wifi“:{„AP“:1,„SSId“:„WLAN2“,„BSSId“:„8A:8A:20:8D:BE:39“,„Channel“:1,„RSSI“:48,„LinkCount“:1,„Downtime“:„0T00:00:04“}}
19:07:11 MQT: tele/gosund-5A06D7/SENSOR = {„Time“:„2019-08-28T19:07:11“,„ENERGY“:{„TotalStartTime“:„2019-08-22T13:37:01“,„Total“:0.566,„Yesterday“:0.095,„Today“:0.073,„Period“:0,„Power“:9,„ApparentPower“:64,„ReactivePower“:63,„Factor“:0.15,„Voltage“:221,„Current“:0.290}}
19:12:11 MQT: tele/gosund-5A06D7/STATE = {„Time“:„2019-08-28T19:12:11“,„Uptime“:„0T04:24:16“,„Vcc“:3.222,„SleepMode“:„Dynamic“,„Sleep“:50,„LoadAvg“:19,„POWER“:„ON“,„Wifi“:{„AP“:1,„SSId“:„WLAN2“,„BSSId“:„8A:8A:20:8D:BE:39“,„Channel“:1,„RSSI“:48,„LinkCount“:1,„Downtime“:„0T00:00:04“}}
19:12:11 MQT: tele/gosund-5A06D7/SENSOR = {„Time“:„2019-08-28T19:12:11“,„ENERGY“:{„TotalStartTime“:„2019-08-22T13:37:01“,„Total“:0.566,„Yesterday“:0.095,„Today“:0.074,„Period“:0,„Power“:7,„ApparentPower“:40,„ReactivePower“:39,„Factor“:0.16,„Voltage“:221,„Current“:0.179}}

Das könnte der Übeltäter sein - ich leg die mal tot.

Gruß Harry

Genau diese Meldungen in der Tasmota Konsole sind es, die auch die Meldungen in IPS erzeugen.
Da musst du suchen, was bei dem Gerät nicht passt.
Ev hilft auch da einfach nur ein Neustart des Gerätes.

Hatte ich bis jetzt einmal (bei einem Tasmota Modul, welches mit RSSI ~20 unterwegs war), und warte auf den Fehler.:smiley:
Bitte berichte weiter !

Ich habe diese Steckdose erst mal aus dem Verkehr gezogen - die LOG-Meldungen sind spürbar weniger geworden, aber noch nicht ganz weg. Ein Neustart hat nichts geändert.

Morgen suche ich nach den weiteren Übeltätern und werde die Problem-Steckdose woanders einstecken.

Eigentlich dachte ich mein WLAN wäre sehr gut und ausreichend, jedoch sind es mittlerweile 35 Geräte, davon 17 Tasmotas bzw. Shellies.

Viele Grüße aus dem Unterallgäu
Harry

Das Drama geht weiter, obwohl die vermeintliche Problem-Steckdose ausgesteckt ist. Weiterhin Störungsmeldungen und 5 Minuten Zeitversatz im LOG.

Irgendwie glaube ich nicht, das es nur an meinem WLAN liegen soll. Bislang hatte ich diese Probleme nicht.

Jetzt schau ich wieder alle Consolen-Meldungen der GOSUND durch.

Frustrierte Grüße aus dem Unterallgäu
Harry