arendst / Tasmota

Alternative firmware for ESP8266 and ESP32 based devices with easy configuration using webUI, OTA updates, automation using timers or rules, expandability and entirely local control over MQTT, HTTP, Serial or KNX. Full documentation at
https://tasmota.github.io/docs
GNU General Public License v3.0
21.93k stars 4.76k forks source link

Wakeup command changes Sleep and LoadAvg goes very high and Sleep is changed to 10 #14295

Closed kreutpet closed 2 years ago

kreutpet commented 2 years ago

PROBLEM DESCRIPTION

A clear and concise description of what the problem is. I have a H801 which i like to wakeup and smothly change the dimmer2 to a value of 67 I have set SetOption60 1 and Sleep 70. When the wakeup is triggered the LoadAvg goes very high on 99. Before the wakeup the LoadAvg is <20 The sleep is then changed to 10

The Device seems to be overloaded and is not able to perform the task of smoothly dimming the dimmer2

After the device is in that status a Sleep command to a higher value is not anymore accepted. Device need a restart and agin configured on the Sleep command

I use the 10.1.0 2021.12.08 14:47:39

REQUESTED INFORMATION

Make sure your have performed every step and checked the applicable boxes before submitting your issue. Thank you!

- [ ] If using rules, provide the output of this command: `Backlog Rule1; Rule2; Rule3`:
```lua
  Rules output here:
stat/aquarium/RESULT = {"Rule1":{"State":"ON","Once":"OFF","StopOnError":"OFF","Length":119,"Free":392,"Rules":"ON clock#timer=1 DO Backlog Wakeup 67;POWER2 ON;POWER1 OFF BREAK ON rules#timer=2 DO Backlog Speed2 40;POWER2 OFF ENDON"}}
stat/aquarium/RESULT = {"Rule2":{"State":"ON","Once":"OFF","StopOnError":"OFF","Length":129,"Free":382,"Rules":"ON clock#timer=3 DO Backlog Color 3;POWER1 ON;POWER2 OFF;Dimmer1 90 ENDON ON rules#timer=4 DO Backlog POWER1 OFF;POWER2 OFF ENDON"}}
stat/aquarium/RESULT = {"Rule3":{"State":"OFF","Once":"OFF","StopOnError":"OFF","Length":0,"Free":511,"Rules":""}}
- [ ] Set `weblog` to 4 and then, when you experience your issue, provide the output of the Console log:
```lua
  Console output here:
Here the log if the rule is triggered
00:00:00.001 HDW: ESP8266EX
00:00:00.050 CFG: aus Flash geladen von F6, Anzahl 182
00:00:00.055 QPC: Count 1
00:00:00.068 Projekt tasmota - Aquarium Version 10.1.0(tasmota)-2_7_4_9(2021.12.08 14:47:39)
00:00:00.531 WIF: verbinden mit AP1 kiot Channel 9 BSSId 60:A4:4C:DC:B2:23 in Modus 11n als aquarium-0407...
00:00:01.507 WIF: verbunden
00:00:01.753 HTP: Web-Server aktiv bei aquarium-0407 mit IP-Adresse 192.168.1.95
11:21:02.313 MQT: Verbindungsversuch...
11:21:02.331 MQT: verbunden
11:21:02.334 MQT: tele/aquarium/LWT = Online (beibehalten)
11:21:02.337 MQT: cmnd/aquarium/POWER = 
11:21:02.355 MQT: tele/aquarium/INFO1 = {"Info1":{"Module":"H801","Version":"10.1.0(tasmota)","FallbackTopic":"cmnd/DVES_4DC197_fb/","GroupTopic":"cmnd/tasmotas/"}}
11:21:02.385 MQT: tele/aquarium/INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"aquarium-0407","IPAddress":"192.168.1.95"}}
11:21:02.404 MQT: tele/aquarium/INFO3 = {"Info3":{"RestartReason":"External System"}}
11:21:02.419 MQT: stat/aquarium/RESULT = {"POWER1":"OFF"}
11:21:02.421 MQT: stat/aquarium/POWER1 = OFF
11:21:02.423 MQT: stat/aquarium/RESULT = {"POWER2":"ON"}
11:21:02.424 MQT: stat/aquarium/POWER2 = ON
11:21:06.417 QPC: Reset
11:21:07.395 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T11:21:07","Uptime":"0T00:00:09","UptimeSec":9,"Heap":28,"SleepMode":"Normal","Sleep":10,"LoadAvg":67,"MqttCount":1,"POWER1":"OFF","Dimmer1":100,"POWER2":"ON","Dimmer2":62,"Color":"5454FF9E00","HSBColor":"240,67,100","White":62,"CT":153,"Channel":[33,33,100,62,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":68,"Signal":-66,"LinkCount":1,"Downtime":"0T00:00:03"}}
11:21:21.277 CMD: dimmer2 60
11:21:21.287 MQT: stat/aquarium/RESULT = {"POWER1":"OFF","Dimmer1":100,"POWER2":"ON","Dimmer2":60,"Color":"5454FF9900","HSBColor":"240,67,100","White":60,"CT":153,"Channel":[33,33,100,60,0]}
11:21:32.081 CMD: SetOption60 1
11:21:32.089 MQT: stat/aquarium/RESULT = {"SetOption60":"ON"}
11:21:34.153 CMD: Sleep 70
11:21:34.159 MQT: stat/aquarium/RESULT = {"Sleep":{"70":{"Active":"70"}}}
11:22:16.707 MQT: stat/aquarium/RESULT = {"POWER2":"OFF"}
11:22:16.711 MQT: stat/aquarium/POWER2 = OFF
11:22:48.360 CMD: Timer1 {"Enable":1,"Mode":0,"Time":"11:24","Window":0,"Days":"1111111","Repeat":1,"Action":3}
11:22:48.384 MQT: stat/aquarium/RESULT = {"Timer1":{"Enable":1,"Mode":0,"Time":"11:24","Window":0,"Days":"1111111","Repeat":1,"Output":1,"Action":3}}
11:23:00.937 CMD: state
11:23:00.948 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T11:23:00","Uptime":"0T00:02:02","UptimeSec":122,"Heap":26,"SleepMode":"Normal","Sleep":70,"LoadAvg":14,"MqttCount":1,"POWER1":"OFF","Dimmer1":100,"POWER2":"OFF","Dimmer2":60,"Color":"5454FF9900","HSBColor":"240,67,100","White":60,"CT":153,"Channel":[33,33,100,60,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":66,"Signal":-67,"LinkCount":1,"Downtime":"0T00:00:03"}}
11:23:08.502 CMD: weblog 4
11:23:08.509 MQT: stat/aquarium/RESULT = {"WebLog":4}
11:23:21.513 WIF: Prüfe Verbindung...
11:23:41.523 WIF: Prüfe Verbindung...
11:24:00.449 WIF: Sending Gratuitous ARP
11:24:00.463 RUL: CLOCK#TIMER=1 performs "Backlog Wakeup 67;POWER2 ON;POWER1 OFF"
11:24:00.465 SRC: Rule
11:24:00.467 CMD: Grp 0, Cmnd 'BACKLOG', Idx 1, Len 30, Data 'Wakeup 67;POWER2 ON;POWER1 OFF'
11:24:00.570 SRC: Backlog
11:24:00.572 CMD: Grp 0, Cmnd 'WAKEUP', Idx 1, Len 2, Data '67'
11:24:00.576 SRC: Light
11:24:00.582 MQT: stat/aquarium/RESULT = {"POWER1":"ON"}
11:24:00.587 MQT: stat/aquarium/POWER1 = ON
11:24:00.591 MQT: stat/aquarium/RESULT = {"Wakeup":"Started"}
11:24:00.778 SRC: Backlog
11:24:00.780 CMD: Grp 0, Cmnd 'POWER', Idx 2, Len 2, Data 'ON'
11:24:00.787 MQT: stat/aquarium/RESULT = {"POWER2":"ON"}
11:24:00.792 MQT: stat/aquarium/POWER2 = ON
11:24:00.982 SRC: Backlog
11:24:00.985 CMD: Grp 0, Cmnd 'POWER', Idx 1, Len 3, Data 'OFF'
11:24:00.993 MQT: stat/aquarium/RESULT = {"POWER1":"OFF"}
11:24:00.997 MQT: stat/aquarium/POWER1 = OFF
11:24:01.505 WIF: Prüfe Verbindung...
11:24:21.510 WIF: Prüfe Verbindung...
11:24:25.151 CMD: state
11:24:25.153 SRC: WebConsole from 192.168.1.46
11:24:25.156 CMD: Grp 0, Cmnd 'STATE', Idx 1, Len 0, Data ''
11:24:25.165 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T11:24:25","Uptime":"0T00:03:27","UptimeSec":207,"Heap":26,"SleepMode":"Normal","Sleep":10,"LoadAvg":57,"MqttCount":1,"POWER1":"OFF","Dimmer1":1,"POWER2":"ON","Dimmer2":1,"Color":"0101040400","HSBColor":"240,67,1","White":1,"CT":153,"Channel":[1,1,1,1,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":68,"Signal":-66,"LinkCount":1,"Downtime":"0T00:00:03"}}
11:24:41.508 WIF: Prüfe Verbindung...
11:24:55.816 CMD: state
11:24:55.818 SRC: WebConsole from 192.168.1.46
11:24:55.820 CMD: Grp 0, Cmnd 'STATE', Idx 1, Len 0, Data ''
11:24:55.829 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T11:24:55","Uptime":"0T00:03:57","UptimeSec":237,"Heap":26,"SleepMode":"Normal","Sleep":10,"LoadAvg":99,"MqttCount":1,"POWER1":"OFF","Dimmer1":3,"POWER2":"ON","Dimmer2":3,"Color":"03030A0A00","HSBColor":"240,67,3","White":3,"CT":153,"Channel":[1,1,3,3,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":68,"Signal":-66,"LinkCount":1,"Downtime":"0T00:00:03"}}

TO REPRODUCE

Steps to reproduce the behavior: Set the sleep mode tho normal and change the sleep to an acceptable value. Start the wakup verify the sleep configuration which has changed and the LoadAvg goes 99

SetOption60 1 Sleep70 Wakup -> Sleep has changed to 10 and LoadAvg becomes 99

EXPECTED BEHAVIOUR

A clear and concise description of what you expected to happen.

When the wakeup is triggered the Sleep value should not set to 10 as this will impact the LoadAvg.

SCREENSHOTS

If applicable, add screenshots to help explain your problem.

ADDITIONAL CONTEXT

Add any other context about the problem here.

(Please, remember to close the issue when the problem has been addressed)

sfromis commented 2 years ago

With software PWM in operation, Tasmota needs to lower Sleep to 10, and run at a high LoadAvg. This is not a problem, Tasmota can run just fine.

kreutpet commented 2 years ago

Well actually the device ends up in a status after the wakeup that it does not wakeup properly in the next run. Running now for 1 week the wakeup never completed or even did not start. As the LoadAvg after first run modifies sleep with the LoaadAvg of 99 my assumption is that the following wakeup and triggers are impacted in their functionality. Even the triggers for rule 2 are not triggered properly.

You also state in the documentation that LoadAvg should be kept below 75. How can I verify that the other functions are not impacted by sleep 10?

I am also working on the integration to openhab and could offload the smooth dimming to the openhab. I would prefer to run it on tasmota .

Jason2866 commented 2 years ago

Does dimming works smoothly when you set sleep 0? Loadavg is not valid in this case and will end at 999.

sfromis commented 2 years ago

If you think that there is an issue with how it works (or not), better drop the focus on LoadAvg, this number is not a simple measure of "CPU busy". High LoadAvg is simply how it works with software PWM running. This supersedes suggestions about managing dynamic sleep yourself, which is mainly focused on a situation where you add many sensors.

kreutpet commented 2 years ago

Many thnkas for looking into this.

I created another test run and it seems the smooth dimming is working. Here you can see the log. After that run the LoadAvg is on 999 and it actually stays there. I now went through 2 cycles and both were successfully completed. See the both logs. I will need to check now with the Sleep 0 setting if the triggers run under normal conditions. Will give feedback in the next days.

First run

14:23:35.378 MQT: stat/aquarium/RESULT = {"Sleep":{"0":{"Active":"0"}}}
14:23:39.304 CMD: state
14:23:39.313 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:23:39","Uptime":"0T00:58:40","UptimeSec":3520,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":116,"MqttCount":1,"POWER1":"OFF","Dimmer1":100,"POWER2":"ON","Dimmer2":62,"Color":"5454FF9E00","HSBColor":"240,67,100","White":62,"CT":153,"Channel":[33,33,100,62,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":48,"Signal":-76,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:24:14.940 CMD: Timer1 {"Enable":1,"Mode":0,"Time":"14:25","Window":0,"Days":"1111111","Repeat":1,"Action":3}
14:24:14.949 MQT: stat/aquarium/RESULT = {"Timer1":{"Enable":1,"Mode":0,"Time":"14:25","Window":0,"Days":"1111111","Repeat":1,"Output":1,"Action":3}}
14:24:20.835 CMD: power2 off
14:24:20.843 MQT: stat/aquarium/RESULT = {"POWER2":"OFF"}
14:24:20.848 MQT: stat/aquarium/POWER2 = OFF
14:24:23.441 CMD: state
14:24:23.451 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:24:23","Uptime":"0T00:59:24","UptimeSec":3564,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":239,"MqttCount":1,"POWER1":"OFF","Dimmer1":100,"POWER2":"OFF","Dimmer2":62,"Color":"5454FF9E00","HSBColor":"240,67,100","White":62,"CT":153,"Channel":[33,33,100,62,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:25:00.410 RUL: CLOCK#TIMER=1 performs "Backlog Wakeup 67;POWER2 ON;POWER1 OFF"
14:25:00.446 MQT: stat/aquarium/RESULT = {"POWER1":"ON"}
14:25:00.449 MQT: stat/aquarium/POWER1 = ON
14:25:00.451 MQT: stat/aquarium/RESULT = {"Wakeup":"Started"}
14:25:00.645 MQT: stat/aquarium/RESULT = {"POWER2":"ON"}
14:25:00.649 MQT: stat/aquarium/POWER2 = ON
14:25:00.846 MQT: stat/aquarium/RESULT = {"POWER1":"OFF"}
14:25:00.851 MQT: stat/aquarium/POWER1 = OFF
14:25:10.406 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:25:10","Uptime":"0T01:00:11","UptimeSec":3611,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":311,"MqttCount":1,"POWER1":"OFF","Dimmer1":1,"POWER2":"ON","Dimmer2":1,"Color":"0000010100","HSBColor":"240,67,0","White":1,"CT":153,"Channel":[0,0,1,1,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":50,"Signal":-75,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:26:15.778 CMD: state
14:26:15.787 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:26:15","Uptime":"0T01:01:16","UptimeSec":3676,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":401,"MqttCount":1,"POWER1":"OFF","Dimmer1":5,"POWER2":"ON","Dimmer2":5,"Color":"04040E0E00","HSBColor":"240,67,5","White":5,"CT":153,"Channel":[1,1,5,5,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":58,"Signal":-71,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:30:11.406 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:30:11","Uptime":"0T01:05:12","UptimeSec":3912,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":738,"MqttCount":1,"POWER1":"OFF","Dimmer1":23,"POWER2":"ON","Dimmer2":23,"Color":"13133B3B00","HSBColor":"240,67,23","White":23,"CT":153,"Channel":[7,7,23,23,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:30:45.530 CMD: state
14:30:45.540 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:30:45","Uptime":"0T01:05:46","UptimeSec":3946,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":779,"MqttCount":1,"POWER1":"OFF","Dimmer1":25,"POWER2":"ON","Dimmer2":25,"Color":"1515414100","HSBColor":"240,67,25","White":25,"CT":153,"Channel":[8,8,25,25,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":54,"Signal":-73,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:31:55.634 CMD: state
14:31:55.644 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:31:55","Uptime":"0T01:06:56","UptimeSec":4016,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":877,"MqttCount":1,"POWER1":"OFF","Dimmer1":31,"POWER2":"ON","Dimmer2":31,"Color":"1A1A4F4F00","HSBColor":"240,67,31","White":31,"CT":153,"Channel":[10,10,31,31,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:32:45.680 CMD: state
14:32:45.690 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:32:45","Uptime":"0T01:07:46","UptimeSec":4066,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":945,"MqttCount":1,"POWER1":"OFF","Dimmer1":34,"POWER2":"ON","Dimmer2":34,"Color":"1D1D585800","HSBColor":"240,67,34","White":34,"CT":153,"Channel":[11,11,34,34,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":54,"Signal":-73,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:35:11.407 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:35:11","Uptime":"0T01:10:12","UptimeSec":4212,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":45,"POWER2":"ON","Dimmer2":45,"Color":"2626747400","HSBColor":"240,67,45","White":45,"CT":153,"Channel":[15,15,45,45,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:40:01.483 MQT: stat/aquarium/RESULT = {"Wakeup":"Done","POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON"}
14:40:11.406 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:40:11","Uptime":"0T01:15:12","UptimeSec":4512,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":50,"Signal":-75,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:40:30.982 CMD: state
14:40:30.993 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:40:30","Uptime":"0T01:15:31","UptimeSec":4531,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:41:35.806 CMD: state
14:41:35.816 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:41:35","Uptime":"0T01:16:36","UptimeSec":4596,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":58,"Signal":-71,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:45:11.406 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:45:11","Uptime":"0T01:20:12","UptimeSec":4812,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:47:46.067 CMD: state
14:47:46.077 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:47:46","Uptime":"0T01:22:47","UptimeSec":4967,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":58,"Signal":-71,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:48:41.164 MQT: stat/aquarium/RESULT = {"POWER2":"OFF"}
14:48:41.168 MQT: stat/aquarium/POWER2 = OFF
14:50:11.407 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:50:11","Uptime":"0T01:25:12","UptimeSec":5112,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"OFF","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:50:25.349 CMD: state
14:50:25.359 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:50:25","Uptime":"0T01:25:26","UptimeSec":5126,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"OFF","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:04"}}

Second run

14:50:25.359 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:50:25","Uptime":"0T01:25:26","UptimeSec":5126,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"POWER1":"OFF","Dimmer1":67,"POWER2":"OFF","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":0,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:DC:B2:23","Channel":9,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:50:30.425 CMD: power2 off
14:50:30.432 MQT: stat/aquarium/RESULT = {"POWER2":"OFF"}
14:50:30.436 MQT: stat/aquarium/POWER2 = OFF
14:50:56.505 CMD: Timer1 {"Enable":1,"Mode":0,"Time":"14:52","Window":0,"Days":"1111111","Repeat":1,"Action":3}
14:50:56.514 MQT: stat/aquarium/RESULT = {"Timer1":{"Enable":1,"Mode":0,"Time":"14:52","Window":0,"Days":"1111111","Repeat":1,"Output":1,"Action":3}}
14:52:00.410 RUL: CLOCK#TIMER=1 performs "Backlog Wakeup 67;POWER2 ON;POWER1 OFF"
14:52:00.446 MQT: stat/aquarium/RESULT = {"POWER1":"ON"}
14:52:00.449 MQT: stat/aquarium/POWER1 = ON
14:52:00.451 MQT: stat/aquarium/RESULT = {"Wakeup":"Started"}
14:52:00.645 MQT: stat/aquarium/RESULT = {"POWER2":"ON"}
14:52:00.650 MQT: stat/aquarium/POWER2 = ON
14:52:00.846 MQT: stat/aquarium/RESULT = {"POWER1":"OFF"}
14:52:00.851 MQT: stat/aquarium/POWER1 = OFF
14:53:03.642 WIF: verbinden mit AP1 kiot Channel 13 BSSId 60:A4:4C:68:82:0B in Modus 11n als aquarium-0407...
14:53:05.004 MQT: Verbindungsversuch...
14:53:05.014 MQT: verbunden
14:53:05.018 MQT: tele/aquarium/LWT = Online (beibehalten)
14:53:05.022 MQT: cmnd/aquarium/POWER = 
14:55:10.816 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T14:55:10","Uptime":"0T01:30:11","UptimeSec":5411,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":14,"POWER2":"ON","Dimmer2":14,"Color":"0C0C242400","HSBColor":"240,67,14","White":14,"CT":153,"Channel":[4,4,14,14,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":54,"Signal":-73,"LinkCount":2,"Downtime":"0T00:00:05"}}
14:56:22.058 CMD: state
14:56:22.068 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:56:22","Uptime":"0T01:31:23","UptimeSec":5483,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":19,"POWER2":"ON","Dimmer2":19,"Color":"1010313100","HSBColor":"240,67,19","White":19,"CT":153,"Channel":[6,6,19,19,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":54,"Signal":-73,"LinkCount":2,"Downtime":"0T00:00:05"}}
14:57:23.950 CMD: state
14:57:23.960 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:57:23","Uptime":"0T01:32:24","UptimeSec":5544,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":24,"POWER2":"ON","Dimmer2":24,"Color":"14143D3D00","HSBColor":"240,67,24","White":24,"CT":153,"Channel":[7,7,24,24,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":50,"Signal":-75,"LinkCount":2,"Downtime":"0T00:00:05"}}
14:59:25.500 CMD: state
14:59:25.510 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T14:59:25","Uptime":"0T01:34:26","UptimeSec":5666,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":33,"POWER2":"ON","Dimmer2":33,"Color":"1C1C555500","HSBColor":"240,67,33","White":33,"CT":153,"Channel":[11,11,33,33,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":2,"Downtime":"0T00:00:05"}}
15:00:10.816 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T15:00:10","Uptime":"0T01:35:11","UptimeSec":5711,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":36,"POWER2":"ON","Dimmer2":36,"Color":"1E1E5D5D00","HSBColor":"240,67,36","White":36,"CT":153,"Channel":[11,11,36,36,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":54,"Signal":-73,"LinkCount":2,"Downtime":"0T00:00:05"}}
15:02:44.916 CMD: state
15:02:44.925 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T15:02:44","Uptime":"0T01:37:45","UptimeSec":5865,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":48,"POWER2":"ON","Dimmer2":48,"Color":"28287B7B00","HSBColor":"240,67,48","White":48,"CT":153,"Channel":[15,15,48,48,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":2,"Downtime":"0T00:00:05"}}
15:04:19.025 CMD: state
15:04:19.035 MQT: stat/aquarium/RESULT = {"Time":"2022-01-05T15:04:19","Uptime":"0T01:39:20","UptimeSec":5960,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":55,"POWER2":"ON","Dimmer2":55,"Color":"2E2E8D8D00","HSBColor":"240,67,55","White":55,"CT":153,"Channel":[18,18,55,55,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":52,"Signal":-74,"LinkCount":2,"Downtime":"0T00:00:05"}}
15:05:10.816 MQT: tele/aquarium/STATE = {"Time":"2022-01-05T15:05:10","Uptime":"0T01:40:11","UptimeSec":6011,"Heap":26,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":2,"POWER1":"OFF","Dimmer1":59,"POWER2":"ON","Dimmer2":59,"Color":"3232979700","HSBColor":"240,67,59","White":59,"CT":153,"Channel":[19,19,59,59,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON","Wifi":{"AP":1,"SSId":"kiot","BSSId":"60:A4:4C:68:82:0B","Channel":13,"Mode":"11n","RSSI":56,"Signal":-72,"LinkCount":2,"Downtime":"0T00:00:05"}}
15:06:59.857 MQT: stat/aquarium/RESULT = {"Wakeup":"Done","POWER1":"OFF","Dimmer1":67,"POWER2":"ON","Dimmer2":67,"Color":"3838ABAB00","HSBColor":"240,67,67","White":67,"CT":153,"Channel":[22,22,67,67,0],"Scheme":1,"Fade":"ON","Speed":1,"LedTable":"ON"}
ascillato2 commented 2 years ago

I created another test run and it seems the smooth dimming is working. Here you can see the log. After that run the LoadAvg is on 999 and it actually stays there. I now went through 2 cycles and both were successfully completed. See the both logs.

Great to know that now it is working fine for you. Thanks for sharing your logs.