Any Way to disable switching on / off Device via MQTT without disabling MQTT? #18164

LurchiStromberg · 2023-03-11T13:26:43Z

LurchiStromberg
Mar 11, 2023

Hello!
I have huge problems with Ghost Switiching with my Tasmota sOnOff POW Devices.

I spent a lot of Time Reading, switched on / off powerretain, buttonretain, switchretain etc.
nothing really worked

My Configuration is special and not as easy in connection with Mosquitto.
I have installed Mosquitto on an Ubuntu 20.04 LTS Server. persistence is set to false in the mosquitto.conf
I have a FHEM Server with MQTT1 (no MQTT Server) to switch and visualize the POW Devices
I use Smart Appliance Enabler to monitor the POWs Power at SMA Sunny Portal.

If I disable Smart Appliance Enabler, "Ghost Switching" is gone.
The Developer of SAE uses sOnOff POWs too, but no FHEM and has no Problems with Ghost Switching.
If I only use FHEM the Devices wont switch random....

It looks like the POWs "overrun" and Reset?

The POW Devices makes a short off / on switching and runs normal till next time same happens...

The log looks like this:

00:00:00.001 HDW: ESP8266EX
00:00:00.050 CFG: aus Flash geladen von F8, Anzahl 4310
00:00:00.055 SER: Set to 8E1 4800 bit/s
00:00:00.185 QPC: Count 1
00:00:00.200 NRG: Init driver 2
00:00:00.202 Projekt tasmota - POW2 Version 12.4.0(tasmota)-2_7_4_9(2023.02.24 15:37:29)
00:00:00.206 SER: Set to 8E1 4800 bit/s
00:00:00.209 SNS: Hardware Serial
00:00:00.753 WIF: verbinden mit AP1 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als POW2-4615...
00:00:03.551 WIF: verbunden
00:00:03.761 HTP: Web-Server aktiv bei POW2-4615 mit IP-Adresse 192.168.2.237
00:00:03.763 UPP: Multicast (wieder-)verbunden
20:56:17.583 MQT: Verbindungsversuch...
20:56:17.596 MQT: verbunden
20:56:17.599 MQT: tele/POW2/LWT = Online (beibehalten)

Does Tasmota have Problems with multiple Apps that are using MQTT, like in my Case SAE and FHEM?

Somebody any Idea?

At Mosquitto I have such Messages in log:

1678508009: Client Trockner already connected, closing old connection.
1678508009: New client connected from 192.168.2.231:56491 as Trockner (p2, c1, k30, u'DVES_USER').

At SAE it looks like this:

2023-03-07 21:16:39,863 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:62] F-27017101-000000000010-00: value=70.17 protocolHandlerValue=70.17 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-07 21:16:42,438 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000015-00: HTTP request: method=GET url=http://192.168.2.229/cm?cmnd=Status%208 data=null
2023-03-07 21:16:47,449 ERROR [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:114] F-27017101-000000000015-00: Error executing GET request.
java.net.SocketTimeoutException: Read timed out
	at java.base/java.net.SocketInputStream.socketRead0(Native Method)
	at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
	at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
	at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
	at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
	at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
	at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
	at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
	at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
	at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
	at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
	at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.executeLeaveOpen(HttpTransactionExecutor.java:109)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.execute(HttpTransactionExecutor.java:65)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getValue(HttpHandler.java:88)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getDoubleValue(HttpHandler.java:45)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.getValue(HttpElectricityMeter.java:289)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:285)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:280)
	at de.avanux.smartapplianceenabler.meter.PollEnergyMeter$1.runTask(PollEnergyMeter.java:78)
	at de.avanux.smartapplianceenabler.util.GuardedTimerTask.run(GuardedTimerTask.java:57)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)

But why the Heck the POW does a quick off / on switching after that all?
Is there a way to disable the Relais and just leave it on?
Its important for Devices like my Freezer for example. Its on 24/7
But sure no Solution for the Main Problem...
I dont think it is a ratain Message from MQTT because i disabled the db.......
It looks like the POW has an electric short for some milliseconds.
But I have 12 POWs and all do the same while connected to SAE and FHEM
If i stop SAE Service the POWs run as expected without any Problems...

Devil is a Squirrel ?

I would be happy if somebody could help me with my Problem

barbudor · 2023-03-11T13:39:18Z

barbudor
Mar 11, 2023

I have no knowledge of FHEM or SAE but using NodeRed and TDM in relation with my Tasmota and Mosquitto

I see:

1678508009: Client Trockner already connected, closing old connection. 1678508009: New client connected from 192.168.2.231:56491 as Trockner (p2, c1, k30, u'DVES_USER').

This is typical of multiple devices using the same Client identifier
In MQTT what is important is that each device is identified with a unique Client identifier
if Mosquitto sees another connection coming in from the same Client Id, it assume it's the same device and drop the previous connection in favor of the new one
User and Password are there only for authentication on the connection not for the MQTT . You can have all your devices using the same user and password as far as each device as it's own Client Id
This is why the default setting in Tasmota is to have part of the mac address in the client : DVES_%12X
It is fine to have DVES_USER as MqttUser but not as MqttClient

Now to understand what is happenning on the tasmota side, you should enabled WebLog 3 (or Syslog 3 is using a syslog server) to see where the commands are comming from.
That should enlight you on the reason for the ghost switching

For better readability of your logs, please enclose the complete log with 3 backticks like below

resulting in

sample
of logs
on multiple lines

3 replies

LurchiStromberg Mar 11, 2023
Author

This is why the default setting in Tasmota is to have part of the mac address in the client : DVES_%12X
It is fine to have DVES_USER as MqttUser but not as MqttClient

First of all i say "Thank you barbudor!" that you are willing to help me!

To get things clear:
Yes, my Devices use all the Default User "DVES_USER", but all Devices have Different client and topic, like "Wohnzimmer, Trockner, Waschmaschine" etc.
The Names are unique, even without using the MAC inside the Client ID. The IDs never used twice.

But I agree, it cant be right, if in mosquitto.log there are a lot of "disconnecting", "New client connected", "closing old connection".
Main Question is why that is happening.

So I understand right, that the same User at all Devices is not the Problem, right?

The Log is bad, right?

´´´
1678537677: New connection from 192.168.2.47:49913 on port 1883.
1678537677: New client connected from 192.168.2.47:49913 as KleinesEsszimmer (p2, c1, k30, u'DVES_USER').
1678537681: Client KleinesEsszimmer closed its connection.
1678537695: New connection from 192.168.2.47:50944 on port 1883.
1678537695: New client connected from 192.168.2.47:50944 as KleinesEsszimmer (p2, c1, k30, u'DVES_USER').
1678537834: Client KleinesEsszimmer has exceeded timeout, disconnecting.
1678539154: New connection from 192.168.2.47:51875 on port 1883.
1678539154: New client connected from 192.168.2.47:51875 as KleinesEsszimmer (p2, c1, k30, u'DVES_USER').
1678539352: Client KleinesEsszimmer has exceeded timeout, disconnecting.
1678539672: New connection from 192.168.2.47:50187 on port 1883.
1678539672: New client connected from 192.168.2.47:50187 as KleinesEsszimmer (p2, c1, k30, u'DVES_USER').
1678539778: Client KleinesEsszimmer has exceeded timeout, disconnecting.
1678540527: New connection from 192.168.2.229:58900 on port 1883.
1678540527: Client Schlafzimmer already connected, closing old connection.
1678540527: New client connected from 192.168.2.229:58900 as Schlafzimmer (p2, c1, k30, u'DVES_USER').
1678540590: New connection from 192.168.2.235:50476 on port 1883.
1678540590: Client Wasserbett already connected, closing old connection.
1678540590: New client connected from 192.168.2.235:50476 as Wasserbett (p2, c1, k30, u'DVES_USER').
1678541621: New connection from 192.168.2.47:52123 on port 1883.
1678541621: New client connected from 192.168.2.47:52123 as KleinesEsszimmer (p2, c1, k30, u'DVES_USER').
1678543132: Client KleinesEsszimmer has exceeded timeout, disconnecting.
´´´

barbudor Mar 11, 2023

OK so your config doesn't seem to be the problem
Although you seem to have 2 different behavior
KleinesEsszimmer suffer from timeouts
Wasserbett loss connection and reconnects

What are the logs of the tasmotas (in level 3)?

And your ticks for the logs are in the wrong direction 😊

barbudor Mar 11, 2023

If you have a German keyboard, I think it's this key

LurchiStromberg · 2023-03-11T16:24:09Z

LurchiStromberg
Mar 11, 2023
Author

I just tried to figure out if Problems are exisiting while stopping SAE Service again.
Past 2 hours no Ghost switches.

I will now switch to WebLog 3 in Console, but....... If that "Reset" or however you will call the "Reboot" or "off / on" Switch happens, weblog is gone in Console and starts like I posted at my 1st Post.

Just to tell...
I have "allow_anonymous true" set at mosquitto. Just to show, here are the configs for mosquitto and SAE.
Is there maybe the Problem?

Here is my mosquitto.conf

# Place your local configuration in /etc/mosquitto/conf.d/
#
# A full description of the configuration file is at
# /usr/share/doc/mosquitto/examples/mosquitto.conf.example

persistence false
log_dest file /var/log/mosquitto/mosquitto.log

include_dir /etc/mosquitto/conf.d

and here the smartapplianceenabler.conf which is placed in /etc/mosquitto/conf.d

listener 1883
allow_anonymous true

EDIT
Just to add the Info. I now looked at the mosquitto.log.

After stopping smartapplianceenabler Service, the disconnect, new connect, closing old connection msg are gone...

7 replies

LurchiStromberg Mar 11, 2023
Author

Ok, like Britney would say, oops, he did it again....
I am curious now, if somebody is able to tell whats the Issue

Weblog from POW:

:00:00.001 HDW: ESP8266EX
00:00:00.050 CFG: aus Flash geladen von F4, Anzahl 2094
00:00:00.055 SER: Set to 8E1 4800 bit/s
00:00:00.185 QPC: Count 1
00:00:00.199 ROT: Mode 1
00:00:00.200 NRG: Init driver 2
00:00:00.200 SRC: Restart
00:00:00.203 Projekt tasmota - WohnzimmerTechnik Version 12.4.0(tasmota)-2_7_4_9(2023.02.24 15:37:29)
00:00:00.207 SER: Set to 8E1 4800 bit/s
00:00:00.210 SNS: Hardware Serial
00:00:00.414 WIF: Verbindungsversuch...
00:00:00.750 WIF: verbinden mit AP2 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Wohnzimmer...
00:00:01.200 CSE: Overload
00:00:01.515 WIF: Verbindungsversuch...
00:00:02.504 WIF: Verbindungsversuch...
00:00:03.520 WIF: verbunden
00:00:03.755 WIF: DNS resolved '192.168.2.7' (192.168.2.7) in 0 ms
00:00:03.788 HTP: Web-Server aktiv bei Wohnzimmer mit IP-Adresse 192.168.2.234
00:00:03.790 UPP: Multicast (wieder-)verbunden
00:00:04.171 WIF: DNS resolved 'pool.ntp.org' (185.207.105.38) in 8 ms
00:00:04.205 RTC: UTC 2023-03-11T19:40:28, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
20:40:28.000 RTC: Synced by NTP
20:40:28.581 UPP: Multicast deaktiviert
20:40:28.582 MQT: Verbindungsversuch...
20:40:28.584 WIF: DNS resolved '192.168.2.3' (192.168.2.3) in 0 ms
20:40:28.597 MQT: verbunden
20:40:28.600 MQT: tele/WohnzimmerTechnik/LWT = Online (beibehalten)
20:40:28.603 MQT: cmnd/WohnzimmerTechnik/POWER = 
20:40:28.605 MQT: abonniere cmnd/WohnzimmerTechnik/#
20:40:28.610 MQT: abonniere cmnd/sonoffs/#
20:40:28.613 MQT: abonniere cmnd/WohnzimmerTechnik_fb/#
20:40:28.620 MQT: tele/WohnzimmerTechnik/INFO1 = {"Info1":{"Module":"Sonoff Pow R2","Version":"12.4.0(tasmota)","FallbackTopic":"cmnd/WohnzimmerTechnik_fb/","GroupTopic":"cmnd/sonoffs/"}}
20:40:28.625 MQT: tele/WohnzimmerTechnik/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"Wohnzimmer","IPAddress":"192.168.2.234"}}
20:40:28.631 MQT: tele/WohnzimmerTechnik/INFO3 = {"Info3":{"RestartReason":{"Exception":4,"Reason":"Software Watchdog","EPC":["40003b53","00000000","00000000"],"EXCVADDR":"00000000","DEPC":"00000000"},"BootCount":92}}
20:40:28.634 MQT: stat/WohnzimmerTechnik/RESULT = {"POWER":"ON"}
20:40:28.637 MQT: stat/WohnzimmerTechnik/POWER = ON
20:40:29.754 UPP: Multicast (wieder-)verbunden
20:40:30.195 QPC: Reset
20:40:32.163 APP: Anzahl Startvorgänge 92
20:40:32.171 MQT: tele/WohnzimmerTechnik/MARGINS = {"MARGINS":{"PowerDelta":138}}
20:40:32.180 MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:40:32","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85541,"Yesterday":1.67161,"Today":1.21841,"Power":138,"ApparentPower":162,"ReactivePower":84,"Factor":0.85,"Voltage":230,"Current":0.705}}
20:40:32.325 CFG: in Flash gespeichert an FB, Anzahl 2095, Bytes 4096
20:40:33.169 MQT: tele/WohnzimmerTechnik/STATE = {"Time":"2023-03-11T20:40:33","Uptime":"0T00:00:11","UptimeSec":11,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-29,"LinkCount":1,"Downtime":"0T00:00:05"}}
20:40:33.184 MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:40:33","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85541,"Yesterday":1.67161,"Today":1.21841,"Period":0,"Power":130,"ApparentPower":165,"ReactivePower":102,"Factor":0.79,"Voltage":230,"Current":0.718}}
20:40:42.507 WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:2616
20:40:43.139 HTP: WeMo-Setup from 192.168.2.109
20:40:54.590 WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:12311
20:40:55.112 HTP: WeMo-Setup from 192.168.2.109
20:41:15.955 HTP: Befehl
20:41:15.960 SRC: WebCommand from 192.168.2.3
20:41:15.963 CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 1, Pld 8, Data '8'
20:41:15.973 MQT: stat/WohnzimmerTechnik/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:41:15","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85706,"Yesterday":1.67161,"Today":1.22006,"Power":177,"ApparentPower":227,"ReactivePower":142,"Factor":0.78,"Voltage":231,"Current":0.985}}}
20:41:20.287 WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:36555
20:41:20.516 HTP: WeMo-Setup from 192.168.2.109
20:41:33.163 MQT: tele/WohnzimmerTechnik/STATE = {"Time":"2023-03-11T20:41:33","Uptime":"0T00:01:11","UptimeSec":71,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-29,"LinkCount":1,"Downtime":"0T00:00:05"}}
20:41:33.175 MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:41:33","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85789,"Yesterday":1.67161,"Today":1.22089,"Period":2,"Power":175,"ApparentPower":222,"ReactivePower":137,"Factor":0.79,"Voltage":230,"Current":0.966}}
20:42:33.201 MQT: tele/WohnzimmerTechnik/STATE = {"Time":"2023-03-11T20:42:33","Uptime":"0T00:02:11","UptimeSec":131,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-29,"LinkCount":1,"Downtime":"0T00:00:05"}}
20:42:33.213 MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:42:33","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.86035,"Yesterday":1.67161,"Today":1.22335,"Period":2,"Power":139,"ApparentPower":179,"ReactivePower":113,"Factor":0.78,"Voltage":231,"Current":0.776}}
20:42:47.656 HTP: WeMo basic event from 192.168.2.52
20:42:50.297 WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:54068
20:42:50.335 HTP: WeMo-Setup from 192.168.2.109

Log from Mosquitto:

1678559121: New connection from 192.168.2.47:57355 on port 1883.
1678559121: New client connected from 192.168.2.47:57355 as KleinesEsszimmer (p2, c1, k30, u'DVES_USER').
1678563627: New connection from 192.168.2.71:49930 on port 1883.
1678563627: Client Kamera1 already connected, closing old connection.
1678563627: New client connected from 192.168.2.71:49930 as Kamera1 (p2, c1, k30, u'DVES_USER').
1678563629: New connection from 192.168.2.234:52330 on port 1883.
1678563629: Client WohnzimmerTechnik already connected, closing old connection.
1678563629: New client connected from 192.168.2.234:52330 as WohnzimmerTechnik (p2, c1, k30, u'DVES_USER').
1678563703: Client MqttClient closed its connection.
1678563703: Client F-27017101-000000000003-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client F-27017101-000000000001-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client F-27017101-000000000003-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000001-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000014-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client F-27017101-000000000014-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000006-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client F-27017101-000000000006-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000005-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client F-27017101-000000000005-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000015-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client SaeController-0 closed its connection.
1678563703: Client F-27017101-000000000010-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client SempController-0 closed its connection.
1678563703: Client F-27017101-000000000015-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000009-00-HttpElectricityMeter-0 closed its connection.
1678563703: Client F-27017101-000000000010-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000009-00-AlwaysOnSwitch-0 closed its connection.
1678563703: Client F-27017101-000000000003-00-Appliance-0 closed its connection.
1678563703: Client F-27017101-000000000001-00-Appliance-0 closed its connection.
1678563703: Client F-27017101-000000000014-00-Appliance-0 closed its connection.
1678563703: Client F-27017101-000000000006-00-Appliance-0 closed its connection.
1678563703: Client F-27017101-000000000005-00-Appliance-0 closed its connection.
1678563703: Client F-27017101-000000000015-00-Appliance-0 closed its connection.
1678563703: Client F-27017101-000000000010-00-Appliance-0 closed its connection.
1678563703: Client ApplianceManager-0 closed its connection.
1678563703: Client F-27017101-000000000009-00-Appliance-0 closed its connection.

And here the Log from Syslog Server:

11.03.2023 20:40:28 [6982] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: Verbindungsversuch...
11.03.2023 20:40:28 [6985] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: Web-Server aktiv bei Wohnzimmer mit IP-Adresse 192.168.2.234
11.03.2023 20:40:28 [6977] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SNS: Hardware Serial
11.03.2023 20:40:28 [6983] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: verbunden
11.03.2023 20:40:28 [6981] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: Verbindungsversuch...
11.03.2023 20:40:28 [6980] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CSE: Overload
11.03.2023 20:40:28 [6979] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: verbinden mit AP2 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Wohnzimmer...
11.03.2023 20:40:28 [6978] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: Verbindungsversuch...
11.03.2023 20:40:28 [6986] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-UPP: Multicast (wieder-)verbunden
11.03.2023 20:40:28 [6970] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SER: Set to 8E1 4800 bit/s
11.03.2023 20:40:28 [6984] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: DNS resolved ''192.168.2.7'' (192.168.2.7) in 0 ms
11.03.2023 20:40:28 [6969] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CFG: aus Flash geladen von F4, Anzahl 2094
11.03.2023 20:40:28 [6971] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-QPC: Count 1
11.03.2023 20:40:28 [6976] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SER: Set to 8E1 4800 bit/s
11.03.2023 20:40:28 [6972] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-ROT: Mode 1
11.03.2023 20:40:28 [6973] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-NRG: Init driver 2
11.03.2023 20:40:28 [6974] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SRC: Restart
11.03.2023 20:40:28 [6975] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-Projekt tasmota - WohnzimmerTechnik Version 12.4.0(tasmota)-2_7_4_9(2023.02.24 15:37:29)
11.03.2023 20:40:28 [6968] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HDW: ESP8266EX
11.03.2023 20:40:29 [6990] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-RTC: Synced by NTP
11.03.2023 20:40:29 [6995] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/LWT = Online (beibehalten)
11.03.2023 20:40:29 [7000] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/INFO1 = {"Info1":{"Module":"Sonoff Pow R2","Version":"12.4.0(tasmota)","FallbackTopic":"cmnd/WohnzimmerTechnik_fb/","GroupTopic":"cmnd/sonoffs/"}}
11.03.2023 20:40:29 [6999] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: abonniere cmnd/WohnzimmerTechnik_fb/#
11.03.2023 20:40:29 [6998] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: abonniere cmnd/sonoffs/#
11.03.2023 20:40:29 [6997] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: abonniere cmnd/WohnzimmerTechnik/#
11.03.2023 20:40:29 [6996] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: cmnd/WohnzimmerTechnik/POWER = 
11.03.2023 20:40:29 [6994] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: verbunden
11.03.2023 20:40:29 [6993] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: DNS resolved ''192.168.2.3'' (192.168.2.3) in 0 ms
11.03.2023 20:40:29 [6991] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-UPP: Multicast deaktiviert
11.03.2023 20:40:29 [6989] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-RTC: UTC 2023-03-11T19:40:28, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
11.03.2023 20:40:29 [6988] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: DNS resolved ''pool.ntp.org'' (185.207.105.38) in 8 ms
11.03.2023 20:40:29 [6987] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> 
11.03.2023 20:40:29 [6992] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: Verbindungsversuch...
11.03.2023 20:40:30 [7003] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: stat/WohnzimmerTechnik/RESULT = {"POWER":"ON"}
11.03.2023 20:40:30 [7007] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-UPP: Multicast (wieder-)verbunden
11.03.2023 20:40:30 [7008] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-QPC: Reset
11.03.2023 20:40:30 [7002] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/INFO3 = {"Info3":{"RestartReason":{"Exception":4,"Reason":"Software Watchdog","EPC":["40003b53","00000000","00000000"],"EXCVADDR":"00000000","DEPC":"00000000"},"BootCount":92}}
11.03.2023 20:40:30 [7001] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"Wohnzimmer","IPAddress":"192.168.2.234"}}
11.03.2023 20:40:30 [7004] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: stat/WohnzimmerTechnik/POWER = ON
11.03.2023 20:40:32 [7009] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-APP: Anzahl StartvorgÃ¤nge 92
11.03.2023 20:40:33 [7012] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/MARGINS = {"MARGINS":{"PowerDelta":138}}
11.03.2023 20:40:33 [7013] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:40:32","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85541,"Yesterday":1.67161,"Today":1.21841,"Power":138,"ApparentPower":162,"ReactivePower":84,"Factor":0.85,"Voltage":230,"Current":0.705}}
11.03.2023 20:40:33 [7014] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CFG: in Flash gespeichert an FB, Anzahl 2095, Bytes 4096
11.03.2023 20:40:34 [7015] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/STATE = {"Time":"2023-03-11T20:40:33","Uptime":"0T00:00:11","UptimeSec":11,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-29,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:40:34 [7016] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:40:33","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85541,"Yesterday":1.67161,"Today":1.21841,"Period":0,"Power":130,"ApparentPower":165,"ReactivePower":102,"Factor":0.79,"Voltage":230,"Current":0.718}}
11.03.2023 20:40:43 [7026] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:2616
11.03.2023 20:40:43 [7027] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: WeMo-Setup from 192.168.2.109
11.03.2023 20:40:55 [7050] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:12311
11.03.2023 20:40:55 [7051] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: WeMo-Setup from 192.168.2.109
11.03.2023 20:41:16 [7083] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:41:16 [7082] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:41:16 [7081] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: Befehl
11.03.2023 20:41:16 [7084] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: stat/WohnzimmerTechnik/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:41:15","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85706,"Yesterday":1.67161,"Today":1.22006,"Power":177,"ApparentPower":227,"ReactivePower":142,"Factor":0.78,"Voltage":231,"Current":0.985}}}
11.03.2023 20:41:21 [7108] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WMO: WeMo Type 2, Antwort gesendet to 192.168.2.109:36555
11.03.2023 20:41:21 [7109] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: WeMo-Setup from 192.168.2.109
11.03.2023 20:41:34 [7116] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/STATE = {"Time":"2023-03-11T20:41:33","Uptime":"0T00:01:11","UptimeSec":71,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-29,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:41:34 [7117] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:41:33","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85789,"Yesterday":1.67161,"Today":1.22089,"Period":2,"Power":175,"ApparentPower":222,"ReactivePower":137,"Factor":0.79,"Voltage":230,"Current":0.966}}

Whats the Meaning of ESP-CSE: ?
To me it looks like the POW is dying for some Milliseconds, enought to let the Power cut and Devices switch off an restart.

Any Ideas why that only happens, if SAE Service is running?

barbudor Mar 11, 2023

ESP-CSE: Overload

It's going to explode 🙄
CSE prefix is from the driver of the CS7766 power measurement chip in Sonoff S31 and Sonoff Pow R2/R3
Apparently it warns when power is over 5.5kW
Is it true or not in your case, I can't tell

"RestartReason":{"Exception":4,"Reason":"Software Watchdog",

Definitively somehting went wrong and the ESP got stuck into a dead loop which triggered the watchdog

What would be interresting are the logs before that happens

LurchiStromberg Mar 11, 2023
Author

Well...
All happend at the POW at

20:40:28.000 RTC: Synced by NTP

This was the 1st life Signal in Weblog (weblog 3) after the Reset / off on switching...

I can show you the syslog from 1 Minute before, whole with all Devices but....

11.03.2023 20:39:07 [6875] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-HTP: WeMo basic event from 192.168.2.94
11.03.2023 20:39:12 [6876] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-MQT: tele/Trockner/STATE = {"Time":"2023-03-11T20:39:10","Uptime":"0T08:42:44","UptimeSec":31364,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":2,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:12 [6877] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-MQT: tele/Trockner/SENSOR = {"Time":"2023-03-11T20:39:10","ENERGY":{"TotalStartTime":"2023-01-01T00:41:24","Total":33.29997,"Yesterday":0.00000,"Today":1.78297,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":235,"Current":0.000}}
11.03.2023 20:39:15 [6879] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:15 [6880] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:15 [6881] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-MQT: stat/Pelletofen/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:15","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":26.35654,"Yesterday":0.44313,"Today":0.30854,"Power":10,"ApparentPower":29,"ReactivePower":27,"Factor":0.34,"Voltage":234,"Current":0.124}}}
11.03.2023 20:39:15 [6878] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-HTP: Befehl
11.03.2023 20:39:16 [6887] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:16 [6896] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:16 [6895] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:16 [6894] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: Befehl
11.03.2023 20:39:16 [6893] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: stat/Wasserbett/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:15","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50531,"Yesterday":3.26633,"Today":2.90831,"Power":211,"ApparentPower":211,"ReactivePower":0,"Factor":1.00,"Voltage":220,"Current":0.961}}}
11.03.2023 20:39:16 [6892] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:16 [6897] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: stat/WohnzimmerTechnik/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:16","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85241,"Yesterday":1.67161,"Today":1.21541,"Power":167,"ApparentPower":225,"ReactivePower":150,"Factor":0.74,"Voltage":231,"Current":0.973}}}
11.03.2023 20:39:16 [6891] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:16 [6888] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:16 [6886] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-HTP: Befehl
11.03.2023 20:39:16 [6885] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: stat/Schlafzimmer/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:14","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":50.31135,"Yesterday":0.91335,"Today":0.16435,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}}
11.03.2023 20:39:16 [6884] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:16 [6883] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:16 [6882] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-HTP: Befehl
11.03.2023 20:39:16 [6889] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-MQT: stat/Waschmaschine/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:15","ENERGY":{"TotalStartTime":"2023-01-01T00:41:45","Total":42.93845,"Yesterday":0.00015,"Today":1.47145,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":231,"Current":0.000}}}
11.03.2023 20:39:16 [6890] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-HTP: Befehl
11.03.2023 20:39:17 [6900] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:17 [6905] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: stat/Spuelmaschine/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:16","ENERGY":{"TotalStartTime":"2023-01-01T00:43:32","Total":28.44000,"Yesterday":0.00000,"Today":0.00000,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":235,"Current":0.000}}}
11.03.2023 20:39:17 [6904] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:39:17 [6903] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:17 [6901] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-MQT: stat/Trockner/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:39:15","ENERGY":{"TotalStartTime":"2023-01-01T00:41:24","Total":33.29997,"Yesterday":0.00000,"Today":1.78297,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":235,"Current":0.000}}}
11.03.2023 20:39:17 [6899] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:39:17 [6898] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-HTP: Befehl
11.03.2023 20:39:17 [6902] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-HTP: Befehl
11.03.2023 20:39:18 [6906] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/STATE = {"Time":"2023-03-11T20:39:16","Uptime":"0T06:22:52","UptimeSec":22972,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":2,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":80,"Signal":-60,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:18 [6907] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:39:16","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50539,"Yesterday":3.26633,"Today":2.90838,"Period":5,"Power":212,"ApparentPower":212,"ReactivePower":0,"Factor":1.00,"Voltage":220,"Current":0.961}}
11.03.2023 20:39:19 [6908] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/STATE = {"Time":"2023-03-11T20:39:17","Uptime":"0T23:43:01","UptimeSec":85381,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":3,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":64,"Signal":-68,"LinkCount":1,"Downtime":"0T00:00:03"}}
11.03.2023 20:39:19 [6909] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/SENSOR = {"Time":"2023-03-11T20:39:17","ENERGY":{"TotalStartTime":"2023-01-01T00:43:32","Total":28.44000,"Yesterday":0.00000,"Today":0.00000,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":235,"Current":0.000}}
11.03.2023 20:39:20 [6910] From:Gosund4-4454.fritz.box (192.168.2.132) Fac:0 Sev:0 Msg >>> Gosund4-4454 ESP-MQT: tele/Gosund4/STATE = {"Time":"2023-03-11T20:39:18","Uptime":"0T20:05:52","UptimeSec":72352,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":3,"POWER":"OFF","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":72,"Signal":-64,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:20 [6911] From:Gosund4-4454.fritz.box (192.168.2.132) Fac:0 Sev:0 Msg >>> Gosund4-4454 ESP-MQT: tele/Gosund4/SENSOR = {"Time":"2023-03-11T20:39:18","ENERGY":{"TotalStartTime":"2023-03-10T19:43:05","Total":0.016,"Yesterday":0.001,"Today":0.016,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}
11.03.2023 20:39:33 [6912] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-MQT: tele/Waschmaschine/STATE = {"Time":"2023-03-11T20:39:31","Uptime":"0T15:17:31","UptimeSec":55051,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":3,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":48,"Signal":-76,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:33 [6913] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-MQT: tele/Waschmaschine/SENSOR = {"Time":"2023-03-11T20:39:31","ENERGY":{"TotalStartTime":"2023-01-01T00:41:45","Total":42.93845,"Yesterday":0.00015,"Today":1.47145,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":232,"Current":0.000}}
11.03.2023 20:39:37 [6914] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-MQT: tele/Pelletofen/STATE = {"Time":"2023-03-11T20:39:36","Uptime":"0T14:42:11","UptimeSec":52931,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":2,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":84,"Signal":-58,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:37 [6915] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-MQT: tele/Pelletofen/SENSOR = {"Time":"2023-03-11T20:39:36","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":26.35660,"Yesterday":0.44313,"Today":0.30860,"Period":0,"Power":10,"ApparentPower":28,"ReactivePower":27,"Factor":0.34,"Voltage":234,"Current":0.121}}
11.03.2023 20:39:40 [6916] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/STATE = {"Time":"2023-03-11T20:39:38","Uptime":"0T06:24:17","UptimeSec":23057,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":2,"POWER":"OFF","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-43,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:40 [6917] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/SENSOR = {"Time":"2023-03-11T20:39:38","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":50.31135,"Yesterday":0.91335,"Today":0.16435,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}
11.03.2023 20:39:48 [6918] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-HTP: WeMo basic event from 192.168.2.94
11.03.2023 20:39:52 [6919] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: WeMo basic event from 192.168.2.94
11.03.2023 20:39:57 [6920] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-HTP: WeMo basic event from 192.168.2.94
11.03.2023 20:39:58 [6921] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/STATE = {"Time":"2023-03-11T20:39:57","Uptime":"1T00:43:41","UptimeSec":89021,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":3,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-29,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:39:58 [6922] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-MQT: tele/WohnzimmerTechnik/SENSOR = {"Time":"2023-03-11T20:39:57","ENERGY":{"TotalStartTime":"2023-01-01T00:39:38","Total":105.85432,"Yesterday":1.67161,"Today":1.21732,"Period":3,"Power":166,"ApparentPower":235,"ReactivePower":167,"Factor":0.71,"Voltage":230,"Current":1.026}}
11.03.2023 20:39:59 [6923] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-HTP: WeMo basic event from 192.168.2.85
11.03.2023 20:40:01 [6924] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":-215}}
11.03.2023 20:40:01 [6925] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:39:59","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50790,"Yesterday":3.26633,"Today":2.91091,"Power":6,"ApparentPower":15,"ReactivePower":14,"Factor":0.42,"Voltage":219,"Current":0.069}}
11.03.2023 20:40:05 [6926] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":-3}}
11.03.2023 20:40:05 [6927] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:40:03","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50792,"Yesterday":3.26633,"Today":2.91092,"Power":3,"ApparentPower":15,"ReactivePower":15,"Factor":0.20,"Voltage":219,"Current":0.069}}
11.03.2023 20:40:07 [6928] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":89}}
11.03.2023 20:40:07 [6929] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:40:05","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50793,"Yesterday":3.26633,"Today":2.91094,"Power":93,"ApparentPower":144,"ReactivePower":110,"Factor":0.64,"Voltage":220,"Current":0.655}}
11.03.2023 20:40:08 [6930] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":129}}
11.03.2023 20:40:08 [6931] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:40:06","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50800,"Yesterday":3.26633,"Today":2.91099,"Power":143,"ApparentPower":143,"ReactivePower":0,"Factor":1.00,"Voltage":219,"Current":0.655}}
11.03.2023 20:40:09 [6933] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:40:07","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50803,"Yesterday":3.26633,"Today":2.91103,"Power":77,"ApparentPower":209,"ReactivePower":195,"Factor":0.37,"Voltage":219,"Current":0.957}}
11.03.2023 20:40:09 [6932] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":-145}}
11.03.2023 20:40:12 [6937] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:40:10","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50813,"Yesterday":3.26633,"Today":2.91114,"Power":209,"ApparentPower":209,"ReactivePower":0,"Factor":1.00,"Voltage":219,"Current":0.954}}
11.03.2023 20:40:12 [6936] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":145}}
11.03.2023 20:40:12 [6934] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-MQT: tele/Trockner/STATE = {"Time":"2023-03-11T20:40:10","Uptime":"0T08:43:44","UptimeSec":31424,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":2,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:40:12 [6935] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-MQT: tele/Trockner/SENSOR = {"Time":"2023-03-11T20:40:10","ENERGY":{"TotalStartTime":"2023-01-01T00:41:24","Total":33.29997,"Yesterday":0.00000,"Today":1.78297,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":233,"Current":0.000}}
11.03.2023 20:40:15 [6938] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-HTP: Befehl
11.03.2023 20:40:15 [6939] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:40:15 [6940] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:40:15 [6941] From:Pelletofen.fritz.box (192.168.2.237) Fac:0 Sev:0 Msg >>> Pelletofen-4615 ESP-MQT: stat/Pelletofen/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:40:14","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":26.35671,"Yesterday":0.44313,"Today":0.30871,"Power":9,"ApparentPower":28,"ReactivePower":27,"Factor":0.32,"Voltage":233,"Current":0.120}}}
11.03.2023 20:40:16 [6946] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-HTP: Befehl
11.03.2023 20:40:16 [6953] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: stat/Wasserbett/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:40:15","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50845,"Yesterday":3.26633,"Today":2.91145,"Power":211,"ApparentPower":211,"ReactivePower":0,"Factor":1.00,"Voltage":220,"Current":0.959}}}
11.03.2023 20:40:16 [6952] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:40:16 [6951] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:40:16 [6950] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-HTP: Befehl
11.03.2023 20:40:16 [6949] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-MQT: stat/Waschmaschine/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:40:15","ENERGY":{"TotalStartTime":"2023-01-01T00:41:45","Total":42.93845,"Yesterday":0.00015,"Today":1.47145,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":231,"Current":0.000}}}
11.03.2023 20:40:16 [6947] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:40:16 [6945] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: stat/Schlafzimmer/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:40:14","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":50.31135,"Yesterday":0.91335,"Today":0.16435,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}}
11.03.2023 20:40:16 [6944] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:40:16 [6943] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:40:16 [6942] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-HTP: Befehl
11.03.2023 20:40:16 [6948] From:Waschmaschine.fritz.box (192.168.2.232) Fac:0 Sev:0 Msg >>> Waschmaschine ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:40:17 [6959] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:40:17 [6955] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-SRC: WebCommand from 192.168.2.3
11.03.2023 20:40:17 [6961] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: stat/Spuelmaschine/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:40:16","ENERGY":{"TotalStartTime":"2023-01-01T00:43:32","Total":28.44000,"Yesterday":0.00000,"Today":0.00000,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":234,"Current":0.000}}}
11.03.2023 20:40:17 [6960] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:40:17 [6954] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-HTP: Befehl
11.03.2023 20:40:17 [6956] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
11.03.2023 20:40:17 [6957] From:Trockner.fritz.box (192.168.2.231) Fac:0 Sev:0 Msg >>> Trockner ESP-MQT: stat/Trockner/STATUS8 = {"StatusSNS":{"Time":"2023-03-11T20:40:15","ENERGY":{"TotalStartTime":"2023-01-01T00:41:24","Total":33.29997,"Yesterday":0.00000,"Today":1.78297,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":235,"Current":0.000}}}
11.03.2023 20:40:17 [6958] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-HTP: Befehl
11.03.2023 20:40:18 [6962] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/STATE = {"Time":"2023-03-11T20:40:16","Uptime":"0T06:23:52","UptimeSec":23032,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":2,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":80,"Signal":-60,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:40:18 [6963] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-11T20:40:16","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50851,"Yesterday":3.26633,"Today":2.91151,"Period":3,"Power":211,"ApparentPower":211,"ReactivePower":0,"Factor":1.00,"Voltage":220,"Current":0.959}}
11.03.2023 20:40:19 [6964] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/STATE = {"Time":"2023-03-11T20:40:17","Uptime":"0T23:44:01","UptimeSec":85441,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":3,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":64,"Signal":-68,"LinkCount":1,"Downtime":"0T00:00:03"}}
11.03.2023 20:40:19 [6965] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/SENSOR = {"Time":"2023-03-11T20:40:17","ENERGY":{"TotalStartTime":"2023-01-01T00:43:32","Total":28.44000,"Yesterday":0.00000,"Today":0.00000,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":234,"Current":0.000}}
11.03.2023 20:40:20 [6966] From:Gosund4-4454.fritz.box (192.168.2.132) Fac:0 Sev:0 Msg >>> Gosund4-4454 ESP-MQT: tele/Gosund4/STATE = {"Time":"2023-03-11T20:40:18","Uptime":"0T20:06:52","UptimeSec":72412,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":3,"POWER":"OFF","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":66,"Signal":-67,"LinkCount":1,"Downtime":"0T00:00:05"}}
11.03.2023 20:40:20 [6967] From:Gosund4-4454.fritz.box (192.168.2.132) Fac:0 Sev:0 Msg >>> Gosund4-4454 ESP-MQT: tele/Gosund4/SENSOR = {"Time":"2023-03-11T20:40:18","ENERGY":{"TotalStartTime":"2023-03-10T19:43:05","Total":0.016,"Yesterday":0.001,"Today":0.016,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}
11.03.2023 20:40:28 [6982] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: Verbindungsversuch...
11.03.2023 20:40:28 [6985] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HTP: Web-Server aktiv bei Wohnzimmer mit IP-Adresse 192.168.2.234
11.03.2023 20:40:28 [6977] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SNS: Hardware Serial
11.03.2023 20:40:28 [6983] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: verbunden
11.03.2023 20:40:28 [6981] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: Verbindungsversuch...
11.03.2023 20:40:28 [6980] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CSE: Overload
11.03.2023 20:40:28 [6979] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: verbinden mit AP2 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Wohnzimmer...
11.03.2023 20:40:28 [6978] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: Verbindungsversuch...
11.03.2023 20:40:28 [6986] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-UPP: Multicast (wieder-)verbunden
11.03.2023 20:40:28 [6970] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SER: Set to 8E1 4800 bit/s
11.03.2023 20:40:28 [6984] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-WIF: DNS resolved ''192.168.2.7'' (192.168.2.7) in 0 ms
11.03.2023 20:40:28 [6969] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-CFG: aus Flash geladen von F4, Anzahl 2094
11.03.2023 20:40:28 [6971] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-QPC: Count 1
11.03.2023 20:40:28 [6976] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SER: Set to 8E1 4800 bit/s
11.03.2023 20:40:28 [6972] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-ROT: Mode 1
11.03.2023 20:40:28 [6973] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-NRG: Init driver 2
11.03.2023 20:40:28 [6974] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-SRC: Restart
11.03.2023 20:40:28 [6975] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-Projekt tasmota - WohnzimmerTechnik Version 12.4.0(tasmota)-2_7_4_9(2023.02.24 15:37:29)
11.03.2023 20:40:28 [6968] From:Wohnzimmer.fritz.box (192.168.2.234) Fac:0 Sev:0 Msg >>> Wohnzimmer ESP-HDW: ESP8266EX

Thats all the syslog tells...... :(
Problem in this case is, that I am unable to have syslog or else from SAE Service...
Here the SAE.log from this Timeframe:

2023-03-11 20:40:15,648 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:67] F-27017101-000000000010-00: value=70.02 protocolHandlerValue=70.02 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-11 20:40:15,648 DEBUG [Timer-0] d.a.s.m.PollPowerMeter [PollPowerMeter.java:117] F-27017101-000000000010-00: Calculating diffEnergy from power: diffEnergy=0.00117kWh currentPower=70W previousPowerTimestamp=2023-03-11T20:39:15.592098 currentPowerTimestamp=2023-03-11T20:40:15.593042 diffTime=60000ms
2023-03-11 20:40:15,906 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000015-00: HTTP request: method=GET url=http://192.168.2.229/cm?cmnd=Status%208 data=null
2023-03-11 20:40:15,972 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000015-00: Response code is 200
2023-03-11 20:40:15,980 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000015-00: url=http://192.168.2.229/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-11 20:40:15,980 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000015-00: Response: {"StatusSNS":{"Time":"2023-03-11T20:40:14","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":50.31135,"Yesterday":0.91335,"Today":0.16435,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}}
2023-03-11 20:40:15,981 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:67] F-27017101-000000000015-00: value=50.31135 protocolHandlerValue=50.31135 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-11 20:40:15,981 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000015-00: Calculating power from energy: power=0 currentEnergyCounter=50.31135 previousEnergyCounter=50.31135 diffEnergy=0 diffTime=60001
2023-03-11 20:40:16,062 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000005-00: HTTP request: method=GET url=http://192.168.2.232/cm?cmnd=Status%208 data=null
2023-03-11 20:40:16,113 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000005-00: Response code is 200
2023-03-11 20:40:16,124 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000005-00: url=http://192.168.2.232/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-11 20:40:16,124 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000005-00: Response: {"StatusSNS":{"Time":"2023-03-11T20:40:15","ENERGY":{"TotalStartTime":"2023-01-01T00:41:45","Total":42.93845,"Yesterday":0.00015,"Today":1.47145,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":231,"Current":0.000}}}
2023-03-11 20:40:16,124 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:67] F-27017101-000000000005-00: value=42.93845 protocolHandlerValue=42.93845 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-11 20:40:16,124 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000005-00: Calculating power from energy: power=0 currentEnergyCounter=42.93845 previousEnergyCounter=42.93845 diffEnergy=0 diffTime=60000
2023-03-11 20:40:16,282 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000006-00: HTTP request: method=GET url=http://192.168.2.231/cm?cmnd=Status%208 data=null
2023-03-11 20:40:16,338 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000006-00: Response code is 200
2023-03-11 20:40:16,361 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000006-00: url=http://192.168.2.231/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-11 20:40:16,362 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000006-00: Response: {"StatusSNS":{"Time":"2023-03-11T20:40:15","ENERGY":{"TotalStartTime":"2023-01-01T00:41:24","Total":33.29997,"Yesterday":0.00000,"Today":1.78297,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":235,"Current":0.000}}}
2023-03-11 20:40:16,362 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:67] F-27017101-000000000006-00: value=33.29997 protocolHandlerValue=33.29997 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-11 20:40:16,362 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000006-00: Calculating power from energy: power=0 currentEnergyCounter=33.29997 previousEnergyCounter=33.29997 diffEnergy=0 diffTime=60000
2023-03-11 20:40:16,362 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000014-00: HTTP request: method=GET url=http://192.168.2.240/cm?cmnd=Status%208 data=null
2023-03-11 20:40:16,427 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000014-00: Response code is 200
2023-03-11 20:40:16,440 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000014-00: url=http://192.168.2.240/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-11 20:40:16,440 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000014-00: Response: {"StatusSNS":{"Time":"2023-03-11T20:40:16","ENERGY":{"TotalStartTime":"2023-01-01T00:43:32","Total":28.44000,"Yesterday":0.00000,"Today":0.00000,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":234,"Current":0.000}}}
2023-03-11 20:40:16,440 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:67] F-27017101-000000000014-00: value=28.44 protocolHandlerValue=28.44 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-11 20:40:16,440 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000014-00: Calculating power from energy: power=0 currentEnergyCounter=28.44 previousEnergyCounter=28.44 diffEnergy=0 diffTime=60002
2023-03-11 20:40:16,440 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000001-00: HTTP request: method=GET url=http://192.168.2.235/cm?cmnd=Status%208 data=null
2023-03-11 20:40:16,506 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000001-00: Response code is 200
2023-03-11 20:40:16,516 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000001-00: url=http://192.168.2.235/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-11 20:40:16,516 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000001-00: Response: {"StatusSNS":{"Time":"2023-03-11T20:40:15","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":205.50845,"Yesterday":3.26633,"Today":2.91145,"Power":211,"ApparentPower":211,"ReactivePower":0,"Factor":1.00,"Voltage":220,"Current":0.959}}}
2023-03-11 20:40:16,517 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:67] F-27017101-000000000001-00: value=205.50845 protocolHandlerValue=205.50845 valueExtractionRegex=null extractedValue=null factorToValue=null
2023-03-11 20:40:16,517 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000001-00: Calculating power from energy: power=188 currentEnergyCounter=205.50845 previousEnergyCounter=205.50531 diffEnergy=0.00314 diffTime=60011
2023-03-11 20:40:16,523 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000003-00: HTTP request: method=GET url=http://192.168.2.234/cm?cmnd=Status%208 data=null
2023-03-11 20:40:21,538 ERROR [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:114] F-27017101-000000000003-00: Error executing GET request.
java.net.SocketTimeoutException: Read timed out
	at java.base/java.net.SocketInputStream.socketRead0(Native Method)
	at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
	at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
	at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
	at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
	at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
	at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
	at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
	at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
	at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
	at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
	at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.executeLeaveOpen(HttpTransactionExecutor.java:109)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.execute(HttpTransactionExecutor.java:65)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getValue(HttpHandler.java:97)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getDoubleValue(HttpHandler.java:50)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.getValue(HttpElectricityMeter.java:262)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:258)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:253)
	at de.avanux.smartapplianceenabler.meter.PollEnergyMeter$1.runTask(PollEnergyMeter.java:78)
	at de.avanux.smartapplianceenabler.util.GuardedTimerTask.run(GuardedTimerTask.java:57)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
2023-03-11 20:40:21,538 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000003-00: url=http://192.168.2.234/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-11 20:40:21,538 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000003-00: Calculating power from energy: power=171 currentEnergyCounter=105.85241 previousEnergyCounter=105.84955 diffEnergy=0.00286 diffTime=59999
2023-03-11 20:40:21,538 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000005-00: Current Queue is empty
2023-03-11 20:40:21,539 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000001-00: Current Queue is empty
2023-03-11 20:40:21,539 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000010-00: Current Queue is empty
2023-03-11 20:40:21,539 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000006-00: Current Queue is empty
2023-03-11 20:40:21,539 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000014-00: Current Queue is empty
2023-03-11 20:40:21,539 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000003-00: Current Queue is empty
2023-03-11 20:40:21,539 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000015-00: Current Queue is empty
2023-03-11 20:40:45,592 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000009-00: Current Queue is empty
2023-03-11 20:40:51,538 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000005-00: Current Queue is empty
2023-03-11 20:40:51,540 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000010-00: Current Queue is empty
2023-03-11 20:40:51,540 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000015-00: Current Queue is empty
2023-03-11 20:40:51,540 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000001-00: Current Queue is empty
2023-03-11 20:40:51,540 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000003-00: Current Queue is empty
2023-03-11 20:40:51,540 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000014-00: Current Queue is empty
2023-03-11 20:40:51,541 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000006-00: Current Queue is empty
2023-03-11 20:40:55,494 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:89] Device info/status/planning requested.
2023-03-11 20:40:55,495 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000009-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,495 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000010-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=8000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,496 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000015-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,496 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000005-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,496 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000006-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,496 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000014-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,496 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000001-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,497 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000003-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-11 20:40:55,497 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000009-00: Reporting device status from control
2023-03-11 20:40:55,497 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000009-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-11 20:40:55,497 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000009-00: Reporting power info from meter.
2023-03-11 20:40:55,497 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000009-00: PowerInfo{averagePower=10, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,498 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000010-00: Reporting device status from control
2023-03-11 20:40:55,498 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000010-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-11 20:40:55,498 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000010-00: Reporting power info from meter.
2023-03-11 20:40:55,498 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000010-00: PowerInfo{averagePower=70, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,499 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000015-00: Reporting device status from control
2023-03-11 20:40:55,499 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000015-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-11 20:40:55,499 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000015-00: Reporting power info from meter.
2023-03-11 20:40:55,499 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000015-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,499 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000005-00: Reporting device status from control
2023-03-11 20:40:55,499 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000005-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000005-00: Reporting power info from meter.
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000005-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000006-00: Reporting device status from control
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000006-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000006-00: Reporting power info from meter.
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000006-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,500 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000014-00: Reporting device status from control
2023-03-11 20:40:55,501 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000014-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-11 20:40:55,501 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000014-00: Reporting power info from meter.
2023-03-11 20:40:55,501 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000014-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,501 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000001-00: Reporting device status from control
2023-03-11 20:40:55,501 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000001-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-11 20:40:55,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000001-00: Reporting power info from meter.
2023-03-11 20:40:55,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000001-00: PowerInfo{averagePower=188, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:40:55,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000003-00: Reporting device status from control
2023-03-11 20:40:55,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000003-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-11 20:40:55,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000003-00: Reporting power info from meter.
2023-03-11 20:40:55,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000003-00: PowerInfo{averagePower=171, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-11 20:41:12,845 DEBUG [http-nio-8080-exec-3] d.a.s.w.SaeController [SaeController.java:891] Received request for ApplianceStatus

Any Idea?

All i can say is, if i stop SAE all is fine....

EDIT:

I have another log not, fun fact, FHEM service is stopped......
So it cant be a fight between FHEM and SAE

I would say, hey ok, so one POW has a Hardware Demage and that causes the random off / on switching but......
I have 12 POWs and every POW, IF ITS CONNECTED TO SAE, have the same Issue...

Here the log without FHEM:

12.03.2023 03:51:21 [29565] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-QPC: Count 1
12.03.2023 03:51:21 [29567] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-ROT: Mode 1
12.03.2023 03:51:21 [29568] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-NRG: Init driver 2
12.03.2023 03:51:21 [29566] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-CFG: CR 405/699, Busy 0
12.03.2023 03:51:21 [29563] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-CFG: aus Flash geladen von F6, Anzahl 709
12.03.2023 03:51:21 [29562] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-HDW: ESP8266EX
12.03.2023 03:51:21 [29564] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SER: Set to 8E1 4800 bit/s
12.03.2023 03:51:22 [29576] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: Verbindungsversuch...
12.03.2023 03:51:22 [29581] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> 
12.03.2023 03:51:22 [29580] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-UPP: Multicast (wieder-)verbunden
12.03.2023 03:51:22 [29579] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-HTP: Web-Server aktiv bei Schlafzimmer-4823 mit IP-Adresse 192.168.2.229
12.03.2023 03:51:22 [29577] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: verbunden
12.03.2023 03:51:22 [29575] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: Verbindungsversuch...
12.03.2023 03:51:22 [29574] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: verbinden mit AP1 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Schlafzimmer-4823...
12.03.2023 03:51:22 [29573] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: Verbindungsversuch...
12.03.2023 03:51:22 [29572] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SNS: Hardware Serial
12.03.2023 03:51:22 [29571] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SER: Set to 8E1 4800 bit/s
12.03.2023 03:51:22 [29570] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-Projekt tasmota - Schlafzimmer Version 12.4.0(tasmota)-2_7_4_9(2023.02.24 15:37:29)
12.03.2023 03:51:22 [29569] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SRC: Restart
12.03.2023 03:51:22 [29578] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: DNS resolved ''192.168.2.7'' (192.168.2.7) in 0 ms
12.03.2023 03:51:23 [29593] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: abonniere cmnd/Schlafzimmer_fb/#
12.03.2023 03:51:23 [29597] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: stat/Schlafzimmer/RESULT = {"POWER":"OFF"}
12.03.2023 03:51:23 [29596] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/INFO3 = {"Info3":{"RestartReason":{"Exception":4,"Reason":"Software Watchdog","EPC":["40003b4d","00000000","00000000"],"EXCVADDR":"00000000","DEPC":"00000000"},"BootCount":143}}
12.03.2023 03:51:23 [29590] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: cmnd/Schlafzimmer/POWER = 
12.03.2023 03:51:23 [29594] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/INFO1 = {"Info1":{"Module":"Sonoff Pow R2","Version":"12.4.0(tasmota)","FallbackTopic":"cmnd/Schlafzimmer_fb/","GroupTopic":"cmnd/tasmotas/"}}
12.03.2023 03:51:23 [29598] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: stat/Schlafzimmer/POWER = OFF
12.03.2023 03:51:23 [29592] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: abonniere cmnd/tasmotas/#
12.03.2023 03:51:23 [29591] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: abonniere cmnd/Schlafzimmer/#
12.03.2023 03:51:23 [29582] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: DNS resolved ''pool.ntp.org'' (195.201.20.16) in 14 ms
12.03.2023 03:51:23 [29589] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/LWT = Online (beibehalten)
12.03.2023 03:51:23 [29595] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"Schlafzimmer-4823","IPAddress":"192.168.2.229"}}
12.03.2023 03:51:23 [29583] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-RTC: UTC 2023-03-12T02:51:21, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
12.03.2023 03:51:23 [29584] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-RTC: Synced by NTP
12.03.2023 03:51:23 [29585] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-UPP: Multicast deaktiviert
12.03.2023 03:51:23 [29586] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: Verbindungsversuch...
12.03.2023 03:51:23 [29587] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-WIF: DNS resolved ''192.168.2.3'' (192.168.2.3) in 0 ms
12.03.2023 03:51:23 [29588] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: verbunden
12.03.2023 03:51:24 [29600] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-QPC: Reset
12.03.2023 03:51:24 [29599] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-UPP: Multicast (wieder-)verbunden
12.03.2023 03:51:26 [29601] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-APP: Anzahl StartvorgÃ¤nge 143
12.03.2023 03:51:27 [29602] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-CFG: in Flash gespeichert an F5, Anzahl 710, Bytes 4096
12.03.2023 03:51:28 [29603] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/STATE = {"Time":"2023-03-12T03:51:26","Uptime":"0T00:00:11","UptimeSec":11,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"OFF","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-44,"LinkCount":1,"Downtime":"0T00:00:05"}}
12.03.2023 03:51:28 [29604] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/SENSOR = {"Time":"2023-03-12T03:51:26","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":51.03035,"Yesterday":0.41255,"Today":0.47135,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}
12.03.2023 03:52:00 [29616] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-HTP: WeMo basic event from 192.168.2.52
12.03.2023 03:52:09 [29621] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-HTP: Befehl
12.03.2023 03:52:09 [29624] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: stat/Schlafzimmer/STATUS8 = {"StatusSNS":{"Time":"2023-03-12T03:52:07","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":51.03035,"Yesterday":0.41255,"Today":0.47135,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}}
12.03.2023 03:52:09 [29623] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-CMD: Grp 0, Cmd ''STATUS'', Idx 1, Len 1, Pld 8, Data ''8''
12.03.2023 03:52:09 [29622] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-SRC: WebCommand from 192.168.2.3
12.03.2023 03:52:28 [29655] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/SENSOR = {"Time":"2023-03-12T03:52:26","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":51.03035,"Yesterday":0.41255,"Today":0.47135,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}
12.03.2023 03:52:28 [29654] From:Schlafzimmer.fritz.box (192.168.2.229) Fac:0 Sev:0 Msg >>> Schlafzimmer-4823 ESP-MQT: tele/Schlafzimmer/STATE = {"Time":"2023-03-12T03:52:26","Uptime":"0T00:01:11","UptimeSec":71,"Heap":24,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"OFF","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":100,"Signal":-44,"LinkCount":1,"Downtime":"0T00:00:05"}}

barbudor Mar 12, 2023

From the Java crash log it looks like Tasmota crashes while/before (presumably) SAE is polling it through web requests on STATUS8
If SAE is supposed to support MQTT, why is it polling through HTTP instead of reading the SENSOR topic from tasmota ?
Any way to remove htat HTTP polling in favor of MQTT ?
Intense http polling is generally considered as a bad behavior for Tasmota devices

Do you have only 1 device that is crashing this way ?
If multiple, are they all the same model ?

LurchiStromberg Mar 12, 2023
Author

Hello!
Well, SAE is an App that sends Meter Data to an Internet Portal https://www.sunnyportal.com/. I have an Inverter from SMA and this is the
Homepage where Data is evaluatet. Thats the Reason, why SAE polls Data to HTTP i guess.
This is the Homepage of the Project: https://github.com/camueller/SmartApplianceEnabler

Yes, like I said, I have 12 POW Devices. 10 POW2 and 2 POW1 from Itead.
Its not only one Device, all have the same Faults IF THEY CONNECTED TO SAE.

Past night I only let run 3 of them and 2 had same off / on / Reset Issue.

Here the crashlog from SAE for my Wasserbett: (POW Gen.1)

2023-03-12 07:27:55,351 TRACE [MQTT Call: SaeController-0] d.a.s.m.MqttClient [MqttClient.java:318] SaeController: Message received: topic=sae/F-27017101-000000000015-00/Meter payload={"energy":0.0,"power":0,"time":"2023-03-12T07:27:55.307326","type":"MeterMessage"}
2023-03-12 07:27:55,351 TRACE [MQTT Call: F-27017101-000000000015-00-Appliance-0] d.a.s.m.MqttClient [MqttClient.java:318] F-27017101-000000000015-00-MQTT-Appliance: Message received: topic=sae/F-27017101-000000000015-00/Meter payload={"energy":0.0,"power":0,"time":"2023-03-12T07:27:55.307326","type":"MeterMessage"}
2023-03-12 07:27:55,351 TRACE [MQTT Call: SempController-0] d.a.s.m.MqttClient [MqttClient.java:318] SempController: Message received: topic=sae/F-27017101-000000000015-00/Meter payload={"energy":0.0,"power":0,"time":"2023-03-12T07:27:55.307326","type":"MeterMessage"}
2023-03-12 07:27:55,403 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000001-00: Executing timer task name=PollEnergyMeter id=234983856
2023-03-12 07:27:55,403 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000001-00: HTTP request: method=GET url=http://192.168.2.235/cm?cmnd=Status%208 data=null
2023-03-12 07:27:56,502 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] SempController: Publish message: topic=sae/Event/SempDevice2EM payload={"time":"2023-03-12T07:27:56.502564","type":"MqttMessage"} retained=false
2023-03-12 07:27:56,502 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:89] Device info/status/planning requested.
2023-03-12 07:27:56,503 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000009-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 07:27:56,503 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000010-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=8000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 07:27:56,504 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000015-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 07:27:56,504 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000001-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 07:27:56,504 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000009-00: Reporting device status from control
2023-03-12 07:27:56,504 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000009-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-12 07:27:56,504 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000009-00: Reporting power info from meter.
2023-03-12 07:27:56,505 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000009-00: PowerInfo{averagePower=10, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 07:27:56,505 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000010-00: Reporting device status from control
2023-03-12 07:27:56,505 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000010-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-12 07:27:56,505 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000010-00: Reporting power info from meter.
2023-03-12 07:27:56,505 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000010-00: PowerInfo{averagePower=789, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 07:27:56,505 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000015-00: Reporting device status from control
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000015-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000015-00: Reporting power info from meter.
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000015-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000001-00: Reporting device status from control
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000001-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000001-00: Reporting power info from meter.
2023-03-12 07:27:56,506 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000001-00: PowerInfo{averagePower=99, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 07:28:00,418 ERROR [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:114] F-27017101-000000000001-00: Error executing GET request.
java.net.SocketTimeoutException: Read timed out
	at java.base/java.net.SocketInputStream.socketRead0(Native Method)
	at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
	at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
	at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
	at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
	at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
	at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
	at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
	at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
	at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
	at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
	at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.executeLeaveOpen(HttpTransactionExecutor.java:109)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.execute(HttpTransactionExecutor.java:65)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getValue(HttpHandler.java:97)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getDoubleValue(HttpHandler.java:50)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.getValue(HttpElectricityMeter.java:262)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:258)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:253)
	at de.avanux.smartapplianceenabler.meter.PollEnergyMeter$1.runTask(PollEnergyMeter.java:78)
	at de.avanux.smartapplianceenabler.util.GuardedTimerTask.run(GuardedTimerTask.java:57)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
2023-03-12 07:28:00,419 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000001-00: url=http://192.168.2.235/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-12 07:28:00,419 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000001-00: Calculating power from energy: power=99 currentEnergyCounter=206.5405 previousEnergyCounter=206.53885 diffEnergy=0.00165 diffTime=60000
2023-03-12 07:28:00,419 TRACE [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:127] F-27017101-000000000001-00: energy=0.12894000000000005kWh totalEnergy=null startEnergyCounter=206.41156 currentEnergyCounter=206.5405 started=true
2023-03-12 07:28:00,419 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000001-00: Executing timer task name=UpdateActiveTimeframeInterval id=1881075585
2023-03-12 07:28:00,419 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000001-00: Current Queue is empty
2023-03-12 07:28:00,419 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000001-00-MQTT-HttpElectricityMeter: Publish message: topic=sae/F-27017101-000000000001-00/Meter payload={"energy":0.12894000000000005,"power":99,"time":"2023-03-12T07:27:55.403614","type":"MeterMessage"} retained=false

And here 2 Crashlogs from a POW2:

2023-03-12 09:12:54,910 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000009-00: HTTP request: method=GET url=http://192.168.2.237/cm?cmnd=Status%208 data=null
2023-03-12 09:12:56,008 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] SempController: Publish message: topic=sae/Event/SempDevice2EM payload={"time":"2023-03-12T09:12:56.008201","type":"MqttMessage"} retained=false
2023-03-12 09:12:56,008 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:89] Device info/status/planning requested.
2023-03-12 09:12:56,008 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000009-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 09:12:56,008 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000010-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=8000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 09:12:56,009 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000015-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 09:12:56,009 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000001-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 09:12:56,009 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000009-00: Reporting device status from control
2023-03-12 09:12:56,009 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000009-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-12 09:12:56,009 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000009-00: Reporting power info from meter.
2023-03-12 09:12:56,009 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000009-00: PowerInfo{averagePower=8, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000010-00: Reporting device status from control
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000010-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000010-00: Reporting power info from meter.
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000010-00: PowerInfo{averagePower=771, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000015-00: Reporting device status from control
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000015-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000015-00: Reporting power info from meter.
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000015-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000001-00: Reporting device status from control
2023-03-12 09:12:56,010 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000001-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-12 09:12:56,011 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000001-00: Reporting power info from meter.
2023-03-12 09:12:56,011 DEBUG [http-nio-8080-exec-1] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000001-00: PowerInfo{averagePower=244, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 09:12:59,921 ERROR [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:114] F-27017101-000000000009-00: Error executing GET request.
java.net.SocketTimeoutException: Read timed out
	at java.base/java.net.SocketInputStream.socketRead0(Native Method)
	at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
	at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
	at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
	at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
	at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
	at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
	at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
	at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
	at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
	at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
	at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.executeLeaveOpen(HttpTransactionExecutor.java:109)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.execute(HttpTransactionExecutor.java:65)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getValue(HttpHandler.java:97)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getDoubleValue(HttpHandler.java:50)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.getValue(HttpElectricityMeter.java:262)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:258)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:253)
	at de.avanux.smartapplianceenabler.meter.PollEnergyMeter$1.runTask(PollEnergyMeter.java:78)
	at de.avanux.smartapplianceenabler.util.GuardedTimerTask.run(GuardedTimerTask.java:57)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
2023-03-12 09:12:59,921 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000009-00: url=http://192.168.2.237/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-12 09:12:59,921 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000009-00: Calculating power from energy: power=8 currentEnergyCounter=26.59971 previousEnergyCounter=26.59957 diffEnergy=0.00014 diffTime=59999
2023-03-12 09:12:59,921 TRACE [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:127] F-27017101-000000000009-00: energy=0.03399000000000285kWh totalEnergy=null startEnergyCounter=26.56572 currentEnergyCounter=26.59971 started=true
2023-03-12 09:12:59,922 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000009-00: Executing timer task name=UpdateActiveTimeframeInterval id=1131092302
2023-03-12 09:12:59,922 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000009-00: Current Queue is empty
2023-03-12 09:12:59,922 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000009-00-MQTT-HttpElectricityMeter: Publish message: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.03399000000000285,"power":8,"time":"2023-03-12T09:12:54.910624","type":"MeterMessage"} retained=false
2023-03-12 09:12:59,922 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000010-00: Executing timer task name=PollPowerMeter id=1851207411
2023-03-12 09:12:59,922 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000010-00: HTTP request: method=GET url=http://192.168.2.70/json data=null
2023-03-12 09:12:59,923 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000009-00-MQTT-Appliance: Publish message: topic=sae/F-27017101-000000000009-00/TimeframeIntervalQueue payload={"entries":[],"time":"2023-03-12T09:12:59.922167","type":"TimeframeIntervalQueueMessage"} retained=false
2023-03-12 09:12:59,923 TRACE [MQTT Call: SaeController-0] d.a.s.m.MqttClient [MqttClient.java:318] SaeController: Message received: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.03399000000000285,"power":8,"time":"2023-03-12T09:12:54.910624","type":"MeterMessage"}
2023-03-12 09:12:59,923 TRACE [MQTT Call: SempController-0] d.a.s.m.MqttClient [MqttClient.java:318] SempController: Message received: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.03399000000000285,"power":8,"time":"2023-03-12T09:12:54.910624","type":"MeterMessage"}
2023-03-12 09:12:59,923 TRACE [MQTT Call: F-27017101-000000000009-00-Appliance-0] d.a.s.m.MqttClient [MqttClient.java:318] F-27017101-000000000009-00-MQTT-Appliance: Message received: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.03399000000000285,"power":8,"time":"2023-03-12T09:12:54.910624","type":"MeterMessage"}
2023-03-12 09:12:59,941 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000010-00: Response code is 200
2023-03-12 09:12:59,982 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000010-00: url=http://192.168.2.70/json method=GET data=null path=$.Sensors[0].TaskValues[2].Value
2023-03-12 09:12:59,982 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000010-00: Response: {"System":{

and

2023-03-12 12:33:30,374 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000009-00: Executing timer task name=UpdateActiveTimeframeInterval id=1131092302
2023-03-12 12:33:30,374 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000009-00: Current Queue is empty
2023-03-12 12:33:30,374 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000009-00-MQTT-Appliance: Publish message: topic=sae/F-27017101-000000000009-00/TimeframeIntervalQueue payload={"entries":[],"time":"2023-03-12T12:33:30.374614","type":"TimeframeIntervalQueueMessage"} retained=false
2023-03-12 12:33:30,524 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000015-00: Executing timer task name=UpdateActiveTimeframeInterval id=1061176928
2023-03-12 12:33:30,524 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000015-00: Current Queue is empty
2023-03-12 12:33:30,524 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000015-00-MQTT-Appliance: Publish message: topic=sae/F-27017101-000000000015-00/TimeframeIntervalQueue payload={"entries":[],"time":"2023-03-12T12:33:30.524263","type":"TimeframeIntervalQueueMessage"} retained=false
2023-03-12 12:33:30,542 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000010-00: Executing timer task name=UpdateActiveTimeframeInterval id=1899206342
2023-03-12 12:33:30,542 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000010-00: Current Queue is empty
2023-03-12 12:33:30,543 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000001-00: Executing timer task name=UpdateActiveTimeframeInterval id=1881075585
2023-03-12 12:33:30,543 DEBUG [Timer-0] d.a.s.s.TimeframeIntervalHandler [TimeframeIntervalHandler.java:224] F-27017101-000000000001-00: Current Queue is empty
2023-03-12 12:33:30,543 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000010-00-MQTT-Appliance: Publish message: topic=sae/F-27017101-000000000010-00/TimeframeIntervalQueue payload={"entries":[],"time":"2023-03-12T12:33:30.542963","type":"TimeframeIntervalQueueMessage"} retained=false
2023-03-12 12:33:30,544 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000001-00-MQTT-Appliance: Publish message: topic=sae/F-27017101-000000000001-00/TimeframeIntervalQueue payload={"entries":[],"time":"2023-03-12T12:33:30.543306","type":"TimeframeIntervalQueueMessage"} retained=false
2023-03-12 12:33:54,953 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000009-00: Executing timer task name=PollEnergyMeter id=1373032337
2023-03-12 12:33:54,953 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000009-00: HTTP request: method=GET url=http://192.168.2.237/cm?cmnd=Status%208 data=null
2023-03-12 12:33:56,354 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] SempController: Publish message: topic=sae/Event/SempDevice2EM payload={"time":"2023-03-12T12:33:56.354406","type":"MqttMessage"} retained=false
2023-03-12 12:33:56,354 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:89] Device info/status/planning requested.
2023-03-12 12:33:56,354 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000009-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=3000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 12:33:56,355 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000010-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=8000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 12:33:56,355 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000015-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 12:33:56,355 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:162] F-27017101-000000000001-00: DeviceInfo{characteristics=Characteristics{maxPowerConsumption=1000, minPowerConsumption=null, minOnTime=null, maxOnTime=null, minOffTime=null, maxOffTime=null}, capabilities=Capabilities{currentPowerMethod=Measurement, absoluteTimestamps=false, interruptionsAllowed=false, optionalEnergy=false}}
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000009-00: Reporting device status from control
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000009-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000009-00: Reporting power info from meter.
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000009-00: PowerInfo{averagePower=8, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000010-00: Reporting device status from control
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000010-00: DeviceStatus{eMSignalsAccepted=false, status=Off, errorCode=null}
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000010-00: Reporting power info from meter.
2023-03-12 12:33:56,356 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000010-00: PowerInfo{averagePower=1614, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000015-00: Reporting device status from control
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000015-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000015-00: Reporting power info from meter.
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000015-00: PowerInfo{averagePower=0, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:335] F-27017101-000000000001-00: Reporting device status from control
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:344] F-27017101-000000000001-00: DeviceStatus{eMSignalsAccepted=false, status=On, errorCode=null}
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:350] F-27017101-000000000001-00: Reporting power info from meter.
2023-03-12 12:33:56,357 DEBUG [http-nio-8080-exec-3] d.a.s.s.w.SempController [SempController.java:364] F-27017101-000000000001-00: PowerInfo{averagePower=2, minPower=null, maxPower=null, timestamp=0, averagingInterval=60}
2023-03-12 12:33:59,964 ERROR [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:114] F-27017101-000000000009-00: Error executing GET request.
java.net.SocketTimeoutException: Read timed out
	at java.base/java.net.SocketInputStream.socketRead0(Native Method)
	at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
	at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
	at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
	at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
	at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
	at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
	at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
	at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
	at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
	at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
	at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.executeLeaveOpen(HttpTransactionExecutor.java:109)
	at de.avanux.smartapplianceenabler.http.HttpTransactionExecutor.execute(HttpTransactionExecutor.java:65)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getValue(HttpHandler.java:97)
	at de.avanux.smartapplianceenabler.http.HttpHandler.getDoubleValue(HttpHandler.java:50)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.getValue(HttpElectricityMeter.java:262)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:258)
	at de.avanux.smartapplianceenabler.meter.HttpElectricityMeter.pollEnergy(HttpElectricityMeter.java:253)
	at de.avanux.smartapplianceenabler.meter.PollEnergyMeter$1.runTask(PollEnergyMeter.java:78)
	at de.avanux.smartapplianceenabler.util.GuardedTimerTask.run(GuardedTimerTask.java:57)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
2023-03-12 12:33:59,964 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000009-00: url=http://192.168.2.237/cm?cmnd=Status%208 method=GET data=null path=$.StatusSNS.ENERGY.Total
2023-03-12 12:33:59,964 DEBUG [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:105] F-27017101-000000000009-00: Calculating power from energy: power=8 currentEnergyCounter=26.62801 previousEnergyCounter=26.62787 diffEnergy=0.00014 diffTime=60000
2023-03-12 12:33:59,964 TRACE [Timer-0] d.a.s.m.PollEnergyMeter [PollEnergyMeter.java:127] F-27017101-000000000009-00: energy=0.062290000000000845kWh totalEnergy=null startEnergyCounter=26.56572 currentEnergyCounter=26.62801 started=true
2023-03-12 12:33:59,964 TRACE [Timer-0] d.a.s.u.GuardedTimerTask [GuardedTimerTask.java:54] F-27017101-000000000010-00: Executing timer task name=PollPowerMeter id=1851207411
2023-03-12 12:33:59,964 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:78] F-27017101-000000000010-00: HTTP request: method=GET url=http://192.168.2.70/json data=null
2023-03-12 12:33:59,964 TRACE [pool-3-thread-1] d.a.s.m.MqttClient [MqttClient.java:265] F-27017101-000000000009-00-MQTT-HttpElectricityMeter: Publish message: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.062290000000000845,"power":8,"time":"2023-03-12T12:33:54.953779","type":"MeterMessage"} retained=false
2023-03-12 12:33:59,965 TRACE [MQTT Call: SaeController-0] d.a.s.m.MqttClient [MqttClient.java:318] SaeController: Message received: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.062290000000000845,"power":8,"time":"2023-03-12T12:33:54.953779","type":"MeterMessage"}
2023-03-12 12:33:59,965 TRACE [MQTT Call: F-27017101-000000000009-00-Appliance-0] d.a.s.m.MqttClient [MqttClient.java:318] F-27017101-000000000009-00-MQTT-Appliance: Message received: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.062290000000000845,"power":8,"time":"2023-03-12T12:33:54.953779","type":"MeterMessage"}
2023-03-12 12:33:59,965 TRACE [MQTT Call: SempController-0] d.a.s.m.MqttClient [MqttClient.java:318] SempController: Message received: topic=sae/F-27017101-000000000009-00/Meter payload={"energy":0.062290000000000845,"power":8,"time":"2023-03-12T12:33:54.953779","type":"MeterMessage"}
2023-03-12 12:33:59,984 DEBUG [Timer-0] d.a.s.h.HttpTransactionExecutor [HttpTransactionExecutor.java:150] F-27017101-000000000010-00: Response code is 200
2023-03-12 12:34:00,020 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:99] F-27017101-000000000010-00: url=http://192.168.2.70/json method=GET data=null path=$.Sensors[0].TaskValues[2].Value
2023-03-12 12:34:00,020 DEBUG [Timer-0] d.a.s.h.HttpHandler [HttpHandler.java:101] F-27017101-000000000010-00: Response: {"System":{

As far i can see the Error Msg from SAE are all same on differen POW Devices.
For testing i only connected 3 of my 12 POWs.

I am getting desperate....

Here the Syslog from Syslog Server from my POW Gen.1 Device "Wasserbett"

12.03.2023 07:28:02 [43179] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: Verbindungsversuch...
12.03.2023 07:28:02 [43184] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: DNS resolved ''192.168.2.7'' (192.168.2.7) in 0 ms
12.03.2023 07:28:02 [43183] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-UPP: Multicast (wieder-)verbunden
12.03.2023 07:28:02 [43182] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-HTP: Web-Server aktiv bei Wasserbett mit IP-Adresse 192.168.2.235
12.03.2023 07:28:02 [43181] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: verbunden
12.03.2023 07:28:02 [43174] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-NRG: Init driver 1
12.03.2023 07:28:02 [43180] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: Verbindungsversuch...
12.03.2023 07:28:02 [43185] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> 
12.03.2023 07:28:02 [43177] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: Verbindungsversuch...
12.03.2023 07:28:02 [43175] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-SRC: Restart
12.03.2023 07:28:02 [43173] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-ROT: Mode 1
12.03.2023 07:28:02 [43172] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-QPC: Count 1
12.03.2023 07:28:02 [43171] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-CFG: aus Flash geladen von F6, Anzahl 2019
12.03.2023 07:28:02 [43170] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-HDW: ESP8266EX
12.03.2023 07:28:02 [43178] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: verbinden mit AP2 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Wasserbett...
12.03.2023 07:28:02 [43176] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-Projekt tasmota - Wasserbett Version 12.4.0(tasmota)-2_7_4_9(2023.02.24 15:37:29)
12.03.2023 07:28:03 [43195] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: abonniere cmnd/Wasserbett/#
12.03.2023 07:28:03 [43196] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: abonniere cmnd/sonoffs/#
12.03.2023 07:28:03 [43197] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: abonniere cmnd/Wasserbett_fb/#
12.03.2023 07:28:03 [43198] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/INFO1 = {"Info1":{"Module":"Sonoff Pow","Version":"12.4.0(tasmota)","FallbackTopic":"cmnd/Wasserbett_fb/","GroupTopic":"cmnd/sonoffs/"}}
12.03.2023 07:28:03 [43199] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"Wasserbett","IPAddress":"192.168.2.235"}}
12.03.2023 07:28:03 [43200] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/INFO3 = {"Info3":{"RestartReason":"Software/System restart","BootCount":84}}
12.03.2023 07:28:03 [43186] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: DNS resolved ''pool.ntp.org'' (78.46.28.24) in 14 ms
12.03.2023 07:28:03 [43202] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: stat/Wasserbett/POWER = ON
12.03.2023 07:28:03 [43201] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: stat/Wasserbett/RESULT = {"POWER":"ON"}
12.03.2023 07:28:03 [43193] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/LWT = Online (beibehalten)
12.03.2023 07:28:03 [43192] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: verbunden
12.03.2023 07:28:03 [43191] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-WIF: DNS resolved ''192.168.2.3'' (192.168.2.3) in 0 ms
12.03.2023 07:28:03 [43190] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: Verbindungsversuch...
12.03.2023 07:28:03 [43189] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-UPP: Multicast deaktiviert
12.03.2023 07:28:03 [43187] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-RTC: UTC 2023-03-12T06:28:01, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
12.03.2023 07:28:03 [43188] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-RTC: Synced by NTP
12.03.2023 07:28:03 [43194] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: cmnd/Wasserbett/POWER = 
12.03.2023 07:28:04 [43203] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-UPP: Multicast (wieder-)verbunden
12.03.2023 07:28:04 [43204] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-QPC: Reset
12.03.2023 07:28:06 [43205] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-APP: Anzahl StartvorgÃ¤nge 84
12.03.2023 07:28:07 [43206] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":3}}
12.03.2023 07:28:07 [43207] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-12T07:28:04","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":206.54352,"Yesterday":3.22923,"Today":0.71752,"Power":3,"ApparentPower":16,"ReactivePower":15,"Factor":0.20,"Voltage":221,"Current":0.071}}
12.03.2023 07:28:07 [43208] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-CFG: in Flash gespeichert an F5, Anzahl 2020, Bytes 4096
12.03.2023 07:28:08 [43211] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-12T07:28:05","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":206.54352,"Yesterday":3.22923,"Today":0.71752,"Period":0,"Power":3,"ApparentPower":16,"ReactivePower":15,"Factor":0.20,"Voltage":221,"Current":0.071}}
12.03.2023 07:28:08 [43210] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/STATE = {"Time":"2023-03-12T07:28:05","Uptime":"0T00:00:10","UptimeSec":10,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":22,"MqttCount":1,"POWER":"ON","Wifi":{"AP":2,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":72,"Signal":-64,"LinkCount":1,"Downtime":"0T00:00:05"}}
12.03.2023 07:28:14 [43214] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/MARGINS = {"MARGINS":{"PowerDelta":-3}}
12.03.2023 07:28:14 [43215] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-12T07:28:11","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":206.54352,"Yesterday":3.22923,"Today":0.71752,"Power":3,"ApparentPower":16,"ReactivePower":16,"Factor":0.17,"Voltage":221,"Current":0.074}}
12.03.2023 07:28:16 [43217] From:Wasserbett.fritz.box (192.168.2.235) Fac:0 Sev:0 Msg >>> Wasserbett ESP-MQT: tele/Wasserbett/SENSOR = {"Time":"2023-03-12T07:28:13","ENERGY":{"TotalStartTime":"2023-01-01T00:42:09","Total":206.54355,"Yesterday":3.22923,"Today":0.71755,"Power":41,"ApparentPower":41,"ReactivePower":0,"Factor":1.00,"Voltage":220,"Current":0.187}}

There is nothing happening before....
Its just notmal MQT tele Messages from other Devices.....

camueller · 2023-03-12T13:25:56Z

camueller
Mar 12, 2023

If SAE is supposed to support MQTT, why is it polling through HTTP instead of reading the SENSOR topic from tasmota ?
Any way to remove htat HTTP polling in favor of MQTT ?

This feature has not been implemented yet (but is planned).

0 replies

camueller · 2023-03-12T13:35:15Z

camueller
Mar 12, 2023

This is the Homepage of the Project: https://github.com/camueller/SmartApplianceEnabler

I'm the developer behind this project and use 7 Tasmota devices myself with SAE for some years already. One of them is even a Sonoff Pow R2.

If I disable Smart Appliance Enabler, "Ghost Switching" is gone.

Running SAE and Tasmota devices I never experienced any "Ghost Switching".

java.net.SocketTimeoutException: Read timed out

Recently during some testing I got the same error when I run a Web browser on Tasmota Web-UI and SAE was accessing the same Tasmota device via HTTP. Most times it works but about 10-20 percent of the calls result in an error. Looks like Tasmota does not support concurrent HTTP requests.

7 replies

LurchiStromberg Mar 12, 2023
Author

Hello @barbudor
Thanks again for your Time to solving that Problem, I am very hopeful, that with @camueller we can find a Way to fix it.
Like I said:
With SAE Service stopped, there are not Ghost Switches / Resets with any of my 12 POWs. Only the ones who are configured an running in SAE are going "crazy"
Even running with FHEM Home Automation the POWs run since Years without any of those Resets....

PowerOnState which should trigger only on a clean power-cycle, not on a restart

Its set to Value 3

[SetOption0](https://tasmota.github.io/docs/Commands/#setoption0) which define if the power state is saved to flash for restoring after a powercycle (PowerOnState 3)

Its set default "on"

[SetOption63](https://tasmota.github.io/docs/Commands/#setoption63) whichby default (0) reads back hte status of the GPIO to determine the POWER state (instead of using the what is saved in flash)

Its also set to Default Value "off"
But, maybe I should try to set this to Value "on" ?

Maybe the Power State scanning do the quick toggle at the Relais ?

arendst Mar 12, 2023
Maintainer

Update to v12.4.0.2 and see if the spikes are gone.

LurchiStromberg Mar 12, 2023
Author

Update to v12.4.0.2 and see if the spikes are gone.

Thank you a lot @arendst for your Information.
I will now flash my POWs with the v12.4.0.2 and report after some testing.

LurchiStromberg Mar 12, 2023
Author

Update to v12.4.0.2 and see if the spikes are gone.

Sadly Error still existing :(
I am sorry, but i cannot say, if the Relais of the POW toggled too, coz its Dishwasher and it was not running.
It was the only Error happend since 6PM
Just to keep you up to Date

12.03.2023 21:41:48 [84080] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: verbinden mit AP1 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Spuelmaschine-4713...
12.03.2023 21:41:48 [84086] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-UPP: Multicast (wieder-)verbunden
12.03.2023 21:41:48 [84085] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-HTP: Web-Server aktiv bei Spuelmaschine-4713 mit IP-Adresse 192.168.2.240
12.03.2023 21:41:48 [84084] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: DNS resolved ''192.168.2.7'' (192.168.2.7) in 0 ms
12.03.2023 21:41:48 [84083] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: verbunden
12.03.2023 21:41:48 [84081] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: Verbindungsversuch...
12.03.2023 21:41:48 [84079] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: Verbindungsversuch...
12.03.2023 21:41:48 [84078] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-SNS: Hardware Serial
12.03.2023 21:41:48 [84077] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-SER: Set to 8E1 4800 bit/s
12.03.2023 21:41:48 [84076] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-Projekt tasmota - Spuelmaschine Version 12.4.0.2(tasmota)-2_7_4_9(2023.03.12 10:17:16)
12.03.2023 21:41:48 [84074] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-NRG: Init driver 2
12.03.2023 21:41:48 [84073] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-ROT: Mode 1
12.03.2023 21:41:48 [84072] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-CFG: CR 459/699, Busy 0
12.03.2023 21:41:48 [84071] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-QPC: Count 1
12.03.2023 21:41:48 [84070] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-SER: Set to 8E1 4800 bit/s
12.03.2023 21:41:48 [84069] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-CFG: aus Flash geladen von F9, Anzahl 933
12.03.2023 21:41:48 [84068] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-HDW: ESP8266EX
12.03.2023 21:41:48 [84075] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-SRC: Restart
12.03.2023 21:41:48 [84082] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: Verbindungsversuch...
12.03.2023 21:41:50 [84091] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: DNS resolved ''pool.ntp.org'' (178.215.228.24) in 11 ms
12.03.2023 21:41:50 [84108] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-UPP: Multicast (wieder-)verbunden
12.03.2023 21:41:50 [84107] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: stat/Spuelmaschine/POWER = ON
12.03.2023 21:41:50 [84101] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: abonniere cmnd/tasmotas/#
12.03.2023 21:41:50 [84106] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: stat/Spuelmaschine/RESULT = {"POWER":"ON"}
12.03.2023 21:41:50 [84105] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/INFO3 = {"Info3":{"RestartReason":{"Exception":4,"Reason":"Software Watchdog","EPC":["40003b53","00000000","00000000"],"EXCVADDR":"00000000","DEPC":"00000000"},"BootCount":32}}
12.03.2023 21:41:50 [84104] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"Spuelmaschine-4713","IPAddress":"192.168.2.240"}}
12.03.2023 21:41:50 [84103] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/INFO1 = {"Info1":{"Module":"Sonoff Pow R2","Version":"12.4.0.2(tasmota)","FallbackTopic":"cmnd/Spuelmaschine_fb/","GroupTopic":"cmnd/tasmotas/"}}
12.03.2023 21:41:50 [84102] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: abonniere cmnd/Spuelmaschine_fb/#
12.03.2023 21:41:50 [84092] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-RTC: UTC 2023-03-12T20:41:48, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
12.03.2023 21:41:50 [84100] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: abonniere cmnd/Spuelmaschine/#
12.03.2023 21:41:50 [84093] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-RTC: Synced by NTP
12.03.2023 21:41:50 [84094] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-UPP: Multicast deaktiviert
12.03.2023 21:41:50 [84095] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: Verbindungsversuch...
12.03.2023 21:41:50 [84096] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-WIF: DNS resolved ''192.168.2.3'' (192.168.2.3) in 1 ms
12.03.2023 21:41:50 [84097] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: verbunden
12.03.2023 21:41:50 [84098] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/LWT = Online (beibehalten)
12.03.2023 21:41:50 [84099] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: cmnd/Spuelmaschine/POWER = 
12.03.2023 21:41:51 [84109] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-QPC: Reset
12.03.2023 21:41:52 [84110] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-APP: Anzahl StartvorgÃ¤nge 32
12.03.2023 21:41:53 [84111] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-CFG: in Flash gespeichert an F8, Anzahl 934, Bytes 4096
12.03.2023 21:41:54 [84113] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/STATE = {"Time":"2023-03-12T21:41:53","Uptime":"0T00:00:11","UptimeSec":11,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":66,"Signal":-67,"LinkCount":1,"Downtime":"0T00:00:05"}}
12.03.2023 21:41:54 [84114] From:Spuelmaschine.fritz.box (192.168.2.240) Fac:0 Sev:0 Msg >>> Spuelmaschine-4713 ESP-MQT: tele/Spuelmaschine/SENSOR = {"Time":"2023-03-12T21:41:53","ENERGY":{"TotalStartTime":"2023-01-01T00:43:32","Total":29.65340,"Yesterday":0.00000,"Today":1.21340,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":236,"Current":0.000}}

LurchiStromberg Mar 13, 2023
Author

Update to v12.4.0.2 and see if the spikes are gone.

Sad to say, that its still same :(
The POW2 is flashed with 12.4.0.2
I can hear the Relais click short and the POW is rebooting. All connected Devices went off coz of lost Power....

00:00:00.454 WIF: Verbindungsversuch...
00:00:00.790 WIF: verbinden mit AP1 7490Fritzbox Channel 4 BSSId 28:D1:27:4E:1B:F6 in Modus 11n als Wohnzimmer-4615...
00:00:01.528 WIF: Verbindungsversuch...
00:00:02.148 CSE: Overload
00:00:02.504 WIF: Verbindungsversuch...
00:00:03.504 WIF: verbunden
00:00:03.753 WIF: DNS resolved '192.168.2.7' (192.168.2.7) in 1 ms
00:00:03.787 HTP: Web-Server aktiv bei Wohnzimmer-4615 mit IP-Adresse 192.168.2.237
00:00:03.789 UPP: Multicast (wieder-)verbunden
00:00:04.139 WIF: DNS resolved 'pool.ntp.org' (81.169.208.5) in 14 ms
00:00:04.173 RTC: UTC 2023-03-13T01:17:47, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
02:17:47.000 RTC: Synced by NTP
02:17:47.582 UPP: Multicast deaktiviert
02:17:47.583 MQT: Verbindungsversuch...
02:17:47.585 WIF: DNS resolved '192.168.2.3' (192.168.2.3) in 0 ms
02:17:47.599 MQT: verbunden
02:17:47.603 MQT: tele/Wohnzimmer/LWT = Online (beibehalten)
02:17:47.606 MQT: cmnd/Wohnzimmer/POWER = 
02:17:47.609 MQT: abonniere cmnd/Wohnzimmer/#
02:17:47.612 MQT: abonniere cmnd/sonoffs/#
02:17:47.614 MQT: abonniere cmnd/Wohnzimmer_fb/#
02:17:47.620 MQT: tele/Wohnzimmer/INFO1 = {"Info1":{"Module":"Sonoff Pow R2","Version":"12.4.0.2(tasmota)","FallbackTopic":"cmnd/Wohnzimmer_fb/","GroupTopic":"cmnd/sonoffs/"}}
02:17:47.623 MQT: tele/Wohnzimmer/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"Wohnzimmer-4615","IPAddress":"192.168.2.237"}}
02:17:47.627 MQT: tele/Wohnzimmer/INFO3 = {"Info3":{"RestartReason":{"Exception":4,"Reason":"Software Watchdog","EPC":["40003b53","00000000","00000000"],"EXCVADDR":"00000000","DEPC":"00000000"},"BootCount":154}}
02:17:47.630 MQT: stat/Wohnzimmer/RESULT = {"POWER":"ON"}
02:17:47.632 MQT: stat/Wohnzimmer/POWER = ON
02:17:48.780 UPP: Multicast (wieder-)verbunden
02:17:49.156 QPC: Reset
02:17:51.166 APP: Anzahl Startvorgänge 154
02:17:51.327 CFG: in Flash gespeichert an F4, Anzahl 4345, Bytes 4096
02:17:52.127 MQT: tele/Wohnzimmer/STATE = {"Time":"2023-03-13T02:17:52","Uptime":"0T00:00:11","UptimeSec":11,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"POWER":"ON","Wifi":{"AP":1,"SSId":"7490Fritzbox","BSSId":"28:D1:27:4E:1B:F6","Channel":4,"Mode":"11n","RSSI":86,"Signal":-57,"LinkCount":1,"Downtime":"0T00:00:05"}}
02:17:52.140 MQT: tele/Wohnzimmer/SENSOR = {"Time":"2023-03-13T02:17:52","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":26.97085,"Yesterday":0.49292,"Today":0.03685,"Period":0,"Power":10,"ApparentPower":30,"ReactivePower":28,"Factor":0.35,"Voltage":230,"Current":0.130}}
02:17:52.714 HTP: WeMo basic event from 192.168.2.85
02:18:23.119 HTP: Konsole
02:18:33.817 HTP: Befehl
02:18:33.822 SRC: WebCommand from 192.168.2.3
02:18:33.824 CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 1, Pld 8, Data '8'
02:18:33.835 MQT: stat/Wohnzimmer/STATUS8 = {"StatusSNS":{"Time":"2023-03-13T02:18:33","ENERGY":{"TotalStartTime":"2023-01-01T00:00:00","Total":26.97099,"Yesterday":0.49292,"Today":0.03699,"Power":11,"ApparentPower":31,"ReactivePower":29,"Factor":0.34,"Voltage":231,"Current":0.135}}}

camueller · 2023-03-12T14:57:32Z

camueller
Mar 12, 2023

Is there any possibility for SAE to use MQTT instead of HTPP polling ?

This feature has not been implemented yet (but is planned).

0 replies

LurchiStromberg · 2023-03-13T12:19:31Z

LurchiStromberg
Mar 13, 2023
Author

@barbudor @camueller @arendst

Ok Guys!
First of all let me say "Thank you, that you willing to help me at my Problem"

I have a sleepless Night behind me, to get things clear and exclude Error maybe of my running System.
So i decided to freshly install an Ubuntu 20.04. in a Virtual Machine (VMWare) and Start at Zero.
Present Linux Server was switched off / disconnected from Network.

All whats installed at the VM is Mosquitto and SAE + needed Files for SAE... No FHEM not nothing!

I can trace this sudden off/on "spike" Switching at all the POWs connected to SAE!
It also not depend on the Version of SAE (as i thought before and mendioned at Axels Github)
I rolled back till 1.6.21, and can trace the Ghost Switchings of the POWs even with that Version.

@camueller
I am sorry to say Axel, but i am sure its a Problem with POW and SAE only.
And i am sure, its happening since a long Time already, but nobody recognized.
As I talked to my Wife, all things get more clear. She told me several times past Months, that for example the Clothes out of the
Dryer wasnt dry. The Dishwasher stopped sudden but continued in pumping out Water but dirty Dishes... Same with the Washing Machine.
I dont know how many People use SAE with sOnOff POWs, but maybe you should start a Poll at your Github and ask about Users with POW Devices.

If you only have one POW this Fault happend maybe one time in 24 Hours, maybe in 48 Hours. So most of the Users of SAE wont realize that.
Not all are Power Monitoring TVs for example. And I recognized this Problem first, the time I installed a POW I still have at my Bedroom, connected
to a TV. That switched off and on sudden, like explained.

The Error Messages in Syslog, Weblog and SAE are still same, so I dont attach them here again. Only a Screenshot with

grep ERROR /tmp/rolling-2023-03-13.log

to show, that those random off / on Thing still happens at all connected POW Devices

Maybe @arendst is able to fix the toggeling at SAE Error restart, that would help a lot.
The Error Screenshot was made with POWs flashed with the new Version Tasmota 12.4.0.2.

So I think I have done all I can to find the Error. Hope we can terminate whats the Reason for it.

1 reply

LurchiStromberg Mar 13, 2023
Author

@barbudor @camueller @arendst

Ok Guys! First of all let me say "Thank you, that you willing to help me at my Problem"

I have a sleepless Night behind me, to get things clear and exclude Error maybe of my running System. So i decided to freshly install an Ubuntu 20.04. in a Virtual Machine (VMWare) and Start at Zero. Present Linux Server was switched off / disconnected from Network.

All whats installed at the VM is Mosquitto and SAE + needed Files for SAE... No FHEM not nothing!

I can trace this sudden off/on "spike" Switching at all the POWs connected to SAE! It also not depend on the Version of SAE (as i thought before and mendioned at Axels Github) I rolled back till 1.6.21, and can trace the Ghost Switchings of the POWs even with that Version.

@camueller I am sorry to say Axel, but i am sure its a Problem with POW and SAE only. And i am sure, its happening since a long Time already, but nobody recognized. As I talked to my Wife, all things get more clear. She told me several times past Months, that for example the Clothes out of the Dryer wasnt dry. The Dishwasher stopped sudden but continued in pumping out Water but dirty Dishes... Same with the Washing Machine. I dont know how many People use SAE with sOnOff POWs, but maybe you should start a Poll at your Github and ask about Users with POW Devices.

If you only have one POW this Fault happend maybe one time in 24 Hours, maybe in 48 Hours. So most of the Users of SAE wont realize that. Not all are Power Monitoring TVs for example. And I recognized this Problem first, the time I installed a POW I still have at my Bedroom, connected to a TV. That switched off and on sudden, like explained.

The Error Messages in Syslog, Weblog and SAE are still same, so I dont attach them here again. Only a Screenshot with
grep ERROR /tmp/rolling-2023-03-13.log
to show, that those random off / on Thing still happens at all connected POW Devices

Maybe @arendst is able to fix the toggeling at SAE Error restart, that would help a lot. The Error Screenshot was made with POWs flashed with the new Version Tasmota 12.4.0.2.

So I think I have done all I can to find the Error. Hope we can terminate whats the Reason for it.

EDIT:
Just to answer, I also tried SetOption63 set to "1"
This Option also took no effect.

camueller · 2023-03-13T15:28:22Z

camueller
Mar 13, 2023

I am sorry to say Axel, but i am sure its a Problem with POW and SAE only.

I'm using a Sonoff Pow R2 to monitor the power consumption of a water pump. This Tasmota device is only polled for power consumption but not controlled by any kind of software. If it would be switched off ("ghost switching") I would surely notice since the toilets would run without water :-)

And i am sure, its happening since a long Time already, but nobody recognized.

It may happening for a long time in your installation but so far I did not receive any similar bug reports.

0 replies

arendst · 2023-03-13T15:43:16Z

arendst
Mar 13, 2023
Maintainer

Maybe @arendst is able to fix the toggeling at SAE Error restart

In my test with Sonoff Pow R2 no relay clicking is noticed during a normal restart. The exceptions you encounter may lead to a relay click but then you shouldn't see exceptions at all.

Using HTTP for monitoring Tasmota devices (like TasmoAdmin) is known to lead to unwanted restarts due to used HTTP library. As said before Tasmota was never meant to be monitored by HTTP.

0 replies

camueller · 2023-03-13T15:54:16Z

camueller
Mar 13, 2023

I'm using a Sonoff Pow R2 to monitor the power consumption of a water pump.

It is running Tasmota 7.2.0 with MQTT enabled but not used.

2 replies

sfromis Mar 13, 2023

Version 7.2 is very old, I'd recommend upgrading to current before asking about how things can get different.

While I don't remember when, a long time ago Tasmota was improved to allow restarts reliably without relay clicking, since then I've had my main desktop computer behind a Sonoff Pow R2 with lots of restarts/upgrades, without issues.

camueller Mar 13, 2023

I'm not asking for anything. The Sonoff Pow R2 running Version 7.2.0 runs without problems. No need to upgrade.

arendst · 2023-03-13T16:00:47Z

arendst
Mar 13, 2023
Maintainer

To lower network traffic (decoding) you might also want to consider to disable (WeMo) emulation.

2 replies

LurchiStromberg Mar 13, 2023
Author

Well OK...
Sure, at any kind of Restart of the POWs the Relais never toggle..... Even connected to SAE not.
Its just a sudden thing that happens while monitoring or pushing Data to SAE.

I will do 2 last Tests, else i will uninstall SAE. As I understand @arendst right, HTTP requests or HTTP Communication of SAE causes the Reset off / on switching thing.

I will downgrade to Tasmota 7.2.0
I will replace a POW with a Gosund EP2 flashed with present Tasmota

Just to say:
My POWs work normal all the time! Never reboots or dying before using SAE.
I can easy stop the Reset Thing while stopping SAE Service. After that all work fine.

And also as I said, I ran only SAE at a VM, without anything else and same Issue.

@camueller
Maybe we can do cross checking?
Can you install present Tasmota 12.4.0.2 on your POW and see what will happen?

camueller Mar 13, 2023

Can you install present Tasmota 12.4.0.2 on your POW and see what will happen?

You should downgrade yous to 7.2.0 instead if you want to have a similar setup. I don't think the problem is relates to the Tasmota version.

LurchiStromberg · 2023-03-15T17:07:03Z

LurchiStromberg
Mar 15, 2023
Author

Hello Guys!
After a longer Time of testing I think I found the Issue!

SAE and Tasmota wont like each other at newer Versions.
I flashed my POWs back to 9.5.0 and since then there are no sudden Errors in Connection with SAE, neither Ghost Switchings of the Relais.

In Case you think its about flashing. I had a new Gosund Plug flashed 1st time with Tasmota 12.xxx and had same Issues and Error Messages in Mosquitto.log, SAE log and Syslog.
Back to 9.5.0 at the Gosund Plug too and since then no Errors happen.

To me its OK to solve the Problem like this.

I am sure if other Users who use SAE and have Tasmota 12.xxx Firmware on their Devices will have same Issues.

0 replies

camueller · 2023-03-15T17:17:19Z

camueller
Mar 15, 2023

I flashed my POWs back to 9.5.0 and since then there are no sudden Errors in Connection with SAE, neither Ghost Switchings of the Relais.

If this is true and although MQTT is preferable over HTTP when getting data from Tasmota device I'm really suprised that newer versions of Tasmota are not as stable as older versions. I wouldn't have expected that!

1 reply

LurchiStromberg Mar 15, 2023
Author

Yes silly, its what I witness and it also would explain, why you cant trace my explained Errors because your POW is also on older Firmware.
I tested it with my 12 POWs and one Gosund Plug. Its traceable...
Only @arendst can tell us, what major Changes happend between those Firmwares.
Ok, I didnt test 10x or 11x Firmware....
I was on 9.5.0 before, so I went back to this Version.

arendst · 2023-03-17T16:30:48Z

arendst
Mar 17, 2023
Maintainer

It seems since later versions stack is low on energy monitoring devices leading to unexplainable restarts and exceptions and watchdogs.

Working on a possible solution.

0 replies

arendst · 2023-03-17T20:08:48Z

arendst
Mar 17, 2023
Maintainer

Give latest dev branch a try. It reduces stack usage which when becoming to low could lead to all kind of issues.

0 replies

Any Way to disable switching on / off Device via MQTT without disabling MQTT? #18164

Replies: 14 comments · 23 replies

LurchiStromberg Mar 11, 2023 Author

LurchiStromberg Mar 11, 2023 Author

LurchiStromberg Mar 11, 2023 Author

LurchiStromberg Mar 11, 2023 Author

LurchiStromberg Mar 12, 2023 Author

LurchiStromberg Mar 12, 2023 Author

arendst Mar 12, 2023 Maintainer

LurchiStromberg Mar 12, 2023 Author

LurchiStromberg Mar 12, 2023 Author

LurchiStromberg Mar 13, 2023 Author

LurchiStromberg Mar 13, 2023 Author

LurchiStromberg Mar 13, 2023 Author

arendst Mar 13, 2023 Maintainer

arendst Mar 13, 2023 Maintainer

LurchiStromberg Mar 13, 2023 Author

LurchiStromberg Mar 15, 2023 Author

LurchiStromberg Mar 15, 2023 Author

arendst Mar 17, 2023 Maintainer

arendst Mar 17, 2023 Maintainer

Replies: 14 comments 23 replies

LurchiStromberg Mar 11, 2023
Author

LurchiStromberg
Mar 11, 2023
Author

LurchiStromberg Mar 11, 2023
Author

LurchiStromberg Mar 11, 2023
Author

LurchiStromberg Mar 12, 2023
Author

LurchiStromberg Mar 12, 2023
Author

arendst Mar 12, 2023
Maintainer

LurchiStromberg Mar 12, 2023
Author

LurchiStromberg Mar 12, 2023
Author

LurchiStromberg Mar 13, 2023
Author

LurchiStromberg
Mar 13, 2023
Author

LurchiStromberg Mar 13, 2023
Author

arendst
Mar 13, 2023
Maintainer

arendst
Mar 13, 2023
Maintainer

LurchiStromberg Mar 13, 2023
Author

LurchiStromberg
Mar 15, 2023
Author

LurchiStromberg Mar 15, 2023
Author

arendst
Mar 17, 2023
Maintainer

arendst
Mar 17, 2023
Maintainer