Load always rises to 100%

Moderators: BertB, rtenklooster, Voyager, Stuntteam, Martinus

Post Reply
Message
Author
riker1
Normal user
Posts: 143
Joined: 26 Dec 2017, 18:02

Load always rises to 100%

#1 Post by riker1 » 15 May 2018, 07:37

Hi
having a lolin nodemcu running with mega-20180409

even after rebooting, load rises to 100% .
There is only one PIR device connected.
One simple rule in place

two controller - fhem and mqtt

free Mem: 13568 (8792 - addLog)

How can I identify where the load used ?

can it be a power problem?

Log is not saying a lot to me?

Thanks Thomas

TD-er
Normal user
Posts: 940
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Load always rises to 100%

#2 Post by TD-er » 16 May 2018, 21:24

And here the same reaction as I just posted on the Github, so I'll do it with a link:
https://github.com/letscontrolit/ESPEas ... -389635356

If the webpages load quickly and no frequent WiFi and/or MQTT reconnects, then the reported CPU load is just misinforming and/or misleading.

And my ideal is that the CPU load will be as constant as possible, which means that with the current implementation to compute the CPU load, it will report 100% always.

riker1
Normal user
Posts: 143
Joined: 26 Dec 2017, 18:02

Re: Load always rises to 100%

#3 Post by riker1 » 16 May 2018, 22:35

Hi T-D

thanks for the info

but I do not understand why different esp8266 behave different, same version of firmware deployed.

your statement sounds like a general topic

TD-er
Normal user
Posts: 940
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Load always rises to 100%

#4 Post by TD-er » 18 May 2018, 22:43

What are the differences in the config between the versions?

riker1
Normal user
Posts: 143
Joined: 26 Dec 2017, 18:02

Re: Load always rises to 100%

#5 Post by riker1 » 19 May 2018, 10:32

Hi

please see behaviour:

logging via mqtt to fhem

devices disbaled except sstinfo load.


2018-05-19_10:07:04 mqtt_DEV_esp155_KTM Connected
2018-05-19_10:07:04 mqtt_DEV_esp155_KTM sysload: 0.0
2018-05-19_10:08:00 mqtt_DEV_esp155_KTM sysload: 22.0
2018-05-19_10:08:30 mqtt_DEV_esp155_KTM sysload: 40.0
2018-05-19_10:08:57 mqtt_DEV_esp155_KTM Connection Lost
2018-05-19_10:08:57 mqtt_DEV_esp155_KTM Connected
2018-05-19_10:09:02 mqtt_DEV_esp155_KTM sysload: 0.0
2018-05-19_10:09:58 mqtt_DEV_esp155_KTM sysload: 22.0
2018-05-19_10:10:26 mqtt_DEV_esp155_KTM sysload: 40.0
2018-05-19_10:11:01 mqtt_DEV_esp155_KTM sysload: 58.0
2018-05-19_10:11:27 mqtt_DEV_esp155_KTM sysload: 76.0
2018-05-19_10:11:57 mqtt_DEV_esp155_KTM sysload: 95.0
2018-05-19_10:12:28 mqtt_DEV_esp155_KTM sysload: 100.0
2018-05-19_10:13:07 mqtt_DEV_esp155_KTM Connection Lost
2018-05-19_10:14:42 mqtt_DEV_esp155_KTM Connected
2018-05-19_10:14:44 mqtt_DEV_esp155_KTM sysload: 0.0
2018-05-19_10:15:41 mqtt_DEV_esp155_KTM sysload: 21.0
2018-05-19_10:16:13 mqtt_DEV_esp155_KTM sysload: 40.0
2018-05-19_10:16:41 mqtt_DEV_esp155_KTM sysload: 58.0
2018-05-19_10:17:12 mqtt_DEV_esp155_KTM sysload: 76.0
2018-05-19_10:17:42 mqtt_DEV_esp155_KTM sysload: 95.0
2018-05-19_10:18:12 mqtt_DEV_esp155_KTM sysload: 100.0
2018-05-19_10:19:01 mqtt_DEV_esp155_KTM Connection Lost
2018-05-19_10:29:31 mqtt_DEV_esp155_KTM Connected
2018-05-19_10:29:33 mqtt_DEV_esp155_KTM sysload: 0.0


can not attach config file. sorry

riker1
Normal user
Posts: 143
Joined: 26 Dec 2017, 18:02

Re: Load always rises to 100%

#6 Post by riker1 » 19 May 2018, 10:58

Hi

I deployed config.dat to another esp
same behaviour.

load rises fast to 100%

on the test esp , earlier config was running nice with no load increase as recognised on the other


see serial log

537083 : SYS : 100.00
537085 : EVENT: sysload#V_sysload=100.00
539109 : SYS : 100.00
539111 : EVENT: sysload#V_sysload=100.00
;ld⸮⸮|⸮$⸮|⸮$⸮c|ǃ⸮⸮{⸮c⸮b⸮⸮og⸮l'o⸮⸮⸮#x⸮⸮l{l{$p⸮'⸮⸮l⸮⸮co⸮<l⸮⸮c⸮⸮go⸮d⸮⸮$`⸮'o$`gs⸮⸮⸮ob⸮$r⸮⸮gc⸮l⸮⸮l⸮⸮⸮x⸮⸮$`⸮⸮'⸮⸮U94 :


INIT : Booting version: mega-20180516 (ESP82xx Core 2_4_1, NONOS SDK 2.2.1(cfd48f3), LWIP: 2.0.3)
95 : INIT : Warm boot #7
97 : FS : Mounting...
122 : FS : Mount successful, used 143070 bytes of 957314
455 : CRC : program checksum ...OK
486 : CRC : SecuritySettings CRC ...OK
589 : INIT : Free RAM:20640
589 : INIT : I2C
589 : INIT : SPI not enabled
608 : INFO : Plugins: 71 [Normal] [Testing] (ESP82xx Core 2_4_1, NONOS SDK 2.2.1(cfd48f3), LWIP: 2.0.3)
609 : EVENT: System#Wake
630 : WIFI : Set WiFi to STA
662 : WIFI : Connecting TR7272 attempt #0
675 : EVENT: System#Boot
695 : WD : Uptime 0 ConnectFailures 0 FreeMem 20848
3601 : WIFI : Connected! AP: TR7272 (9C:C7:A6:5A:ED:B4) Ch: 6 Duration: 2938 ms
3602 : EVENT: WiFi#ChangedAccesspoint
5667 : WIFI : DHCP IP: 192.168.0.132 (ESP132-Lolin4-test155-132) GW: 192.168.0.31 SN: 255.255.255.0 duration: 2066 ms
5678 : EVENT: WiFi#Connected
5696 : Webserver: start
5723 : Current Time Zone: DST time start: 2018-03-25 02:00:00 offset: 120 minSTD time start: 2018-10-28 03:00:00 offset: 60 min
5724 : EVENT: Time#Initialized
5749 : EVENT: Clock#Time=Sat,10:58
5778 : MQTT : Intentional reconnect
5816 : MQTT : Connected to broker with client ID: ESPClient_DC:4F:22:10:C2:35
5819 : Subscribed to: /ESP132_Lolin4_test155/testconfigfrom155/#
5821 : EVENT: MQTT#Connected
6697 : SYS : 0.00
6699 : EVENT: sysload#V_sysload=0.00
8695 : SYS : 0.00
8697 : EVENT: sysload#V_sysload=0.00
10695 : SYS : 0.00
10697 : EVENT: sysload#V_sysload=0.00
12695 : SYS : 0.00
12696 : EVENT: sysload#V_sysload=0.00
12811 : EVENT: Clock#Time=Sat,10:59
14691 : SYS : 0.00
14692 : EVENT: sysload#V_sysload=0.00
16695 : SYS : 0.00
16697 : EVENT: sysload#V_sysload=0.00
18691 : SYS : 0.00
18692 : EVENT: sysload#V_sysload=0.00
20695 : SYS : 0.00
20697 : EVENT: sysload#V_sysload=0.00
22691 : SYS : 0.00
22692 : EVENT: sysload#V_sysload=0.00
24691 : SYS : 0.00
24693 : EVENT: sysload#V_sysload=0.00
26691 : SYS : 0.00
26692 : EVENT: sysload#V_sysload=0.00
28691 : SYS : 0.00
28693 : EVENT: sysload#V_sysload=0.00
30691 : SYS : 0.00
30692 : EVENT: sysload#V_sysload=0.00
31036 : WD : Uptime 1 ConnectFailures 0 FreeMem 16112
32691 : SYS : 0.00
32693 : EVENT: sysload#V_sysload=0.00
34691 : SYS : 0.00
34692 : EVENT: sysload#V_sysload=0.00
36691 : SYS : 0.00
36693 : EVENT: sysload#V_sysload=0.00
38691 : SYS : 0.00
38692 : EVENT: sysload#V_sysload=0.00
40691 : SYS : 0.00
40692 : EVENT: sysload#V_sysload=0.00
42691 : SYS : 0.00
42692 : EVENT: sysload#V_sysload=0.00
44691 : SYS : 0.00
44693 : EVENT: sysload#V_sysload=0.00
46691 : SYS : 0.00
46692 : EVENT: sysload#V_sysload=0.00
48691 : SYS : 0.00
48692 : EVENT: sysload#V_sysload=0.00
50691 : SYS : 0.00
50693 : EVENT: sysload#V_sysload=0.00
52691 : SYS : 0.00
52693 : EVENT: sysload#V_sysload=0.00
54691 : SYS : 0.00
54693 : EVENT: sysload#V_sysload=0.00
56691 : SYS : 0.00
56692 : EVENT: sysload#V_sysload=0.00
58691 : SYS : 0.00
58693 : EVENT: sysload#V_sysload=0.00
60691 : SYS : 0.00
60692 : EVENT: sysload#V_sysload=0.00
61396 : WD : Uptime 1 ConnectFailures 0 FreeMem 16112
62691 : SYS : 27.00
62693 : EVENT: sysload#V_sysload=27.00
64691 : SYS : 27.00
64693 : EVENT: sysload#V_sysload=27.00
66691 : SYS : 27.00
66693 : EVENT: sysload#V_sysload=27.00
68691 : SYS : 27.00
68692 : EVENT: sysload#V_sysload=27.00
70694 : SYS : 27.00
70696 : EVENT: sysload#V_sysload=27.00
72691 : SYS : 27.00
72693 : EVENT: sysload#V_sysload=27.00
73536 : EVENT: Clock#Time=Sat,11:00
74691 : SYS : 27.00
74692 : EVENT: sysload#V_sysload=27.00
76691 : SYS : 27.00
76692 : EVENT: sysload#V_sysload=27.00
78691 : SYS : 27.00
78693 : EVENT: sysload#V_sysload=27.00
80691 : SYS : 27.00
80693 : EVENT: sysload#V_sysload=27.00
82691 : SYS : 27.00
82693 : EVENT: sysload#V_sysload=27.00
84691 : SYS : 27.00
84693 : EVENT: sysload#V_sysload=27.00
86691 : SYS : 27.00
86693 : EVENT: sysload#V_sysload=27.00
88691 : SYS : 27.00
88693 : EVENT: sysload#V_sysload=27.00
90691 : SYS : 27.00
90693 : EVENT: sysload#V_sysload=27.00
91762 : WD : Uptime 2 ConnectFailures 0 FreeMem 16112
92691 : SYS : 46.00
92692 : EVENT: sysload#V_sysload=46.00
94691 : SYS : 46.00
94693 : EVENT: sysload#V_sysload=46.00
96691 : SYS : 46.00
96692 : EVENT: sysload#V_sysload=46.00
98691 : SYS : 46.00
98693 : EVENT: sysload#V_sysload=46.00
100691 : SYS : 46.00
100693 : EVENT: sysload#V_sysload=46.00
102691 : SYS : 46.00
102693 : EVENT: sysload#V_sysload=46.00
104691 : SYS : 46.00
104693 : EVENT: sysload#V_sysload=46.00
106691 : SYS : 46.00
106693 : EVENT: sysload#V_sysload=46.00
108691 : SYS : 46.00
108693 : EVENT: sysload#V_sysload=46.00
110691 : SYS : 46.00
110693 : EVENT: sysload#V_sysload=46.00
112691 : SYS : 46.00
112692 : EVENT: sysload#V_sysload=46.00
114691 : SYS : 46.00
114692 : EVENT: sysload#V_sysload=46.00
116691 : SYS : 46.00
116693 : EVENT: sysload#V_sysload=46.00
118691 : SYS : 46.00
118693 : EVENT: sysload#V_sysload=46.00
120691 : SYS : 46.00
120693 : EVENT: sysload#V_sysload=46.00
122120 : WD : Uptime 2 ConnectFailures 0 FreeMem 16112
122691 : SYS : 64.00
122693 : EVENT: sysload#V_sysload=64.00
124691 : SYS : 64.00
124692 : EVENT: sysload#V_sysload=64.00
126691 : SYS : 64.00
126693 : EVENT: sysload#V_sysload=64.00
128694 : SYS : 64.00
128696 : EVENT: sysload#V_sysload=64.00
130691 : SYS : 64.00
130693 : EVENT: sysload#V_sysload=64.00
132235 : EVENT: Clock#Time=Sat,11:01
132691 : SYS : 64.00
132692 : EVENT: sysload#V_sysload=64.00
134691 : SYS : 64.00
134692 : EVENT: sysload#V_sysload=64.00
136691 : SYS : 64.00
136693 : EVENT: sysload#V_sysload=64.00
138691 : SYS : 64.00
138692 : EVENT: sysload#V_sysload=64.00
140691 : SYS : 64.00
140692 : EVENT: sysload#V_sysload=64.00
142691 : SYS : 64.00
142692 : EVENT: sysload#V_sysload=64.00
144691 : SYS : 64.00
144693 : EVENT: sysload#V_sysload=64.00
146691 : SYS : 64.00
146692 : EVENT: sysload#V_sysload=64.00
148692 : SYS : 64.00
148694 : EVENT: sysload#V_sysload=64.00
150691 : SYS : 64.00
150693 : EVENT: sysload#V_sysload=64.00
152485 : WD : Uptime 3 ConnectFailures 0 FreeMem 16112
152691 : SYS : 81.00
152693 : EVENT: sysload#V_sysload=81.00
154691 : SYS : 81.00
154693 : EVENT: sysload#V_sysload=81.00
156691 : SYS : 81.00
156693 : EVENT: sysload#V_sysload=81.00
158691 : SYS : 81.00
158692 : EVENT: sysload#V_sysload=81.00
160691 : SYS : 81.00
160693 : EVENT: sysload#V_sysload=81.00
162605 : SYS : 81.00
162607 : EVENT: sysload#V_sysload=81.00
164628 : SYS : 81.00
164630 : EVENT: sysload#V_sysload=81.00
166653 : SYS : 81.00
166655 : EVENT: sysload#V_sysload=81.00
168676 : SYS : 81.00
168678 : EVENT: sysload#V_sysload=81.00
170700 : SYS : 81.00
170702 : EVENT: sysload#V_sysload=81.00
172725 : SYS : 81.00
172727 : EVENT: sysload#V_sysload=81.00
174749 : SYS : 81.00
174751 : EVENT: sysload#V_sysload=81.00
176774 : SYS : 81.00
176776 : EVENT: sysload#V_sysload=81.00
178800 : SYS : 81.00
178802 : EVENT: sysload#V_sysload=81.00
180823 : SYS : 81.00
180825 : EVENT: sysload#V_sysload=81.00
182847 : WD : Uptime 3 ConnectFailures 0 FreeMem 15200
182850 : SYS : 98.00
182852 : EVENT: sysload#V_sysload=98.00
184871 : SYS : 98.00
184873 : EVENT: sysload#V_sysload=98.00
186898 : SYS : 98.00
186900 : EVENT: sysload#V_sysload=98.00
188923 : SYS : 98.00
188925 : EVENT: sysload#V_sysload=98.00
190947 : SYS : 98.00
190949 : EVENT: sysload#V_sysload=98.00
192964 : EVENT: Clock#Time=Sat,11:02
193002 : SYS : 98.00
193004 : EVENT: sysload#V_sysload=98.00
194996 : SYS : 98.00
194998 : EVENT: sysload#V_sysload=98.00
197019 : SYS : 98.00
197021 : EVENT: sysload#V_sysload=98.00
199043 : SYS : 98.00
199045 : EVENT: sysload#V_sysload=98.00
201068 : SYS : 98.00
201070 : EVENT: sysload#V_sysload=98.00
203095 : SYS : 98.00
203097 : EVENT: sysload#V_sysload=98.00
205124 : SYS : 98.00
205126 : EVENT: sysload#V_sysload=98.00
207143 : SYS : 98.00
207145 : EVENT: sysload#V_sysload=98.00
209168 : SYS : 98.00
209170 : EVENT: sysload#V_sysload=98.00
211192 : SYS : 98.00
211194 : EVENT: sysload#V_sysload=98.00
213217 : WD : Uptime 4 ConnectFailures 0 FreeMem 12576
213220 : SYS : 100.00
213222 : EVENT: sysload#V_sysload=100.00
215241 : SYS : 100.00
215243 : EVENT: sysload#V_sysload=100.00
217265 : SYS : 100.00
217267 : EVENT: sysload#V_sysload=100.00
219290 : SYS : 100.00
219292 : EVENT: sysload#V_sysload=100.00
221314 : SYS : 100.00
221316 : EVENT: sysload#V_sysload=100.00

TD-er
Normal user
Posts: 940
Joined: 01 Sep 2017, 22:13
Location: the Netherlands
Contact:

Re: Load always rises to 100%

#7 Post by TD-er » 19 May 2018, 15:48

Later this week (when I'm back home) I will have a look at the current implementation of the load computation.
As far as I know, nothing has changed in that part, only the scheduling of the tasks is now more distributed and asynchronous.
So it is not strange the load will be rather constant (and also a rather high reported value is expected), but seeing an almost constant increase to 100% is new to me.

riker1
Normal user
Posts: 143
Joined: 26 Dec 2017, 18:02

Re: Load always rises to 100%

#8 Post by riker1 » 22 May 2018, 16:04

Hi
I think it is hardware related.
Having this behaviour only on the lolin3 nodemcu V3

update
same on Wemos mini


23981: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 20 flashStringData: 17315
25721: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 35 flashStringData: 17345
27712: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 50 flashStringData: 17375
29724: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 65 flashStringData: 17405
30904: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 75 flashStringData: 17425
32977: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 95 flashStringData: 24058
35155: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 110 flashStringData: 24088
35795: LoadFromFile: config.dat index: 28672 datasize: 336
35817: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
35828: SYS : 0.00
35830: EVENT: sysload#sysload=0.00
35832: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
38157: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 125 flashStringData: 24118
38867: LoadFromFile: config.dat index: 28672 datasize: 336
38889: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
38900: SYS : 0.00
38902: EVENT: sysload#sysload=0.00
38904: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
41157: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 140 flashStringData: 24148
41939: LoadFromFile: config.dat index: 28672 datasize: 336
41961: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
41972: SYS : 0.00
41974: EVENT: sysload#sysload=0.00
41976: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
44158: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 155 flashStringData: 24178
45011: LoadFromFile: config.dat index: 28672 datasize: 336
45033: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
45044: SYS : 0.00
45046: EVENT: sysload#sysload=0.00
45048: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
47160: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 170 flashStringData: 24208
48083: LoadFromFile: config.dat index: 28672 datasize: 336
48105: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
48116: SYS : 0.00
48118: EVENT: sysload#sysload=0.00
48120: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
51188: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
51200: SYS : 0.00
51202: EVENT: sysload#sysload=0.00
51204: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
52190: LoadFromFile: config.dat index: 28672 datasize: 336
52212: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
52224: SYS : 0.00
52226: EVENT: sysload#sysload=0.00
52228: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
55167: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 200 flashStringData: 24268
55263: LoadFromFile: config.dat index: 28672 datasize: 336
55285: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
55297: SYS : 0.00
55299: EVENT: sysload#sysload=0.00
55301: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
58168: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 215 flashStringData: 24298
58335: LoadFromFile: config.dat index: 28672 datasize: 336
58357: MQTT : /ESP169_WemosMini5/169/sysload/sysload 0.00
58369: SYS : 0.00
58371: EVENT: sysload#sysload=0.00
58373: EVENT: sysload#sysload=0.00 Processing time:2 milliSeconds
59998: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 225 flashStringData: 24318
61484: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 240 flashStringData: 24348
63543: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 255 flashStringData: 24378
65584: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 270 flashStringData: 24408
67630: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 285 flashStringData: 24438
69674: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 300 flashStringData: 24468
72181: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 315 flashStringData: 24498
72671: LoadFromFile: config.dat index: 28672 datasize: 336
72693: MQTT : /ESP169_WemosMini5/169/sysload/sysload 100.00
72705: SYS : 100.00
72707: EVENT: sysload#sysload=100.00
72710: EVENT: sysload#sysload=100.00 Processing time:3 milliSeconds
75184: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 335 flashStringData: 31131
75744: LoadFromFile: config.dat index: 28672 datasize: 336
75766: MQTT : /ESP169_WemosMini5/169/sysload/sysload 100.00
75778: SYS : 100.00
75781: EVENT: sysload#sysload=100.00
75783: EVENT: sysload#sysload=100.00 Processing time:3 milliSeconds
78187: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 350 flashStringData: 31161
78816: LoadFromFile: config.dat index: 28672 datasize: 336
78838: MQTT : /ESP169_WemosMini5/169/sysload/sysload 100.00
78850: SYS : 100.00
78852: EVENT: sysload#sysload=100.00
78855: EVENT: sysload#sysload=100.00 Processing time:3 milliSeconds
79902: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 360 flashStringData: 31181
81650: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 370 flashStringData: 31201
83035: Ram usage: Webserver only: 0 including Core: 0 flashStringCalls: 385 flashStringData: 31231

Post Reply

Who is online

Users browsing this forum: Bing [Bot] and 2 guests