Memory leaking, restarting

Moderators: grovkillen, Stuntteam, TD-er

Post Reply
Message
Author
hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Memory leaking, restarting

#1 Post by hyper_pl » 04 Jan 2023, 19:15

Hi,
I have esp8266 Nodemcu with ESP_Easy_mega_20221224_normal_ESP8266_4M1M istalled.
Every 30 - 80 min esp8266 loses memory and restarts.
How to check what is the reason ?

Code: Select all

Jan  4 13:57:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,13:57
Jan  4 13:57:29 ESP-TVLED EspEasy: WD   : Uptime 64 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 13:57:59 ESP-TVLED EspEasy: WD   : Uptime 65 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 13:58:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,13:58
Jan  4 13:58:03 ESP-TVLED EspEasy: NTP  : NTP replied: delay 21 mSec Accuracy increased by 0.575 seconds
Jan  4 13:58:03 ESP-TVLED EspEasy: Time set to 1672837083.575 Time adjusted by 111.46 msec. Wander: 0.029 msec/second Source: NTP
Jan  4 13:58:03 ESP-TVLED EspEasy: Local time: 2023-01-04 13:58:03
Jan  4 13:58:03 ESP-TVLED EspEasy: EVENT: Time#Set
Jan  4 13:58:29 ESP-TVLED EspEasy: WD   : Uptime 65 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 13:58:59 ESP-TVLED EspEasy: WD   : Uptime 66 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 13:59:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,13:59
Jan  4 13:59:29 ESP-TVLED EspEasy: WD   : Uptime 66 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 13:59:59 ESP-TVLED EspEasy: WD   : Uptime 67 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:00:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:00
Jan  4 14:00:29 ESP-TVLED EspEasy: WD   : Uptime 67 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:00:59 ESP-TVLED EspEasy: WD   : Uptime 68 ConnectFailures 0 FreeMem 18120 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:01:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:01
Jan  4 14:01:29 ESP-TVLED EspEasy: WD   : Uptime 68 ConnectFailures 0 FreeMem 18000 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:01:59 ESP-TVLED EspEasy: WD   : Uptime 69 ConnectFailures 0 FreeMem 17952 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:02:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:02
Jan  4 14:02:29 ESP-TVLED EspEasy: WD   : Uptime 69 ConnectFailures 0 FreeMem 17808 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:02:59 ESP-TVLED EspEasy: WD   : Uptime 70 ConnectFailures 0 FreeMem 17664 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:03:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:03
Jan  4 14:03:29 ESP-TVLED EspEasy: WD   : Uptime 70 ConnectFailures 0 FreeMem 17544 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:03:59 ESP-TVLED EspEasy: WD   : Uptime 71 ConnectFailures 0 FreeMem 17400 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:04:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:04
Jan  4 14:04:29 ESP-TVLED EspEasy: WD   : Uptime 71 ConnectFailures 0 FreeMem 17208 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:04:59 ESP-TVLED EspEasy: WD   : Uptime 72 ConnectFailures 0 FreeMem 16896 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:05:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:05
Jan  4 14:05:29 ESP-TVLED EspEasy: WD   : Uptime 72 ConnectFailures 0 FreeMem 16704 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:05:59 ESP-TVLED EspEasy: WD   : Uptime 73 ConnectFailures 0 FreeMem 16344 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:06:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:06
Jan  4 14:06:29 ESP-TVLED EspEasy: WD   : Uptime 73 ConnectFailures 0 FreeMem 16056 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:06:59 ESP-TVLED EspEasy: WD   : Uptime 74 ConnectFailures 0 FreeMem 15432 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:07:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:07
Jan  4 14:07:29 ESP-TVLED EspEasy: WD   : Uptime 74 ConnectFailures 0 FreeMem 15024 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:07:59 ESP-TVLED EspEasy: WD   : Uptime 75 ConnectFailures 0 FreeMem 14144 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:08:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:08
Jan  4 14:08:29 ESP-TVLED EspEasy: WD   : Uptime 75 ConnectFailures 0 FreeMem 13616 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:08:59 ESP-TVLED EspEasy: WD   : Uptime 76 ConnectFailures 0 FreeMem 12848 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:09:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:09
Jan  4 14:09:29 ESP-TVLED EspEasy: WD   : Uptime 76 ConnectFailures 0 FreeMem 12008 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:09:59 ESP-TVLED EspEasy: WD   : Uptime 77 ConnectFailures 0 FreeMem 11048 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:10:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:10
Jan  4 14:10:29 ESP-TVLED EspEasy: WD   : Uptime 77 ConnectFailures 0 FreeMem 9992 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:10:59 ESP-TVLED EspEasy: WD   : Uptime 78 ConnectFailures 0 FreeMem 8888 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:11:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:11
Jan  4 14:11:29 ESP-TVLED EspEasy: WD   : Uptime 78 ConnectFailures 0 FreeMem 7760 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:11:59 ESP-TVLED EspEasy: WD   : Uptime 79 ConnectFailures 0 FreeMem 6344 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:12:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:12
Jan  4 14:12:29 ESP-TVLED EspEasy: WD   : Uptime 79 ConnectFailures 0 FreeMem 4976 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:12:59 ESP-TVLED EspEasy: WD   : Uptime 80 ConnectFailures 0 FreeMem 3416 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:13:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:13
Jan  4 14:13:21 ESP-TVLED EspEasy: MQTT : Connection lost, state: Disconnected
Jan  4 14:13:36 ESP-TVLED EspEasy: EVENT: MQTT#Disconnected
Jan  4 14:13:42 ESP-TVLED EspEasy: WiFi : WiFi services initialized
Jan  4 14:13:42 ESP-TVLED EspEasy: firstLoopConnectionsEstablished
Jan  4 14:13:42 ESP-TVLED EspEasy: MQTT : Intentional reconnect
Jan  4 14:13:42 ESP-TVLED EspEasy: MQTT : Connected to broker with client ID: ESP_TVLED_0
Jan  4 14:13:42 ESP-TVLED EspEasy: Subscribed to: ESP_TVLED/#
Jan  4 14:13:42 ESP-TVLED EspEasy: EVENT: WiFi#Connected
Jan  4 14:13:42 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Polaczenie do Wi-fi","T":"2023-01-04 14:13:25"}'
Jan  4 14:13:42 ESP-TVLED EspEasy: Command: Publish
Jan  4 14:13:42 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Polaczenie do Wi-fi","T":"2023-01-04 14:13:25"}
Jan  4 14:13:42 ESP-TVLED EspEasy: EVENT: MQTT#Connected
Jan  4 14:13:43 ESP-TVLED EspEasy: EVENT: ustawsile=20
Jan  4 14:13:43 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 50","T":"2023-01-04 14:13:25"}'
Jan  4 14:13:43 ESP-TVLED EspEasy: Command: Publish
Jan  4 14:13:43 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Siła świecenia - 50","T":"2023-01-04 14:13:25"}
Jan  4 14:13:43 ESP-TVLED EspEasy: ACT  : TaskValueSet 4,1,20
Jan  4 14:13:43 ESP-TVLED EspEasy: Command: TaskValueSet
Jan  4 14:13:43 ESP-TVLED EspEasy: ACT  : TaskValueSet 4,3,20*1023/100
Jan  4 14:13:43 ESP-TVLED EspEasy: Command: TaskValueSet
Jan  4 14:13:43 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/UstawSile,20
Jan  4 14:13:43 ESP-TVLED EspEasy: Command: Publish
Jan  4 14:13:43 ESP-TVLED EspEasy: Publish: ESP_TVLED/UstawSile20
Jan  4 14:13:43 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20","T":"2023-01-04 14:13:25"}'
Jan  4 14:13:43 ESP-TVLED EspEasy: Command: Publish
Jan  4 14:13:43 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Siła świecenia  - 20","T":"2023-01-04 14:13:25"}
Jan  4 14:13:43 ESP-TVLED EspEasy: NTP  : NTP replied: delay 20 mSec Accuracy increased by 0.374 seconds
Jan  4 14:13:43 ESP-TVLED EspEasy: Time set to 1672838023.374 Time adjusted by 16950.71 msec. Wander: 0.000 msec/second Source: NTP
Jan  4 14:13:43 ESP-TVLED EspEasy: Local time: 2023-01-04 14:13:43
Jan  4 14:13:43 ESP-TVLED EspEasy: EVENT: Time#Set
Jan  4 14:14:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:14
Jan  4 14:14:08 ESP-TVLED EspEasy: WD   : Uptime 1 ConnectFailures 0 FreeMem 17736 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:14:38 ESP-TVLED EspEasy: WD   : Uptime 1 ConnectFailures 0 FreeMem 16864 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:15:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:15
Jan  4 14:15:08 ESP-TVLED EspEasy: WD   : Uptime 2 ConnectFailures 0 FreeMem 16680 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:15:38 ESP-TVLED EspEasy: WD   : Uptime 2 ConnectFailures 0 FreeMem 16560 WiFiStatus 3 ESPeasy internal wifi status: Conn. IP Init
Jan  4 14:16:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,14:16
Thank you for the help in advance.

User avatar
Ath
Normal user
Posts: 3419
Joined: 10 Jun 2018, 12:06
Location: NL

Re: Memory leaking, restarting

#2 Post by Ath » 04 Jan 2023, 19:18

What plugins are active, and what rules are in use?
/Ton (PayPal.me)

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#3 Post by hyper_pl » 04 Jan 2023, 19:32

I don't know how to check which plugin are active.
If it's about devices, I use switch input x2 and Generic Dummy device.

Rules:

Code: Select all

On System#Boot do
 TaskValueSet 4,1,50
 TaskValueSet 4,3,512
 #pwm,14,0
 TaskValueSet 4,2,0
 let,1,0
 let,2,0
 Publish %sysname%/Inf,"Uruchomienie"
endon

on Wifi#Disconnected do

endon
 
on Wifi#Connected do
 Publish %sysname%/Inf,'{"A":"Polaczenie do Wi-fi","T":"%lcltime%"}'
endon

On czujnik#State=1 do
 Publish %sysname%/Inf,'{"A":"Aktywacja czyjnika - 1","T":"%lcltime%"}'
endon

On czujnik#State=0 do
 Publish %sysname%/Inf,'{"A":"Aktywacja czyjnika - 0","T":"%lcltime%"}'
 if [VAR#1]=0 
  if %systime% > %sunset%
   event Zapal
   let,1,1
   Publish %sysname%/Inf,'{"A":"Aktywacja czyjnika - włączenie.","T":"%lcltime%"}'
  endif
  if %systime% < %sunrise+1h%
   event Zapal
   let,1,1
   Publish %sysname%/Inf,'{"A":"Aktywacja czyjnika - włączenie.","T":"%lcltime%"}'
  endif
 else
   LogEntry "Czujnik wyłączenie swiatla"
   event Zgas
   let,1,0
   Publish %sysname%/Inf,'{"A":"Aktywacja czyjnika - wyłączenie.","T":"%lcltime%"}'
  endif
endon

on UstawSile do
 Publish %sysname%/Inf,'{"A":"Siła świecenia - [Zmienna#Sila]","T":"%lcltime%"}'
 if %eventvalue% > 100 or %eventvalue% < 0
  TaskValueSet 4,1,100
  TaskValueSet 4,3,1023
  Publish %sysname%/UstawSile,100
 else
  TaskValueSet 4,1,%eventvalue%
  TaskValueSet 4,3,[Zmienna#Sila]*1023/100
  Publish %sysname%/UstawSile,[Zmienna#Sila]
  Publish %sysname%/Inf,'{"A":"Siła świecenia  - [Zmienna#Sila]","T":"%lcltime%"}'
 endif
 if [Zmienna#Swieci] = 1
  event Zapal
 endif
endon

on Zapal do
 pwm,14,[Zmienna#Wartosc],1000
 TaskValueSet 4,2,1
 Publish %sysname%/Zmienna/Swieci,[Zmienna#Swieci]
endon

on Zgas do
 pwm,14,0,3000,50000
 TaskValueSet,4,2,0
 Publish %sysname%/Zmienna/Swieci,[Zmienna#Swieci]
endon

TD-er
Core team member
Posts: 8644
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Memory leaking, restarting

#4 Post by TD-er » 04 Jan 2023, 21:04

Maybe first try to replace the "event" commands in your rules with "asyncevent"?

Problem with "event" is that it will start a new rules parsing instance to handle the new event.
AsyncEvent will add the event to an event queue and thus process them at a later time (not that much later).

User avatar
Ath
Normal user
Posts: 3419
Joined: 10 Jun 2018, 12:06
Location: NL

Re: Memory leaking, restarting

#5 Post by Ath » 04 Jan 2023, 21:07

Can you show a screenshot of the Devices page, as I suspect that the rules are getting in a recursion somewhere.
/Ton (PayPal.me)

TD-er
Core team member
Posts: 8644
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Memory leaking, restarting

#6 Post by TD-er » 04 Jan 2023, 21:35

Ath wrote: 04 Jan 2023, 21:07 Can you show a screenshot of the Devices page, as I suspect that the rules are getting in a recursion somewhere.
That's why I suggested to use asyncevent :)

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#7 Post by hyper_pl » 04 Jan 2023, 21:50


User avatar
Ath
Normal user
Posts: 3419
Joined: 10 Jun 2018, 12:06
Location: NL

Re: Memory leaking, restarting

#8 Post by Ath » 04 Jan 2023, 21:53

The board has a nice attachment feature, that will keep the screenshot in this post, so it won't vanish after some time, and we don't know what we where talking about... :o
/Ton (PayPal.me)

TD-er
Core team member
Posts: 8644
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Memory leaking, restarting

#9 Post by TD-er » 04 Jan 2023, 22:01

You are running 2 pwm commands (and one at boot, with a # in front of it...)

However these 2 pwm commands are set to "fade" as they have a duration.
Please remove this duration as "fading" is a blocking call. (still need to look into this)
The last one also has an incredible high frequency set of 50 kHz. No idea why you would need it, but it is for sure something an ESP8266 will struggle with. Especially when it needs to fade.
Apart from that, fade at 50 kHz will be useless as it can hardly generate a 50kHz signal, let alone to have some PWM signal on top of that.

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#10 Post by hyper_pl » 04 Jan 2023, 22:10

I added image instead of link as Ath suggested
devices.png
devices.png (43.71 KiB) Viewed 1892 times

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#11 Post by hyper_pl » 05 Jan 2023, 09:08

For several hours everything is ok.
I replaced event command with asyncevent.
I removed 50kHz frequency in pwm command and I shortened the fading time from 3000 to 1000.

Thank you all for your really quick help !

TD-er
Core team member
Posts: 8644
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Memory leaking, restarting

#12 Post by TD-er » 05 Jan 2023, 14:45

Thanks for getting back with some (preliminary) results :)

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#13 Post by hyper_pl » 11 Jan 2023, 21:57

I'm back.
Unfortunately, the problem still occurs, but not as often.
I removed fading from pwm, disabled switch device (task 3) and minimized rules.

Rules:

Code: Select all

On System#Boot do
 TaskValueSet 4,1,20
 TaskValueSet 4,3,205
 TaskValueSet 4,2,0
 let,1,0
 let,2,0
 Publish %sysname%/Inf,"Uruchomienie"
endon
 
on Wifi#Connected do
 Publish %sysname%/Inf,'{"A":"Polaczenie do Wi-fi","T":"%lcltime%"}'
endon

on UstawSile do
 Publish %sysname%/Inf,'{"A":"Siła świecenia - [Zmienna#Sila]","T":"%lcltime%"}'
 if %eventvalue% > 100 or %eventvalue% < 0
  TaskValueSet 4,1,100
  TaskValueSet 4,3,1023
  Publish %sysname%/UstawSile,100
 else
  TaskValueSet 4,1,%eventvalue%
  TaskValueSet 4,3,[Zmienna#Sila]*1023/100
  Publish %sysname%/UstawSile,[Zmienna#Sila]
  Publish %sysname%/Inf,'{"A":"Siła świecenia  - [Zmienna#Sila]","T":"%lcltime%"}'
 endif
 if [Zmienna#Swieci] = 1
  AsyncEvent Zapal
 endif
endon

on Zapal do
 pwm,14,[Zmienna#Wartosc]
 TaskValueSet 4,2,1
 Publish %sysname%/Zmienna/Swieci,[Zmienna#Swieci]
endon

on Zgas do
 pwm,14,0
 TaskValueSet,4,2,0
 Publish %sysname%/Zmienna/Swieci,[Zmienna#Swieci]
endon

Debug Log: (FreeMem 18648 --> 3312, then mqtt connection lost, wifi reconnect)

Code: Select all

WD   : Uptime 125 ConnectFailures 0 FreeMem 18648 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:34:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.21 loopCounterMax: 309278 loopCounterLast: 211806
Jan 11 15:34:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211806/1982/9/89.85
Jan 11 15:34:51 ESP-TVLED EspEasy: WD   : Uptime 125 ConnectFailures 0 FreeMem 18648 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:34:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.72 loopCounterMax: 309278 loopCounterLast: 211027
Jan 11 15:34:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211027/1982/9/89.79
Jan 11 15:35:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:35
Jan 11 15:35:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:35 Processing time:5 milliSeconds
Jan 11 15:35:21 ESP-TVLED EspEasy: WD   : Uptime 126 ConnectFailures 0 FreeMem 18648 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:35:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.10 loopCounterMax: 309278 loopCounterLast: 211987
Jan 11 15:35:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211987/1982/9/89.89
Jan 11 15:35:51 ESP-TVLED EspEasy: WD   : Uptime 126 ConnectFailures 0 FreeMem 18648 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:35:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.17 loopCounterMax: 309278 loopCounterLast: 211818
Jan 11 15:35:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211818/1982/9/89.89
Jan 11 15:36:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:36
Jan 11 15:36:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:36 Processing time:5 milliSeconds
Jan 11 15:36:21 ESP-TVLED EspEasy: WD   : Uptime 127 ConnectFailures 0 FreeMem 18624 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:36:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.61 loopCounterMax: 309278 loopCounterLast: 211211
Jan 11 15:36:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211211/1982/9/89.74
Jan 11 15:36:51 ESP-TVLED EspEasy: WD   : Uptime 127 ConnectFailures 0 FreeMem 18576 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:36:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.85 loopCounterMax: 309278 loopCounterLast: 210821
Jan 11 15:36:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210821/1982/9/89.75
Jan 11 15:37:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:37
Jan 11 15:37:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:37 Processing time:5 milliSeconds
Jan 11 15:37:21 ESP-TVLED EspEasy: WD   : Uptime 128 ConnectFailures 0 FreeMem 18480 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:37:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.06 loopCounterMax: 309278 loopCounterLast: 210548
Jan 11 15:37:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210548/1982/9/89.80
Jan 11 15:37:51 ESP-TVLED EspEasy: WD   : Uptime 128 ConnectFailures 0 FreeMem 18456 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:37:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.44 loopCounterMax: 309278 loopCounterLast: 211448
Jan 11 15:37:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211448/1982/9/89.89
Jan 11 15:38:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:38
Jan 11 15:38:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:38 Processing time:4 milliSeconds
Jan 11 15:38:21 ESP-TVLED EspEasy: WD   : Uptime 129 ConnectFailures 0 FreeMem 18456 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:38:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.72 loopCounterMax: 309278 loopCounterLast: 211054
Jan 11 15:38:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211054/1982/9/89.79
Jan 11 15:38:51 ESP-TVLED EspEasy: WD   : Uptime 129 ConnectFailures 0 FreeMem 18408 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:38:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.33 loopCounterMax: 309278 loopCounterLast: 211602
Jan 11 15:38:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211602/1982/9/89.82
Jan 11 15:39:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:39
Jan 11 15:39:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:39 Processing time:4 milliSeconds
Jan 11 15:39:21 ESP-TVLED EspEasy: WD   : Uptime 130 ConnectFailures 0 FreeMem 18240 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:39:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.54 loopCounterMax: 309278 loopCounterLast: 209839
Jan 11 15:39:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 209839/1982/9/89.74
Jan 11 15:39:51 ESP-TVLED EspEasy: WD   : Uptime 130 ConnectFailures 0 FreeMem 18144 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:39:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 136.71 loopCounterMax: 309278 loopCounterLast: 212532
Jan 11 15:39:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 212532/1982/9/89.86
Jan 11 15:40:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:40
Jan 11 15:40:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:40 Processing time:5 milliSeconds
Jan 11 15:40:21 ESP-TVLED EspEasy: WD   : Uptime 131 ConnectFailures 0 FreeMem 18144 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:40:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.86 loopCounterMax: 309278 loopCounterLast: 210847
Jan 11 15:40:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210847/1982/9/89.79
Jan 11 15:40:51 ESP-TVLED EspEasy: WD   : Uptime 131 ConnectFailures 0 FreeMem 18096 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:40:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.83 loopCounterMax: 309278 loopCounterLast: 210861
Jan 11 15:40:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210861/1982/9/89.89
Jan 11 15:41:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:41
Jan 11 15:41:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:41 Processing time:4 milliSeconds
Jan 11 15:41:21 ESP-TVLED EspEasy: WD   : Uptime 132 ConnectFailures 0 FreeMem 17808 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:41:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.12 loopCounterMax: 309278 loopCounterLast: 210474
Jan 11 15:41:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210474/1982/9/89.68
Jan 11 15:41:51 ESP-TVLED EspEasy: WD   : Uptime 132 ConnectFailures 0 FreeMem 17544 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:41:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.80 loopCounterMax: 309278 loopCounterLast: 210902
Jan 11 15:41:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210902/1982/9/89.85
Jan 11 15:42:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:42
Jan 11 15:42:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:42 Processing time:5 milliSeconds
Jan 11 15:42:21 ESP-TVLED EspEasy: WD   : Uptime 133 ConnectFailures 0 FreeMem 17520 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:42:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.83 loopCounterMax: 309278 loopCounterLast: 210856
Jan 11 15:42:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210856/1982/9/89.86
Jan 11 15:42:51 ESP-TVLED EspEasy: WD   : Uptime 133 ConnectFailures 0 FreeMem 17472 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:42:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.84 loopCounterMax: 309278 loopCounterLast: 210840
Jan 11 15:42:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210840/1982/9/89.75
Jan 11 15:43:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:43
Jan 11 15:43:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:43 Processing time:5 milliSeconds
Jan 11 15:43:21 ESP-TVLED EspEasy: WD   : Uptime 134 ConnectFailures 0 FreeMem 17040 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:43:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.60 loopCounterMax: 309278 loopCounterLast: 211220
Jan 11 15:43:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211220/1982/9/89.79
Jan 11 15:43:51 ESP-TVLED EspEasy: WD   : Uptime 134 ConnectFailures 0 FreeMem 16464 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:43:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.07 loopCounterMax: 309278 loopCounterLast: 210500
Jan 11 15:43:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210500/1982/9/89.83
Jan 11 15:44:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:44
Jan 11 15:44:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:44 Processing time:5 milliSeconds
Jan 11 15:44:21 ESP-TVLED EspEasy: WD   : Uptime 135 ConnectFailures 0 FreeMem 16320 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:44:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.02 loopCounterMax: 309278 loopCounterLast: 210568
Jan 11 15:44:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210568/1982/9/89.76
Jan 11 15:44:51 ESP-TVLED EspEasy: WD   : Uptime 135 ConnectFailures 0 FreeMem 16272 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:44:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.03 loopCounterMax: 309278 loopCounterLast: 210601
Jan 11 15:44:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210601/1982/9/89.87
Jan 11 15:45:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:45
Jan 11 15:45:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:45 Processing time:5 milliSeconds
Jan 11 15:45:21 ESP-TVLED EspEasy: WD   : Uptime 136 ConnectFailures 0 FreeMem 15624 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:45:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.36 loopCounterMax: 309278 loopCounterLast: 210110
Jan 11 15:45:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210110/1982/9/89.68
Jan 11 15:45:51 ESP-TVLED EspEasy: WD   : Uptime 136 ConnectFailures 0 FreeMem 14448 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:45:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.39 loopCounterMax: 309278 loopCounterLast: 210026
Jan 11 15:45:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210026/1982/9/89.73
Jan 11 15:46:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:46
Jan 11 15:46:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:46 Processing time:5 milliSeconds
Jan 11 15:46:21 ESP-TVLED EspEasy: WD   : Uptime 137 ConnectFailures 0 FreeMem 13920 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:46:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.02 loopCounterMax: 309278 loopCounterLast: 210610
Jan 11 15:46:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210610/1982/9/89.75
Jan 11 15:46:51 ESP-TVLED EspEasy: WD   : Uptime 137 ConnectFailures 0 FreeMem 13848 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:46:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.91 loopCounterMax: 309278 loopCounterLast: 210741
Jan 11 15:46:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210741/1982/9/89.65
Jan 11 15:47:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:47
Jan 11 15:47:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:47 Processing time:5 milliSeconds
Jan 11 15:47:21 ESP-TVLED EspEasy: WD   : Uptime 138 ConnectFailures 0 FreeMem 12960 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:47:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.65 loopCounterMax: 309278 loopCounterLast: 209688
Jan 11 15:47:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 209688/1982/9/89.62
Jan 11 15:47:51 ESP-TVLED EspEasy: WD   : Uptime 138 ConnectFailures 0 FreeMem 11160 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:47:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.45 loopCounterMax: 309278 loopCounterLast: 209933
Jan 11 15:47:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 209933/1982/9/89.53
Jan 11 15:48:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:48
Jan 11 15:48:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:48 Processing time:5 milliSeconds
Jan 11 15:48:21 ESP-TVLED EspEasy: WD   : Uptime 139 ConnectFailures 0 FreeMem 9840 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:48:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.60 loopCounterMax: 309278 loopCounterLast: 209759
Jan 11 15:48:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 209759/1982/9/89.62
Jan 11 15:48:51 ESP-TVLED EspEasy: WD   : Uptime 139 ConnectFailures 0 FreeMem 9600 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:48:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.27 loopCounterMax: 309278 loopCounterLast: 210206
Jan 11 15:48:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210206/1982/9/89.68
Jan 11 15:49:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:49
Jan 11 15:49:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:49 Processing time:5 milliSeconds
Jan 11 15:49:21 ESP-TVLED EspEasy: WD   : Uptime 140 ConnectFailures 0 FreeMem 8496 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:49:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 137.83 loopCounterMax: 309278 loopCounterLast: 210832
Jan 11 15:49:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210832/1982/9/89.38
Jan 11 15:49:51 ESP-TVLED EspEasy: WD   : Uptime 140 ConnectFailures 0 FreeMem 6192 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:49:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.60 loopCounterMax: 309278 loopCounterLast: 209736
Jan 11 15:49:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 209736/1982/9/89.47
Jan 11 15:50:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:50
Jan 11 15:50:01 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:50 Processing time:8 milliSeconds
Jan 11 15:50:21 ESP-TVLED EspEasy: WD   : Uptime 141 ConnectFailures 0 FreeMem 4152 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:50:22 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.14 loopCounterMax: 309278 loopCounterLast: 210453
Jan 11 15:50:22 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 210453/1982/9/89.30
Jan 11 15:50:51 ESP-TVLED EspEasy: WD   : Uptime 141 ConnectFailures 0 FreeMem 3312 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:50:52 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 356249 avgLoopDuration: 138.72 loopCounterMax: 309278 loopCounterLast: 209606
Jan 11 15:50:52 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 209606/1982/9/89.45
Jan 11 15:50:58 ESP-TVLED EspEasy: MQTT : Connection lost, state: Disconnected
Jan 11 15:50:58 ESP-TVLED EspEasy: Read settings: ControllerSettings index: 0
Jan 11 15:50:58 ESP-TVLED EspEasy: Read settings: ControllerSettings index: 0
Jan 11 15:51:21 ESP-TVLED EspEasy: WIFI : Arduino wifi status: WL_CONNECTED 3 ESPeasy internal wifi status: IP 
Jan 11 15:51:21 ESP-TVLED EspEasy: firstLoopConnectionsEstablished
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: WiFi#Connected
Jan 11 15:51:21 ESP-TVLED EspEasy: RuleDebug: 001: on Wifi#Connected do
Jan 11 15:51:21 ESP-TVLED EspEasy: Bitwise: {a:Polaczenie do Wi-fi:T:"1970-01-01 00:00:12"}
Jan 11 15:51:21 ESP-TVLED EspEasy: RuleDebug: 000: Publish ESP_TVLED/Inf,'{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}'
Jan 11 15:51:21 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}'
Jan 11 15:51:21 ESP-TVLED EspEasy: Bitwise: {a:Polaczenie do Wi-fi:T:"1970-01-01 00:00:12"}
Jan 11 15:51:21 ESP-TVLED EspEasy: Command: Publish
Jan 11 15:51:21 ESP-TVLED EspEasy: Publish ESP_TVLED/Inf,'{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}'
Jan 11 15:51:21 ESP-TVLED EspEasy: Par1: 0 Par2: 0 Par3: 0 Par4: 0 Par5: 0
Jan 11 15:51:21 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}
Jan 11 15:51:21 ESP-TVLED EspEasy: Read settings: ControllerSettings index: 0
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: WiFi#Connected Processing time:76 milliSeconds
Jan 11 15:51:21 ESP-TVLED EspEasy: WiFi : Missed connected event
Jan 11 15:51:21 ESP-TVLED EspEasy: WIFI : Connected! AP: HypersNet_2.4G (00:72:63:30:00:36) Ch: 6 Duration: 984 ms
Jan 11 15:51:21 ESP-TVLED EspEasy: WiFi : WiFi services initialized
Jan 11 15:51:21 ESP-TVLED EspEasy: NTP  : NTP host 0.pl.pool.ntp.org (185.231.150.1) queried
Jan 11 15:51:21 ESP-TVLED EspEasy: NTP  : NTP replied: delay 30 mSec Accuracy increased by 0.493 seconds
Jan 11 15:51:21 ESP-TVLED EspEasy: Time set to 1673448681.493
Jan 11 15:51:21 ESP-TVLED EspEasy: Current Time Zone:  DST time start: 2023-03-26 02:00:00 offset: 120 min STD time start: 2023-10-29 03:00:00 offset: 60 min
Jan 11 15:51:21 ESP-TVLED EspEasy: Local time: 2023-01-11 15:51:21
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: WiFi#ChangedAccesspoint
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: WiFi#ChangedAccesspoint Processing time:6 milliSeconds
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: WiFi#ChangedWiFichannel
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: WiFi#ChangedWiFichannel Processing time:5 milliSeconds
Jan 11 15:51:21 ESP-TVLED EspEasy: Read settings: ControllerSettings index: 0
Jan 11 15:51:21 ESP-TVLED EspEasy: Read settings: ControllerSettings index: 0
Jan 11 15:51:21 ESP-TVLED EspEasy: MQTT : Intentional reconnect
Jan 11 15:51:21 ESP-TVLED EspEasy: MQTT : Connected to broker with client ID: ESP_TVLED_0
Jan 11 15:51:21 ESP-TVLED EspEasy: Subscribed to: ESP_TVLED/#
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:51
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:51 Processing time:5 milliSeconds
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: Time#Initialized
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: Time#Initialized Processing time:5 milliSeconds
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: MQTT#Connected
Jan 11 15:51:21 ESP-TVLED EspEasy: EVENT: MQTT#Connected Processing time:5 milliSeconds
Jan 11 15:51:21 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 326652 avgLoopDuration: 2157.91 loopCounterMax: 250000 loopCounterLast: 1025
Jan 11 15:51:21 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 1021/73/9/1.01
Jan 11 15:51:22 ESP-TVLED EspEasy: EVENT: UstawSile=20
Jan 11 15:51:22 ESP-TVLED EspEasy: RuleDebug: 001: on UstawSile do
Jan 11 15:51:22 ESP-TVLED EspEasy: Read settings: TaskSettings index: 0
Jan 11 15:51:22 ESP-TVLED EspEasy: Read settings: TaskSettings index: 1
Jan 11 15:51:22 ESP-TVLED EspEasy: Read settings: TaskSettings index: 2
Jan 11 15:51:22 ESP-TVLED EspEasy: Read settings: TaskSettings index: 3
Jan 11 15:51:22 ESP-TVLED EspEasy: DEBUG DEV: Parsed String='Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 20'
Jan 11 15:51:22 ESP-TVLED EspEasy: Bitwise: {a:Siła świecenia - 20:T:"2023-01-11 15:51:21"}
Jan 11 15:51:22 ESP-TVLED EspEasy: RuleDebug: 000: Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 20","T":"2023-01-11 15:51:21"}'
Jan 11 15:51:22 ESP-TVLED EspEasy: Bitwise: {a:Siła świecenia - 20:T:"2023-01-11 15:51:21"}
Jan 11 15:51:22 ESP-TVLED EspEasy: Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 20","T":"2023-01-11 15:51:21"}'
Jan 11 15:51:22 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Siła świecenia - 20","T":"2023-01-11 15:51:21"}
Jan 11 15:51:22 ESP-TVLED EspEasy: RuleDebug: 000: if 20 > 100 or 20 < 0
Jan 11 15:51:22 ESP-TVLED EspEasy: timeStringToSeconds: "20" --> invalid
Jan 11 15:51:22 ESP-TVLED EspEasy: conditionMatch: "20 > 100 or 20 < 0" --> "20" > "100" --> false (20 > 100)
Jan 11 15:51:22 ESP-TVLED EspEasy: conditionMatchExtended: false "20 > 100 or 20 < 0"
Jan 11 15:51:22 ESP-TVLED EspEasy: Lev.1: [if false || false]=false
Jan 11 15:51:22 ESP-TVLED EspEasy: RuleDebug: 000: TaskValueSet 4,1,20
Jan 11 15:51:22 ESP-TVLED EspEasy: Command: TaskValueSet
Jan 11 15:51:22 ESP-TVLED EspEasy: Par1: 0 Par2: 20 Par3: 0 Par4: 0 Par5: 0
Jan 11 15:51:22 ESP-TVLED EspEasy: DEBUG DEV: Parsed String='Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20'
Jan 11 15:51:22 ESP-TVLED EspEasy: Bitwise: {a:Siła świecenia  - 20:T:"2023-01-11 15:51:21"}
Jan 11 15:51:22 ESP-TVLED EspEasy: RuleDebug: 000: Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20","T":"2023-01-11 15:51:21"}'
Jan 11 15:51:22 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20","T":"2023-01-11 15:51:21"}'
Jan 11 15:51:22 ESP-TVLED EspEasy: Bitwise: {a:Siła świecenia  - 20:T:"2023-01-11 15:51:21"}
Jan 11 15:51:22 ESP-TVLED EspEasy: Command: Publish
Jan 11 15:51:50 ESP-TVLED EspEasy: WD   : Uptime 1 ConnectFailures 0 FreeMem 17888 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:51:51 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 146.23 loopCounterMax: 250000 loopCounterLast: 198524
Jan 11 15:51:51 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 198524/1969/9/89.67
Jan 11 15:52:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:52
Jan 11 15:52:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:52 Processing time:5 milliSeconds
Jan 11 15:52:20 ESP-TVLED EspEasy: WD   : Uptime 1 ConnectFailures 0 FreeMem 17864 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:52:21 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 144.01 loopCounterMax: 250000 loopCounterLast: 201507
Jan 11 15:52:21 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 201507/1982/9/89.70
Jan 11 15:52:50 ESP-TVLED EspEasy: WD   : Uptime 2 ConnectFailures 0 FreeMem 17840 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:52:51 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 143.98 loopCounterMax: 250000 loopCounterLast: 201609
Jan 11 15:52:51 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 201609/1982/9/89.77
Jan 11 15:53:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:53
Jan 11 15:53:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:53 Processing time:5 milliSeconds
Jan 11 15:53:20 ESP-TVLED EspEasy: WD   : Uptime 2 ConnectFailures 0 FreeMem 17840 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:53:21 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 144.09 loopCounterMax: 250000 loopCounterLast: 201412
Jan 11 15:53:21 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 201412/1982/9/89.72
Jan 11 15:53:50 ESP-TVLED EspEasy: WD   : Uptime 3 ConnectFailures 0 FreeMem 17840 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:53:51 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 144.49 loopCounterMax: 250000 loopCounterLast: 200915
Jan 11 15:53:51 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 200915/1982/9/89.79
Jan 11 15:54:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:54
Jan 11 15:54:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:54 Processing time:5 milliSeconds
Jan 11 15:54:20 ESP-TVLED EspEasy: WD   : Uptime 3 ConnectFailures 0 FreeMem 17792 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:54:21 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 157.15 loopCounterMax: 250000 loopCounterLast: 185720
Jan 11 15:54:21 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 185720/1982/9/89.63
Jan 11 15:54:50 ESP-TVLED EspEasy: WD   : Uptime 4 ConnectFailures 0 FreeMem 17744 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:54:51 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 156.96 loopCounterMax: 250000 loopCounterLast: 185965
Jan 11 15:54:51 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 185965/1982/9/89.62
Jan 11 15:55:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:55
Jan 11 15:55:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:55 Processing time:5 milliSeconds
Jan 11 15:55:20 ESP-TVLED EspEasy: WD   : Uptime 4 ConnectFailures 0 FreeMem 17720 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:55:21 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 156.87 loopCounterMax: 250000 loopCounterLast: 186041
Jan 11 15:55:21 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 186041/1982/9/89.67
Jan 11 15:55:50 ESP-TVLED EspEasy: WD   : Uptime 5 ConnectFailures 0 FreeMem 17720 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:55:51 ESP-TVLED EspEasy: LoopStats: shortestLoop: 120 longestLoop: 350767 avgLoopDuration: 157.06 loopCounterMax: 250000 loopCounterLast: 185846
Jan 11 15:55:51 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 185846/1982/9/89.64
Jan 11 15:56:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:56
Jan 11 15:56:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:56 Processing time:5 milliSeconds
Jan 11 15:56:20 ESP-TVLED EspEasy: WD   : Uptime 5 ConnectFailures 0 FreeMem 18136 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:56:21 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 350767 avgLoopDuration: 155.77 loopCounterMax: 309278 loopCounterLast: 187283
Jan 11 15:56:21 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 187283/1978/9/89.62
Jan 11 15:56:50 ESP-TVLED EspEasy: WD   : Uptime 6 ConnectFailures 0 FreeMem 18360 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:56:51 ESP-TVLED EspEasy: LoopStats: shortestLoop: 97 longestLoop: 350767 avgLoopDuration: 137.11 loopCounterMax: 309278 loopCounterLast: 211795
Jan 11 15:56:51 ESP-TVLED EspEasy: Scheduler stats: (called/tasks/max_length/idle%) 211795/1982/9/89.89
Jan 11 15:57:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:57
Jan 11 15:57:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,15:57 Processing time:5 milliSeconds

Thank you in advance for any new idea.

User avatar
Ath
Normal user
Posts: 3419
Joined: 10 Jun 2018, 12:06
Location: NL

Re: Memory leaking, restarting

#14 Post by Ath » 11 Jan 2023, 22:12

Did you filter out the Publish output from the log? I'd expect there to be a couple of these, besides the ones at boot.

- What MQTT server is configured and is it in the local network or external on the internet?
- What Minimum Send interval and Client Timeout are set?
- What is the Max Queue depth set to?
- What is Check reply set to?
/Ton (PayPal.me)

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#15 Post by hyper_pl » 11 Jan 2023, 23:32

Every Publish string in log between restarts:

Code: Select all


Jan 11 13:29:00 ESP-TVLED EspEasy: EVENT: Clock#Time=Wed,13:29 Processing time:9 milliSeconds
Jan 11 13:29:17 ESP-TVLED EspEasy: MQTT : Connection lost, state: Disconnected
Jan 11 13:29:17 ESP-TVLED EspEasy: MQTT : Cannot check, out of RAM
Jan 11 13:29:17 ESP-TVLED EspEasy: EVENT: MQTT#Disconnected
Jan 11 13:29:17 ESP-TVLED EspEasy: EVENT: MQTT#Disconnected Processing time:9 milliSeconds
Jan 11 13:29:18 ESP-TVLED EspEasy: MQTT : Cannot check, out of RAM
Jan 11 13:29:51 ESP-TVLED EspEasy: WIFI : Arduino wifi status: WL_CONNECTED 3 ESPeasy internal wifi status: IP 
Jan 11 13:29:51 ESP-TVLED EspEasy: firstLoopConnectionsEstablished
Jan 11 13:29:51 ESP-TVLED EspEasy: EVENT: WiFi#Connected

Jan 11 13:29:51 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}'
Jan 11 13:29:51 ESP-TVLED EspEasy: Command: Publish
Jan 11 13:29:51 ESP-TVLED EspEasy: Publish ESP_TVLED/Inf,'{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}'
Jan 11 13:29:51 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Polaczenie do Wi-fi","T":"1970-01-01 00:00:12"}

Jan 11 13:29:51 ESP-TVLED EspEasy: EVENT: MQTT#Connected
Jan 11 13:29:51 ESP-TVLED EspEasy: EVENT: MQTT#Connected Processing time:5 milliSeconds

Jan 11 13:29:52 ESP-TVLED EspEasy: Bitwise: {a:Siła świecenia - 20:T:"2023-01-11 13:29:51"}
Jan 11 13:29:52 ESP-TVLED EspEasy: RuleDebug: 000: Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 20","T":"2023-01-11 13:29:51"}'
Jan 11 13:29:52 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 20","T":"2023-01-11 13:29:51"}'
Jan 11 13:29:52 ESP-TVLED EspEasy: Publish ESP_TVLED/Inf,'{"A":"Siła świecenia - 20","T":"2023-01-11 13:29:51"}'
Jan 11 13:29:52 ESP-TVLED EspEasy: Publish: ESP_TVLED/Inf{"A":"Siła świecenia - 20","T":"2023-01-11 13:29:51"}
Jan 11 13:29:52 ESP-TVLED EspEasy: RuleDebug: 000: Publish ESP_TVLED/UstawSile,100
Jan 11 13:29:52 ESP-TVLED EspEasy: Command: Publish
Jan 11 13:29:52 ESP-TVLED EspEasy: DEBUG DEV: Parsed String='Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20'
Jan 11 13:29:52 ESP-TVLED EspEasy: RuleDebug: 000: Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20","T":"2023-01-11 13:29:51"}'
Jan 11 13:29:52 ESP-TVLED EspEasy: ACT  : Publish ESP_TVLED/Inf,'{"A":"Siła świecenia  - 20","T":"2023-01-11 13:29:51"}'

... 2:20h without publish command

Jan 11 15:50:51 ESP-TVLED EspEasy: WD   : Uptime 141 ConnectFailures 0 FreeMem 3312 WiFiStatus WL_CONNECTED 3 ESPeasy internal wifi status: Conn. IP Init
Jan 11 15:50:58 ESP-TVLED EspEasy: MQTT : Connection lost, state: Disconnected
- MQTT server is in local network, docker installation https://registry.hub.docker.com/r/spants/mqtt/ on Unraid
- Minimum send interval : 100 ms, Client Timeout: 100 ms,
- Max Queue Depth: 10,
- Check reply: Ignore Acknowledgement

TD-er
Core team member
Posts: 8644
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Memory leaking, restarting

#16 Post by TD-er » 12 Jan 2023, 01:03

Perhaps the number of events also takes up memory.

Do you also use sending logs to a syslog server?
I know this is also sometimes acting a bit strange.
Just as if those UDP packets are kept in the IP stack part of the SDK without being released.

hyper_pl
New user
Posts: 8
Joined: 04 Jan 2023, 18:48

Re: Memory leaking, restarting

#17 Post by hyper_pl » 13 Jan 2023, 12:15

Yes, I had configured remote syslog server. I turned it off.
I removed few publish command and changed little bit rules.
Now, even if wifi restarts I don't see impact on led stripes.

Now I'll see how often the wifi connection restarts due to lack of memory.

TD-er
Core team member
Posts: 8644
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Memory leaking, restarting

#18 Post by TD-er » 13 Jan 2023, 15:02

So it still seems to fill up the memory?

Post Reply

Who is online

Users browsing this forum: Google [Bot] and 26 guests