• 10 dec 2017: forum version update. In case of issues use this topic.
  • 30 nov 2017: pilight moved servers. In case of issues use this topic.
Hello There, Guest! Login Register


Thread Rating:
  • 1 Vote(s) - 3 Average
  • 1
  • 2
  • 3
  • 4
  • 5
Incompatibility in handling of rules:
#1
The following rule (e.q. the complete config.json) is working with the development branch, but on the rewrite branch i get the following error:
Code:
"Rule_TH_Anbau": {
            "rule": "IF tfa.id == 67 THEN label DEVICE LBL_TH_Anbau TO DATE_FORMAT(zeit, %d.%m.%Y %H:%M)",
            "active": 1
        },

I am getting the following error message in the log:
Code:
[Mar 05 16:27:51:598003] pilight-daemon: ERROR: rule #19 invalid: variable "id" of device "tfa" cannot be used in event rules
[Mar 05 16:27:51:598045] pilight-daemon: INFO: rule #19 was parsed until: ... tfa.id == 67 THEN label DEVICE LBL_TH_Anbau TO DATE_FORMAT(zeit, %d.%m.%Y %H:%M)
[Mar 05 16:27:51:598085] pilight-daemon: INFO: rule #19 Rule_TH_Anbau was parsed in 0.000120 seconds

My definitions in the "devices" section:
Code:
"TH_Anbau": {
            "protocol": [ "tfa" ],
            "id": [{
                "id": 67,
                "channel": 1
            }],
            "temperature": 11.50,
            "humidity": 73.00,
            "battery": 1
        },
.....
        "LBL_TH_Anbau": {
            "protocol": [ "generic_label" ],
            "id": [{
                "id": 100
            }],
            "label": "05.03.2016 16:58",
            "color": "black"
        },
 
Reply
#2
Did you check the latest commit?
 
Reply
#3
With the latest commit (b66e158) it fetches the config.json file and starts processing, but ends up in an endless loop with the CPU at 100%.

Code:
root@pi_65:/home/pi/rewrite/pilight# pilight-daemon -D
[Mar 05 21:43:47:144841] pilight-daemon: INFO: datetime zeit 10.559400:52.244300 seems to be in timezone: Europe/Berlin
[Mar 05 21:43:47:177654] pilight-daemon: INFO: sunriseset sunshine 10.559400:52.244300 seems to be in timezone: Europe/Berlin
[Mar 05 21:43:47:200758] pilight-daemon: INFO: rule #1 RuleTVStatus was parsed in 0.000716 seconds
[Mar 05 21:43:47:201497] pilight-daemon: INFO: rule #2 RuleTVStatus1 was parsed in 0.000605 seconds
[Mar 05 21:43:47:202720] pilight-daemon: INFO: rule #3 RuleTVBeleuchtung_on was parsed in 0.001133 seconds
[Mar 05 21:43:47:203930] pilight-daemon: INFO: rule #4 RuleTVBeleuchtung_off was parsed in 0.001116 seconds
[Mar 05 21:43:47:205148] pilight-daemon: INFO: rule #5 RuleLampenBeleuchtung_on was parsed in 0.001125 seconds
[Mar 05 21:43:47:206408] pilight-daemon: INFO: rule #6 RuleLampenBeleuchtung_off was parsed in 0.001171 seconds
[Mar 05 21:43:47:207507] pilight-daemon: INFO: rule #7 RuleLampen_on_sunset was parsed in 0.001006 seconds
[Mar 05 21:43:47:208666] pilight-daemon: INFO: rule #8 RuleLampen_off_4h_sunset was parsed in 0.001065 seconds
[Mar 05 21:43:47:209804] pilight-daemon: INFO: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.001051 seconds
[Mar 05 21:43:47:210879] pilight-daemon: INFO: rule #10 RuleLampen_off_sunrise was parsed in 0.000990 seconds
[Mar 05 21:43:47:211503] pilight-daemon: INFO: rule #11 RuleLampen_Buero_an was parsed in 0.000539 seconds
[Mar 05 21:43:47:212128] pilight-daemon: INFO: rule #12 RuleLampen_Buero_aus was parsed in 0.000542 seconds
[Mar 05 21:43:47:212732] pilight-daemon: INFO: rule #13 RuleLampen_Flur_an was parsed in 0.000523 seconds
[Mar 05 21:43:47:213345] pilight-daemon: INFO: rule #14 RuleLampen_Flur_aus was parsed in 0.000531 seconds
[Mar 05 21:43:47:213981] pilight-daemon: INFO: rule #15 RuleLampen_Klavier_an was parsed in 0.000554 seconds
[Mar 05 21:43:47:214627] pilight-daemon: INFO: rule #16 RuleLampen_Klavier_aus was parsed in 0.000564 seconds
[Mar 05 21:43:47:215242] pilight-daemon: INFO: rule #17 RuleLampen_Tisch_an was parsed in 0.000531 seconds
[Mar 05 21:43:47:215865] pilight-daemon: INFO: rule #18 RuleLampen_Tisch_aus was parsed in 0.000541 seconds
[Mar 05 21:43:47:217192] pilight-daemon: INFO: rule #19 Rule_TH_Anbau was parsed in 0.001247 seconds
[Mar 05 21:43:47:218458] pilight-daemon: INFO: rule #20 Rule_TH_Keller was parsed in 0.001179 seconds
[Mar 05 21:43:47:219710] pilight-daemon: INFO: rule #21 Rule_TH_Aussen was parsed in 0.001170 seconds
[Mar 05 21:43:47:220912] pilight-daemon: INFO: rule #22 Rule_TH_Bad was parsed in 0.001122 seconds
[Mar 05 21:43:47:222168] pilight-daemon: INFO: rule #23 Rule_TH_SZimmer was parsed in 0.001174 seconds
[Mar 05 21:43:47:230328] pilight-daemon: INFO: version v7.0-114-gb66e158
[Mar 05 21:43:47:296376] pilight-daemon: INFO: pilight will dynamically use between 1 and 4 cores
[Mar 05 21:43:47:296781] pilight-daemon: DEBUG: added worker: 1
[Mar 05 21:43:47:298337] pilight-daemon: DEBUG: running on a raspberrypi
[Mar 05 21:43:47:300493] pilight-daemon: INFO: daemon listening to port: 40386
[Mar 05 21:43:47:301059] pilight-daemon: INFO: secured webserver started on port: 5002
[Mar 05 21:43:47:301252] pilight-daemon: INFO: regular webserver started on port: 5001
[Mar 05 21:43:47:775428] pilight-daemon: DEBUG: possible tfa protocol
[Mar 05 21:43:47:775547] pilight-daemon: DEBUG: recevied pulse length of 224
[Mar 05 21:43:47:775590] pilight-daemon: DEBUG: caught minimum # of repeats 1 of tfa
[Mar 05 21:43:47:775631] pilight-daemon: DEBUG: called tfa parseRaw()
[Mar 05 21:43:47:775961] pilight-daemon: DEBUG: broadcasted: {"message":{"id":228,"temperature":16.27,"humidity":42.00,"channel":2,"battery":1},"origin":"receiver","protocol":"tfa","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:47:778598] pilight-daemon: DEBUG: rule #19 Rule_TH_Anbau was parsed in 0.000121 seconds
[Mar 05 21:43:47:778828] pilight-daemon: DEBUG: rule #20 Rule_TH_Keller was parsed in 0.000079 seconds
[Mar 05 21:43:47:779016] pilight-daemon: DEBUG: rule #21 Rule_TH_Aussen was parsed in 0.000074 seconds
[Mar 05 21:43:47:779200] pilight-daemon: DEBUG: rule #22 Rule_TH_Bad was parsed in 0.000072 seconds
[Mar 05 21:43:47:779766] pilight-daemon: INFO: executed rule: Rule_TH_SZimmer
[Mar 05 21:43:47:779841] pilight-daemon: DEBUG: rule #23 Rule_TH_SZimmer was parsed in 0.000534 seconds
[Mar 05 21:43:47:779941] pilight-daemon: INFO: started "label" action for device "LBL_TH_SZimmer"
[Mar 05 21:43:47:780681] pilight-daemon: INFO: stopped "label" action for device "LBL_TH_SZimmer"
[Mar 05 21:43:47:781331] pilight-daemon: DEBUG: broadcasted: {"message":{"id":104,"label":"05.03.2016 16:59","color":"black"},"protocol":"generic_label","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:48:299214] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"sun":"set","sunrise":6.54,"sunset":18.07},"origin":"receiver","protocol":"sunriseset","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:48:301291] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":48,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:48:303684] pilight-daemon: DEBUG: added worker: 2
[Mar 05 21:43:48:303824] pilight-daemon: DEBUG: trying to sync with ntp-server pool.ntp.org
[Mar 05 21:43:48:304669] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000428 seconds
[Mar 05 21:43:48:304690] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000451 seconds
[Mar 05 21:43:48:304970] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000088 seconds
[Mar 05 21:43:48:304991] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000110 seconds
[Mar 05 21:43:48:305736] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000584 seconds
[Mar 05 21:43:48:305736] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000584 seconds
[Mar 05 21:43:48:306122] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000086 seconds
[Mar 05 21:43:48:306143] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000107 seconds
[Mar 05 21:43:48:363880] pilight-daemon: INFO: time offset found of 0 seconds
[Mar 05 21:43:49:300031] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":49,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:49:302432] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000566 seconds
[Mar 05 21:43:49:302706] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000115 seconds
[Mar 05 21:43:49:303359] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000517 seconds
[Mar 05 21:43:49:303590] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000107 seconds
[Mar 05 21:43:50:301000] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":50,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:50:301209] pilight-daemon: DEBUG: cpu: 0.000000%, ram: 0.000000%
[Mar 05 21:43:50:301531] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":0.000000,"ram":0.000000},"origin":"core","type":-1}
[Mar 05 21:43:50:303638] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000489 seconds
[Mar 05 21:43:50:303900] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000115 seconds
[Mar 05 21:43:50:304540] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000518 seconds
[Mar 05 21:43:50:304773] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000107 seconds
[Mar 05 21:43:51:301639] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":51,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:51:303443] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000355 seconds
[Mar 05 21:43:51:303637] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000076 seconds
[Mar 05 21:43:51:304084] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000365 seconds
[Mar 05 21:43:51:304239] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000072 seconds
[Mar 05 21:43:52:302550] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":52,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:52:304883] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000493 seconds
[Mar 05 21:43:52:305148] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000112 seconds
[Mar 05 21:43:52:305798] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000530 seconds
[Mar 05 21:43:52:306025] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000105 seconds
[Mar 05 21:43:53:300790] pilight-daemon: DEBUG: cpu: 100.513709%, ram: 2.257241%
[Mar 05 21:43:53:301053] pilight-daemon: DEBUG: broadcasted: {"cpu":100.513709,"ram":2.257241},"origin":"core","type":-1}
[Mar 05 21:43:53:303464] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":53,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:53:305810] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000473 seconds
[Mar 05 21:43:53:306083] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000113 seconds
[Mar 05 21:43:53:306814] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000609 seconds
[Mar 05 21:43:53:307074] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000108 seconds
[Mar 05 21:43:54:304351] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":54,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:54:306666] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000544 seconds
[Mar 05 21:43:54:306942] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000115 seconds
[Mar 05 21:43:54:307602] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000535 seconds
[Mar 05 21:43:54:307838] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000108 seconds
[Mar 05 21:43:55:304943] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":55,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:55:306579] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000367 seconds
[Mar 05 21:43:55:306786] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000079 seconds
[Mar 05 21:43:55:307239] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000360 seconds
[Mar 05 21:43:55:307403] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000075 seconds
[Mar 05 21:43:56:300727] pilight-daemon: DEBUG: cpu: 100.485251%, ram: 2.257241%
[Mar 05 21:43:56:300943] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":100.485251,"ram":2.257241},"origin":"core","type":-1}
[Mar 05 21:43:56:305698] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":56,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:56:307999] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000467 seconds
[Mar 05 21:43:56:308256] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000111 seconds
[Mar 05 21:43:56:308892] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000515 seconds
[Mar 05 21:43:56:309119] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000105 seconds
[Mar 05 21:43:57:306650] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":57,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:57:308875] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000476 seconds
[Mar 05 21:43:57:309140] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000118 seconds
[Mar 05 21:43:57:309798] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000532 seconds
[Mar 05 21:43:57:310032] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000108 seconds
[Mar 05 21:43:58:307497] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":58,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:58:309623] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000452 seconds
[Mar 05 21:43:58:309877] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000113 seconds
[Mar 05 21:43:58:310531] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000535 seconds
[Mar 05 21:43:58:310765] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000108 seconds
[Mar 05 21:43:59:300813] pilight-daemon: DEBUG: cpu: 100.493694%, ram: 2.257241%
[Mar 05 21:43:59:300968] pilight-daemon: DEBUG: broadcasted: {"cpu":100.493694,"ram":2.257241},"origin":"core","type":-1}
[Mar 05 21:43:59:308083] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":43,"second":59,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:43:59:309634] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000326 seconds
[Mar 05 21:43:59:309816] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000078 seconds
[Mar 05 21:43:59:310251] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000351 seconds
[Mar 05 21:43:59:310409] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000073 seconds
[Mar 05 21:44:00:308875] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":0,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:00:311025] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000458 seconds
[Mar 05 21:44:00:311280] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000113 seconds
[Mar 05 21:44:00:311927] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000526 seconds
[Mar 05 21:44:00:312163] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000109 seconds
[Mar 05 21:44:01:309644] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":1,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:01:311857] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000499 seconds
[Mar 05 21:44:01:312153] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000116 seconds
[Mar 05 21:44:01:312842] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000566 seconds
[Mar 05 21:44:01:313083] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000109 seconds
[Mar 05 21:44:02:301042] pilight-daemon: DEBUG: cpu: 100.439111%, ram: 2.257241%
[Mar 05 21:44:02:301257] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":100.439111,"ram":2.257241},"origin":"core","type":-1}
[Mar 05 21:44:02:310396] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":2,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:02:312736] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000508 seconds
[Mar 05 21:44:02:313004] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000114 seconds
[Mar 05 21:44:02:313650] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000522 seconds
[Mar 05 21:44:02:313877] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000107 seconds
[Mar 05 21:44:03:310966] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":3,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:03:312575] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000319 seconds
[Mar 05 21:44:03:312760] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000075 seconds
[Mar 05 21:44:03:313199] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000356 seconds
[Mar 05 21:44:03:313352] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000071 seconds
[Mar 05 21:44:04:311555] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":4,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:04:313265] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000330 seconds
[Mar 05 21:44:04:313456] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000080 seconds
[Mar 05 21:44:04:313888] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000349 seconds
[Mar 05 21:44:04:314042] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000071 seconds
[Mar 05 21:44:05:301241] pilight-daemon: DEBUG: cpu: 100.410231%, ram: 2.257241%
[Mar 05 21:44:05:301452] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":100.410231,"ram":2.257241},"origin":"core","type":-1}
[Mar 05 21:44:05:312306] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":5,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:05:314582] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000467 seconds
[Mar 05 21:44:05:314842] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000113 seconds
[Mar 05 21:44:05:315492] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000526 seconds
[Mar 05 21:44:05:315727] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000110 seconds
[Mar 05 21:44:06:313181] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":6,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:06:315384] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000490 seconds
[Mar 05 21:44:06:315637] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000112 seconds
[Mar 05 21:44:06:316348] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000578 seconds
[Mar 05 21:44:06:316587] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000109 seconds
[Mar 05 21:44:07:314021] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":7,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:07:316296] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000492 seconds
[Mar 05 21:44:17:972808] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54843
[Mar 05 21:44:17:973116] pilight-daemon: DEBUG: client fd: 11
[Mar 05 21:44:17:973268] pilight-daemon: DEBUG: cpu: 100.105344%, ram: 2.257241%
[Mar 05 21:44:17:973848] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000120 seconds
[Mar 05 21:44:17:973965] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":17,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:17:974792] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000482 seconds
[Mar 05 21:44:17:975132] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":100.105344,"ram":2.257241},"origin":"core","type":-1}
[Mar 05 21:44:17:975307] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000088 seconds
[Mar 05 21:44:17:975874] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000303 seconds
[Mar 05 21:44:17:976070] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000076 seconds
[Mar 05 21:44:17:976608] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000441 seconds
[Mar 05 21:44:17:976786] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000074 seconds
[Mar 05 21:44:17:982372] pilight-daemon: NOTICE: caching /usr/local/share/pilight//index.html
[Mar 05 21:44:18:257857] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54844
[Mar 05 21:44:18:257940] pilight-daemon: DEBUG: client fd: 11
[Mar 05 21:44:18:258659] pilight-daemon: NOTICE: caching /usr/local/share/pilight//favicon.ico
[Mar 05 21:44:18:326604] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54845
[Mar 05 21:44:18:326678] pilight-daemon: DEBUG: client fd: 11
[Mar 05 21:44:18:394783] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54846
[Mar 05 21:44:18:394858] pilight-daemon: DEBUG: client fd: 11
[Mar 05 21:44:18:394977] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54847
[Mar 05 21:44:18:395023] pilight-daemon: DEBUG: client fd: 12
[Mar 05 21:44:18:402582] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54848
[Mar 05 21:44:18:402632] pilight-daemon: DEBUG: client fd: 13
[Mar 05 21:44:18:403658] pilight-daemon: NOTICE: caching /usr/local/share/pilight//jquery-2.1.4.min.js
[Mar 05 21:44:18:410092] pilight-daemon: NOTICE: caching /usr/local/share/pilight//jquery.mobile-1.4.5.min.js
[Mar 05 21:44:18:412043] pilight-daemon: NOTICE: caching /usr/local/share/pilight//moment.min.js
[Mar 05 21:44:18:419370] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54849
[Mar 05 21:44:18:419462] pilight-daemon: DEBUG: client fd: 14
[Mar 05 21:44:18:421071] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54850
[Mar 05 21:44:18:421123] pilight-daemon: DEBUG: client fd: 15
[Mar 05 21:44:18:444316] pilight-daemon: NOTICE: caching /usr/local/share/pilight//pilight.js
[Mar 05 21:44:18:445354] pilight-daemon: NOTICE: caching /usr/local/share/pilight//jquery.mobile-1.4.5.min.css
[Mar 05 21:44:18:464428] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54851
[Mar 05 21:44:18:464543] pilight-daemon: DEBUG: client fd: 16
[Mar 05 21:44:18:465418] pilight-daemon: NOTICE: caching /usr/local/share/pilight//pilight.css
[Mar 05 21:44:18:520848] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54852
[Mar 05 21:44:18:520957] pilight-daemon: DEBUG: client fd: 13
[Mar 05 21:44:18:521509] pilight-daemon: NOTICE: caching /usr/local/share/pilight//pilight.jquery.theme.css
[Mar 05 21:44:18:974706] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":22,"minute":44,"second":18,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 21:44:18:977228] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000532 seconds
[Mar 05 21:44:18:977510] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000114 seconds
[Mar 05 21:44:18:978246] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000608 seconds
[Mar 05 21:44:18:978483] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000108 seconds
[ Mar 05 21:44:19:99650] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54853
[ Mar 05 21:44:19:99719] pilight-daemon: DEBUG: client fd: 11
[Mar 05 21:44:19:100181] pilight-daemon: DEBUG: new client, ip: 192.168.0.31, port: 54854
[Mar 05 21:44:19:100232] pilight-daemon: DEBUG: client fd: 12
[Mar 05 21:44:19:100821] pilight-daemon: NOTICE: caching /usr/local/share/pilight//apple-touch-icon-iphone-retina-display-hr.png
 
Reply
#4
Any idea what could cause this?
 
Reply
#5
No. It was the first run after compilation. I killed the process and started pilight again in debug mode. It was running for about 20 minutes before i stopped it and restarted it again.
Code:
[Mar 05 22:34:34:655018] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000106 seconds
[Mar 05 22:34:35:334731] pilight-daemon: DEBUG: cpu: 7.088581%, ram: 2.269477%
[Mar 05 22:34:35:334964] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":7.088581,"ram":2.269477},"origin":"core","type":-1}
[Mar 05 22:34:35:652372] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":34,"second":35,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:35:654777] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000507 seconds
[Mar 05 22:34:35:655081] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000117 seconds
[Mar 05 22:34:35:655748] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000532 seconds
[Mar 05 22:34:35:655993] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000107 seconds
[Mar 05 22:34:36:653319] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":34,"second":36,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:36:655765] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000636 seconds
[Mar 05 22:34:36:656062] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000111 seconds
[Mar 05 22:34:36:656774] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000588 seconds
[Mar 05 22:34:36:657048] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000108 seconds
[Mar 05 22:34:37:420965] pilight-daemon: DEBUG: possible tfa protocol
[Mar 05 22:34:37:421055] pilight-daemon: DEBUG: recevied pulse length of 224
[Mar 05 22:34:37:421098] pilight-daemon: DEBUG: caught minimum # of repeats 1 of tfa
[Mar 05 22:34:37:421140] pilight-daemon: DEBUG: called tfa parseRaw()
[Mar 05 22:34:37:421370] pilight-daemon: DEBUG: broadcasted: {"message":{"id":245,"temperature":14.88,"humidity":48.00,"channel":2,"battery":1},"origin":"receiver","protocol":"tfa","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:37:421873] pilight-daemon: DEBUG: rule #19 Rule_TH_Anbau was parsed in 0.000117 seconds
[Mar 05 22:34:37:422959] pilight-daemon: INFO: executed rule: Rule_TH_Keller
[Mar 05 22:34:37:423035] pilight-daemon: DEBUG: rule #20 Rule_TH_Keller was parsed in 0.000543 seconds
[Mar 05 22:34:37:423116] pilight-daemon: INFO: started "label" action for device "LBL_TH_Keller"
[Mar 05 22:34:37:423274] pilight-daemon: DEBUG: rule #21 Rule_TH_Aussen was parsed in 0.000115 seconds
[Mar 05 22:34:37:423527] pilight-daemon: DEBUG: rule #22 Rule_TH_Bad was parsed in 0.000091 seconds
[Mar 05 22:34:37:423815] pilight-daemon: INFO: stopped "label" action for device "LBL_TH_Keller"
[Mar 05 22:34:37:423837] pilight-daemon: DEBUG: rule #23 Rule_TH_SZimmer was parsed in 0.000091 seconds
[Mar 05 22:34:37:424309] pilight-daemon: DEBUG: broadcasted: {"message":{"id":101,"label":"05.03.2016 23:34","color":"black"},"protocol":"generic_label","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:37:572518] pilight-daemon: DEBUG: possible tfa protocol
[Mar 05 22:34:37:572614] pilight-daemon: DEBUG: recevied pulse length of 224
[Mar 05 22:34:37:572660] pilight-daemon: DEBUG: caught minimum # of repeats 2 of tfa
[Mar 05 22:34:37:572702] pilight-daemon: DEBUG: called tfa parseRaw()
[Mar 05 22:34:37:572933] pilight-daemon: DEBUG: broadcasted: {"message":{"id":245,"temperature":14.88,"humidity":48.00,"channel":2,"battery":1},"origin":"receiver","protocol":"tfa","uuid":"0000-b8-27-eb-13d705","repeats":2}
[Mar 05 22:34:37:573461] pilight-daemon: DEBUG: rule #19 Rule_TH_Anbau was parsed in 0.000136 seconds
[Mar 05 22:34:37:574719] pilight-daemon: INFO: executed rule: Rule_TH_Keller
[Mar 05 22:34:37:574807] pilight-daemon: INFO: started "label" action for device "LBL_TH_Keller"
[Mar 05 22:34:37:574798] pilight-daemon: DEBUG: rule #20 Rule_TH_Keller was parsed in 0.000572 seconds
[Mar 05 22:34:37:575089] pilight-daemon: DEBUG: rule #21 Rule_TH_Aussen was parsed in 0.000109 seconds
[Mar 05 22:34:37:575327] pilight-daemon: DEBUG: rule #22 Rule_TH_Bad was parsed in 0.000088 seconds
[Mar 05 22:34:37:575538] pilight-daemon: DEBUG: rule #23 Rule_TH_SZimmer was parsed in 0.000081 seconds
[Mar 05 22:34:37:575660] pilight-daemon: INFO: stopped "label" action for device "LBL_TH_Keller"
[Mar 05 22:34:37:576036] pilight-daemon: DEBUG: broadcasted: {"message":{"id":101,"label":"05.03.2016 23:34","color":"black"},"protocol":"generic_label","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:37:654220] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":34,"second":37,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:37:656595] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000537 seconds
[Mar 05 22:34:37:656908] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000132 seconds
[Mar 05 22:34:37:657562] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000522 seconds
[Mar 05 22:34:37:657794] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000106 seconds
[Mar 05 22:34:37:875397] pilight-daemon: DEBUG: possible tfa protocol
[Mar 05 22:34:37:875489] pilight-daemon: DEBUG: recevied pulse length of 224
[Mar 05 22:34:37:875533] pilight-daemon: DEBUG: caught minimum # of repeats 3 of tfa
[Mar 05 22:34:37:875573] pilight-daemon: DEBUG: called tfa parseRaw()
[Mar 05 22:34:37:875807] pilight-daemon: DEBUG: broadcasted: {"message":{"id":245,"temperature":14.88,"humidity":48.00,"channel":2,"battery":1},"origin":"receiver","protocol":"tfa","uuid":"0000-b8-27-eb-13d705","repeats":3}
[Mar 05 22:34:37:876344] pilight-daemon: DEBUG: rule #19 Rule_TH_Anbau was parsed in 0.000148 seconds
[Mar 05 22:34:37:877783] pilight-daemon: INFO: executed rule: Rule_TH_Keller
[Mar 05 22:34:37:877811] pilight-daemon: INFO: started "label" action for device "LBL_TH_Keller"
[Mar 05 22:34:37:877888] pilight-daemon: DEBUG: rule #20 Rule_TH_Keller was parsed in 0.000933 seconds
[Mar 05 22:34:37:878116] pilight-daemon: DEBUG: rule #21 Rule_TH_Aussen was parsed in 0.000104 seconds
[Mar 05 22:34:37:878391] pilight-daemon: DEBUG: rule #22 Rule_TH_Bad was parsed in 0.000088 seconds
[Mar 05 22:34:37:878606] pilight-daemon: INFO: stopped "label" action for device "LBL_TH_Keller"
[Mar 05 22:34:37:878641] pilight-daemon: DEBUG: rule #23 Rule_TH_SZimmer was parsed in 0.000084 seconds
[Mar 05 22:34:37:879076] pilight-daemon: DEBUG: broadcasted: {{"id":101,"label":"05.03.2016 23:34","color":"black"},"protocol":"generic_label","uuid":"0000-b8-27-eb-13d705","repeats":1}
[ Mar 05 22:34:38:26906] pilight-daemon: DEBUG: possible tfa protocol
[ Mar 05 22:34:38:27005] pilight-daemon: DEBUG: recevied pulse length of 224
[ Mar 05 22:34:38:27049] pilight-daemon: DEBUG: caught minimum # of repeats 4 of tfa
[ Mar 05 22:34:38:27089] pilight-daemon: DEBUG: called tfa parseRaw()
[ Mar 05 22:34:38:27330] pilight-daemon: DEBUG: broadcasted: {"message":{"id":245,"temperature":14.88,"humidity":48.00,"channel":2,"battery":1},"origin":"receiver","protocol":"tfa","uuid":"0000-b8-27-eb-13d705","repeats":4}
[ Mar 05 22:34:38:27839] pilight-daemon: DEBUG: rule #19 Rule_TH_Anbau was parsed in 0.000131 seconds
[ Mar 05 22:34:38:29034] pilight-daemon: INFO: executed rule: Rule_TH_Keller
[ Mar 05 22:34:38:29113] pilight-daemon: DEBUG: rule #20 Rule_TH_Keller was parsed in 0.000567 seconds
[ Mar 05 22:34:38:29126] pilight-daemon: INFO: started "label" action for device "LBL_TH_Keller"
[ Mar 05 22:34:38:29344] pilight-daemon: DEBUG: rule #21 Rule_TH_Aussen was parsed in 0.000096 seconds
[ Mar 05 22:34:38:29611] pilight-daemon: DEBUG: rule #22 Rule_TH_Bad was parsed in 0.000089 seconds
[ Mar 05 22:34:38:29819] pilight-daemon: INFO: stopped "label" action for device "LBL_TH_Keller"
[ Mar 05 22:34:38:29858] pilight-daemon: DEBUG: rule #23 Rule_TH_SZimmer was parsed in 0.000080 seconds
[ Mar 05 22:34:38:30393] pilight-daemon: DEBUG: broadcasted: {{"id":101,"label":"05.03.2016 23:34","color":"black"},"protocol":"generic_label","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:38:334989] pilight-daemon: DEBUG: cpu: 4.776880%, ram: 2.269477%
[Mar 05 22:34:38:335261] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":4.776880,"ram":2.269477},"origin":"core","type":-1}
[Mar 05 22:34:38:655116] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":34,"second":38,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:38:657482] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000489 seconds
[Mar 05 22:34:38:657774] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000114 seconds
[Mar 05 22:34:38:658527] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000616 seconds
[Mar 05 22:34:38:658782] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000107 seconds
[Mar 05 22:34:39:656027] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":34,"second":39,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:34:39:658372] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000487 seconds
[Mar 05 22:34:39:658666] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000115 seconds
[Mar 05 22:34:39:659442] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000635 seconds
[Mar 05 22:34:39:659700] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000109 seconds
I stopped the daemon and restarted it again, this time it stopped responding:
Code:
[Mar 05 22:37:24:607166] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":37,"second":24,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:37:24:609433] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000468 seconds
[Mar 05 22:37:24:609702] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000109 seconds
[Mar 05 22:37:24:610331] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000509 seconds
[Mar 05 22:37:24:610558] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000105 seconds
[Mar 05 22:37:25:607758] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":37,"second":25,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:37:25:609349] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000316 seconds
[Mar 05 22:37:25:609534] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000075 seconds
[Mar 05 22:37:25:609953] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000337 seconds
[Mar 05 22:37:25:610106] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000071 seconds
[Mar 05 22:37:25:737116] pilight-daemon: DEBUG: trying to sync with ntp-server pool.ntp.org
Pressing CTRL+C terminated pilight:
Code:
^C
[Mar 05 22:37:45:572410] pilight-daemon: DEBUG: cpu: 100.450209%, ram: 2.255132%
[Mar 05 22:40:25:843205] pilight-daemon: DEBUG: received interrupt signal, stopping pilight...
[Mar 05 22:40:25:843881] pilight-daemon: DEBUG: cpu: 100.000741%, ram: 2.255132%
[Mar 05 22:40:25:844254] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":100.450209,"ram":2.255132},"origin":"core","type":-1}
[Mar 05 22:40:25:844312] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000124 seconds
[Mar 05 22:40:25:844359] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":5,"weekday":7,"hour":23,"minute":40,"second":25,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 05 22:40:25:844444] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":100.000741,"ram":2.255132},"origin":"core","type":-1}
[Mar 05 22:40:25:845053] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000473 seconds
[Mar 05 22:40:25:845529] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000092 seconds
[Mar 05 22:40:25:846104] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000322 seconds
[Mar 05 22:40:25:846349] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000131 seconds
[Mar 05 22:40:25:846831] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000387 seconds
[Mar 05 22:40:25:847059] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000098 seconds
[Mar 05 22:40:25:847445] pilight-daemon: DEBUG: garbage collected event operator library
[Mar 05 22:40:25:847538] pilight-daemon: DEBUG: garbage collected event action library
[Mar 05 22:40:25:847591] pilight-daemon: DEBUG: garbage collected event function library
[Mar 05 22:40:25:847619] pilight-daemon: DEBUG: garbage collected events library
[Mar 05 22:40:25:847898] pilight-daemon: INFO: removed stale pid_file /var/run/pilight.pid
[Mar 05 22:40:25:847969] pilight-daemon: DEBUG: garbage collected fcache library
[Mar 05 22:40:25:848001] pilight-daemon: DEBUG: garbage collected sha256cache library
[Mar 05 22:40:25:848029] pilight-daemon: DEBUG: garbage collected webserver library
[Mar 05 22:40:25:848054] pilight-daemon: DEBUG: garbage collected datetime library
[Mar 05 22:40:25:848112] pilight-daemon: DEBUG: garbage collected options library
[Mar 05 22:40:25:848142] pilight-daemon: DEBUG: garbage collected socket library
[Mar 05 22:40:25:848170] pilight-daemon: DEBUG: [Z-Wave]: closing and freeing z-wave modules, please wait...
[Mar 05 22:40:25:848209] pilight-daemon: DEBUG: garbage collected config hardware library
[Mar 05 22:40:25:862192] pilight-daemon: DEBUG: garbage collected config rules library
[Mar 05 22:40:25:862273] pilight-daemon: DEBUG: garbage collected config storage library
[Mar 05 22:40:25:862953] pilight-daemon: DEBUG: garbage collected protocol library
[Mar 05 22:40:25:862988] pilight-daemon: DEBUG: garbage collected ntp library
[Mar 05 22:40:25:863134] pilight-daemon: DEBUG: garbage collected threads library
[Mar 05 22:40:25:863765] pilight-daemon: DEBUG: garbage collected wiringX library
[Mar 05 22:40:25:863825] pilight-daemon: DEBUG: garbage collected dso library
DEBUG: garbage collected log library
 
Reply
#6
Can you run it with --debuglevel=2 to see what is being done at the moment the CPU rises.
 
Reply
#7
--debuglevel=2 won't work, only 1

Another issue popped up:
The same config file is working with development
Code:
[Mar 07 20:40:36:610361] pilight-daemon: ERROR: rule #19 invalid: trying to compare string variable "tfa.id" to an integer
[Mar 07 20:40:36:610485] pilight-daemon: INFO: rule #19 was parsed until: ... tfa.id == 67 THEN label DEVICE LBL_TH_Anbau TO DATE_FORMAT(zeit, %d.%m.%Y %H:%M)
[Mar 07 20:40:36:610600] pilight-daemon: INFO: rule #19 Rule_TH_Anbau was parsed in 0.000376 seconds
 
Reply
#8
That's wierd, 2 should work as well. I just tested it and it does.
 
Reply
#9
My mistake, i used a fresh clone, and was still on the master branch .. debuglevel 2 is its working now.
It is difficult to catch a high CPU state. That high CPU state is something i monitor under development as well. I am relatively sure that it is Webgui related, but have no clue why.

Regarding the instability, i am more of the opinion that it may be a pointer/buffer overflow/uninitialized variable issue.
I have
- rewrite in /home/pi/rewrite/pilight and
- development in /home/pi/pilight
I am running Linux V 4.1.19 with the most recent raspbian (jessie) distribution clean install, not a wheezy upgrade, on a PI-2.
If i compile development and switch over to rewrite, the rewrite branch is running more stable after compiling it for a second time.
Unfortunately i was unable to catch one of the examples with a stacktrace.

Example 1: Alle labels are updated and pilight terminates upon CTRL+C
Code:
[Mar 08 00:06:56:331398] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000893 seconds
[Mar 08 00:06:56:331473] pilight-daemon: DEBUG: task rules loop executed in 0.000972 seconds
[Mar 08 00:06:56:459643] pilight-daemon: DEBUG: new threadpool task trigger REASON_RECEIVED_PULSETRAIN
activated worker 1, executing trigger REASON_RECEIVED_PULSETRAIN
[Mar 08 00:06:56:459951] pilight-daemon: DEBUG: task trigger REASON_RECEIVED_PULSETRAIN executed in 0.000058 seconds
[Mar 08 00:06:56:919523] pilight-daemon: DEBUG: new threadpool task trigger REASON_RECEIVED_PULSETRAIN
activated worker 1, executing trigger REASON_RECEIVED_PULSETRAIN
[Mar 08 00:06:56:919810] pilight-daemon: DEBUG: task trigger REASON_RECEIVED_PULSETRAIN executed in 0.000047 seconds
[Mar 08 00:06:56:974832] pilight-daemon: DEBUG: new threadpool task trigger REASON_RECEIVED_PULSETRAIN
activated worker 1, executing trigger REASON_RECEIVED_PULSETRAIN
[Mar 08 00:06:56:975097] pilight-daemon: DEBUG: task trigger REASON_RECEIVED_PULSETRAIN executed in 0.000035 seconds
[  Mar 08 00:06:57:5811] pilight-daemon: DEBUG: new threadpool task trigger REASON_RECEIVED_PULSETRAIN
activated worker 1, executing trigger REASON_RECEIVED_PULSETRAIN
[  Mar 08 00:06:57:6067] pilight-daemon: DEBUG: task trigger REASON_RECEIVED_PULSETRAIN executed in 0.000035 seconds
[Mar 08 00:06:57:325476] pilight-daemon: DEBUG: new threadpool task zeit
activated worker 1, executing zeit
[Mar 08 00:06:57:326414] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[Mar 08 00:06:57:326563] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
activated worker 2, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:06:57:326667] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[Mar 08 00:06:57:326690] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":8,"weekday":3,"hour":1,"minute":6,"second":57,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}

= 5
pilight stats 1457395617 991438193
zeit 1457395618 326770908
[Mar 08 00:06:57:326806] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000135 seconds
ntp sync 1457481734 32653023
sunshine 1457416020 944674998
IPcheckTV 1457395633 944230821
activated worker 2, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:06:57:326958] pilight-daemon: DEBUG: task zeit executed in 0.001227 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:06:57:327074] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000027 seconds
[Mar 08 00:06:57:328625] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:06:57:328801] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:06:57:328927] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:06:57:329014] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:06:57:329053] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000134 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:06:57:329114] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.002140 seconds
[Mar 08 00:06:57:329171] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000030 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
activated worker 2, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:06:57:329475] pilight-daemon: DEBUG: new threadpool task rules loop
[Mar 08 00:06:57:329480] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000028 seconds
[Mar 08 00:06:57:329596] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000172 seconds
activated worker 2, executing rules loop
evaluate (AND) LampenZeitschaltUhr.state IS off AND (sunshine.sunset == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[Mar 08 00:06:57:330029] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000268 seconds
[Mar 08 00:06:57:330100] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[Mar 08 00:06:57:330265] pilight-daemon: DEBUG: task rules loop executed in 0.000507 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunset + 5.00
sunshine.sunset / + / 5.00
replace (sunshine.sunset + 5.00) with 23.110000 in (sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.060000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.060000
zeit.hour / + / 0.060000
replace (zeit.hour + (zeit.minute / 100)) with 1.060000 in 23.110000 == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) 23.110000 == 1.060000 AND zeit.second == 0
23.110000 / == / 1.060000
skip (AND) zeit.second == 0
replace ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[Mar 08 00:06:57:331375] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.001059 seconds
[Mar 08 00:06:57:331451] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[Mar 08 00:06:57:331565] pilight-daemon: DEBUG: task rules loop executed in 0.001253 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND ((sunshine.sunrise - 1.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[Mar 08 00:06:57:331826] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000195 seconds
[Mar 08 00:06:57:331894] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[Mar 08 00:06:57:332017] pilight-daemon: DEBUG: task rules loop executed in 0.000388 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunrise
replace (sunshine.sunrise) with sunshine.sunrise in (sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.060000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.060000
zeit.hour / + / 0.060000
replace (zeit.hour + (zeit.minute / 100)) with 1.060000 in sunshine.sunrise == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) sunshine.sunrise == 1.060000 AND zeit.second == 0
sunshine.sunrise / == / 1.060000
skip (AND) zeit.second == 0
replace ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[Mar 08 00:06:57:332891] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000809 seconds
[Mar 08 00:06:57:332965] pilight-daemon: DEBUG: task rules loop executed in 0.000887 seconds
[Mar 08 00:06:57:340901] pilight-daemon: DEBUG: new threadpool task trigger REASON_RECEIVED_PULSETRAIN
activated worker 1, executing trigger REASON_RECEIVED_PULSETRAIN
[Mar 08 00:06:57:341163] pilight-daemon: DEBUG: task trigger REASON_RECEIVED_PULSETRAIN executed in 0.000058 seconds
^C[Mar 08 00:06:57:350516] pilight-daemon: DEBUG: received interrupt signal, stopping pilight...
[Mar 08 00:06:57:355677] pilight-daemon: DEBUG: garbage collected event operator library
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
[Mar 08 00:06:57:355887] pilight-daemon: DEBUG: garbage collected event action library
[Mar 08 00:06:57:355937] pilight-daemon: DEBUG: garbage collected event function library
[Mar 08 00:06:57:355976] pilight-daemon: DEBUG: garbage collected events library
[Mar 08 00:06:57:356139] pilight-daemon: INFO: removed stale pid_file /var/run/pilight.pid
[Mar 08 00:06:57:356392] pilight-daemon: DEBUG: garbage collected fcache library
[Mar 08 00:06:57:356447] pilight-daemon: DEBUG: garbage collected sha256cache library
[Mar 08 00:06:57:356489] pilight-daemon: DEBUG: garbage collected webserver library
[Mar 08 00:06:57:356528] pilight-daemon: DEBUG: garbage collected datetime library
[Mar 08 00:06:57:356567] pilight-daemon: DEBUG: garbage collected options library
[Mar 08 00:06:57:356604] pilight-daemon: DEBUG: garbage collected socket library
[Mar 08 00:06:57:356643] pilight-daemon: DEBUG: [Z-Wave]: closing and freeing z-wave modules, please wait...
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
[Mar 08 00:06:57:356752] pilight-daemon: DEBUG: garbage collected config hardware library
[Mar 08 00:06:57:363851] pilight-daemon: DEBUG: garbage collected config rules library
[Mar 08 00:06:57:363958] pilight-daemon: DEBUG: garbage collected config storage library
protocol lm75
ran garbage collector
freed options struct
protocol ds18s20

...

freed options struct
protocol process
freed options struct
[Mar 08 00:06:57:367003] pilight-daemon: DEBUG: garbage collected protocol library
[Mar 08 00:06:57:367056] pilight-daemon: DEBUG: garbage collected ntp library
[Mar 08 00:06:57:367266] pilight-daemon: DEBUG: garbage collected threads library
[Mar 08 00:06:57:368026] pilight-daemon: DEBUG: garbage collected wiringX library
[Mar 08 00:06:57:368103] pilight-daemon: DEBUG: garbage collected dso library
DEBUG: garbage collected log library
root@pi_65:/home/pi/rewrite/pilight#

Example: Link to the webgui got lost.
Code:
[ Mar 08 00:11:01:87411] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000766 seconds
[ Mar 08 00:11:01:87483] pilight-daemon: DEBUG: task rules loop executed in 0.000842 seconds
[ Mar 08 00:11:02:82098] pilight-daemon: DEBUG: new threadpool task zeit
activated worker 1, executing zeit
[ Mar 08 00:11:02:82794] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:02:82901] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
activated worker 2, executing trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:02:82978] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:02:82996] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":8,"weekday":3,"hour":1,"minute":11,"second":2,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}

= 5
zeit 1457395863 83067003
pilight stats 1457395863 877121518
[ Mar 08 00:11:02:83094] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000111 seconds
sunshine 1457416020 854064801
ntp sync 1457482090 954957203
activated worker 2, executing trigger REASON_CODE_RECEIVED
IPcheckTV 1457395870 853037353
[ Mar 08 00:11:02:83257] pilight-daemon: DEBUG: task zeit executed in 0.000957 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:02:83359] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000033 seconds
[ Mar 08 00:11:02:84273] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84395] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84479] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84538] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84561] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000087 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84606] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.001382 seconds
activated worker 2, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84645] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000022 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:02:84695] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000025 seconds
[ Mar 08 00:11:02:84738] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[ Mar 08 00:11:02:84818] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000111 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND (sunshine.sunset == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[ Mar 08 00:11:02:85017] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000152 seconds
[ Mar 08 00:11:02:85062] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[ Mar 08 00:11:02:85132] pilight-daemon: DEBUG: task rules loop executed in 0.000268 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunset + 5.00
sunshine.sunset / + / 5.00
replace (sunshine.sunset + 5.00) with 23.110000 in (sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.110000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.110000
zeit.hour / + / 0.110000
replace (zeit.hour + (zeit.minute / 100)) with 1.110000 in 23.110000 == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) 23.110000 == 1.110000 AND zeit.second == 0
23.110000 / == / 1.110000
skip (AND) zeit.second == 0
replace ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[ Mar 08 00:11:02:85801] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000628 seconds
[ Mar 08 00:11:02:85857] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[ Mar 08 00:11:02:85957] pilight-daemon: DEBUG: task rules loop executed in 0.000786 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND ((sunshine.sunrise - 1.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[ Mar 08 00:11:02:86156] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000149 seconds
[ Mar 08 00:11:02:86202] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[ Mar 08 00:11:02:86288] pilight-daemon: DEBUG: task rules loop executed in 0.000283 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunrise
replace (sunshine.sunrise) with sunshine.sunrise in (sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.110000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.110000
zeit.hour / + / 0.110000
replace (zeit.hour + (zeit.minute / 100)) with 1.110000 in sunshine.sunrise == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) sunshine.sunrise == 1.110000 AND zeit.second == 0
sunshine.sunrise / == / 1.110000
skip (AND) zeit.second == 0
replace ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[ Mar 08 00:11:02:86867] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000534 seconds
[ Mar 08 00:11:02:86917] pilight-daemon: DEBUG: task rules loop executed in 0.000586 seconds
[ Mar 08 00:11:03:83152] pilight-daemon: DEBUG: new threadpool task zeit
activated worker 1, executing zeit
[ Mar 08 00:11:03:84056] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:03:84207] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
activated worker 2, executing trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:03:84304] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:03:84300] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":8,"weekday":3,"hour":1,"minute":11,"second":3,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}

= 5
pilight stats 1457395863 877121518
zeit 1457395864 84417230
sunshine 1457416020 854064801
ntp sync 1457482090 954957203
IPcheckTV 1457395870 853037353
[ Mar 08 00:11:03:84442] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000157 seconds
activated worker 2, executing trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:03:84575] pilight-daemon: DEBUG: task zeit executed in 0.001183 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[ Mar 08 00:11:03:84729] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000026 seconds
[ Mar 08 00:11:03:86040] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:03:86233] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:03:86343] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:03:86453] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:03:86463] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000125 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:03:86592] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.001957 seconds
[ Mar 08 00:11:03:86636] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000030 seconds
activated worker 2, executing trigger REASON_CONFIG_UPDATE
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[ Mar 08 00:11:03:86811] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000031 seconds
[ Mar 08 00:11:03:86863] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[ Mar 08 00:11:03:86971] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000152 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND (sunshine.sunset == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[ Mar 08 00:11:03:87290] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000248 seconds
[ Mar 08 00:11:03:87359] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[ Mar 08 00:11:03:87468] pilight-daemon: DEBUG: task rules loop executed in 0.000431 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunset + 5.00
sunshine.sunset / + / 5.00
replace (sunshine.sunset + 5.00) with 23.110000 in (sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.110000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.110000
zeit.hour / + / 0.110000
replace (zeit.hour + (zeit.minute / 100)) with 1.110000 in 23.110000 == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) 23.110000 == 1.110000 AND zeit.second == 0
23.110000 / == / 1.110000
skip (AND) zeit.second == 0
replace ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[ Mar 08 00:11:03:88445] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000912 seconds
[ Mar 08 00:11:03:88513] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[ Mar 08 00:11:03:88645] pilight-daemon: DEBUG: task rules loop executed in 0.001115 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND ((sunshine.sunrise - 1.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[ Mar 08 00:11:03:88879] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000168 seconds
[ Mar 08 00:11:03:88942] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[ Mar 08 00:11:03:89042] pilight-daemon: DEBUG: task rules loop executed in 0.000334 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunrise
replace (sunshine.sunrise) with sunshine.sunrise in (sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.110000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.110000
zeit.hour / + / 0.110000
replace (zeit.hour + (zeit.minute / 100)) with 1.110000 in sunshine.sunrise == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) sunshine.sunrise == 1.110000 AND zeit.second == 0
sunshine.sunrise / == / 1.110000
skip (AND) zeit.second == 0
replace ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[ Mar 08 00:11:03:89863] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000759 seconds
[ Mar 08 00:11:03:89929] pilight-daemon: DEBUG: task rules loop executed in 0.000829 seconds
^C[Mar 08 00:11:03:819678] pilight-daemon: DEBUG: received interrupt signal, stopping pilight...
[Mar 08 00:11:03:824891] pilight-daemon: DEBUG: garbage collected event operator library
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
[Mar 08 00:11:03:825104] pilight-daemon: DEBUG: garbage collected event action library
[Mar 08 00:11:03:825154] pilight-daemon: DEBUG: garbage collected event function library
[Mar 08 00:11:03:825191] pilight-daemon: DEBUG: garbage collected events library
[Mar 08 00:11:03:825351] pilight-daemon: INFO: removed stale pid_file /var/run/pilight.pid
[Mar 08 00:11:03:825599] pilight-daemon: DEBUG: garbage collected fcache library
[Mar 08 00:11:03:825694] pilight-daemon: DEBUG: garbage collected sha256cache library
[Mar 08 00:11:03:825745] pilight-daemon: DEBUG: garbage collected webserver library
[Mar 08 00:11:03:825784] pilight-daemon: DEBUG: garbage collected datetime library
[Mar 08 00:11:03:825823] pilight-daemon: DEBUG: garbage collected options library
[Mar 08 00:11:03:825884] pilight-daemon: DEBUG: garbage collected socket library
[Mar 08 00:11:03:825929] pilight-daemon: DEBUG: [Z-Wave]: closing and freeing z-wave modules, please wait...
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
freed options struct
[Mar 08 00:11:03:826040] pilight-daemon: DEBUG: garbage collected config hardware library
[Mar 08 00:11:03:833216] pilight-daemon: DEBUG: garbage collected config rules library
[Mar 08 00:11:03:833324] pilight-daemon: DEBUG: garbage collected config storage library
protocol lm75
ran garbage collector
freed options struct


...

freed options struct
protocol generic_dimmer
freed options struct
protocol generic_weather
freed options struct
protocol process
freed options struct
[Mar 08 00:11:03:836295] pilight-daemon: DEBUG: garbage collected protocol library
[Mar 08 00:11:03:836346] pilight-daemon: DEBUG: garbage collected ntp library
[Mar 08 00:11:03:836556] pilight-daemon: DEBUG: garbage collected threads library
[Mar 08 00:11:03:837333] pilight-daemon: DEBUG: garbage collected wiringX library
[Mar 08 00:11:03:837409] pilight-daemon: DEBUG: garbage collected dso library
DEBUG: garbage collected log library
root@pi_65:/home/pi/rewrite/pilight# ^C
root@pi_65:/home/pi/rewrite/pilight#

In the following example the webgui remains active, only one label gets updated, the debug log stops, however after pressing one of the switch buttons in the GUI pilight resumes printing debug lines.

Code:
pilight stats 1457396238 911187873
[Mar 08 00:17:17:922387] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000099 seconds
activated worker 2, executing trigger REASON_CODE_RECEIVED
zeit 1457396238 922370560
IPcheckTV 1457396287 906977547
ntp sync 1457482627 983220241
sunshine 1457416020 908859207
[Mar 08 00:17:17:922555] pilight-daemon: DEBUG: task zeit executed in 0.001098 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:17:17:922724] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000043 seconds
[Mar 08 00:17:17:923892] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924045] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924160] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924246] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924282] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000130 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924350] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.001866 seconds
activated worker 2, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924404] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000028 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:17:924480] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000033 seconds
[Mar 08 00:17:17:924535] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[Mar 08 00:17:17:924649] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000152 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND (sunshine.sunset == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[Mar 08 00:17:17:925012] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000300 seconds
[Mar 08 00:17:17:925082] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[Mar 08 00:17:17:925185] pilight-daemon: DEBUG: task rules loop executed in 0.000475 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunset + 5.00
sunshine.sunset / + / 5.00
replace (sunshine.sunset + 5.00) with 23.110000 in (sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.170000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.170000
zeit.hour / + / 0.170000
replace (zeit.hour + (zeit.minute / 100)) with 1.170000 in 23.110000 == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) 23.110000 == 1.170000 AND zeit.second == 0
23.110000 / == / 1.170000
skip (AND) zeit.second == 0
replace ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[Mar 08 00:17:17:926127] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000883 seconds
[Mar 08 00:17:17:926192] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[Mar 08 00:17:17:926300] pilight-daemon: DEBUG: task rules loop executed in 0.001059 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND ((sunshine.sunrise - 1.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[Mar 08 00:17:17:926515] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000168 seconds
[Mar 08 00:17:17:926578] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[Mar 08 00:17:17:926677] pilight-daemon: DEBUG: task rules loop executed in 0.000331 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunrise
replace (sunshine.sunrise) with sunshine.sunrise in (sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.170000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.170000
zeit.hour / + / 0.170000
replace (zeit.hour + (zeit.minute / 100)) with 1.170000 in sunshine.sunrise == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) sunshine.sunrise == 1.170000 AND zeit.second == 0
sunshine.sunrise / == / 1.170000
skip (AND) zeit.second == 0
replace ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[Mar 08 00:17:17:927467] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000732 seconds
[Mar 08 00:17:17:927532] pilight-daemon: DEBUG: task rules loop executed in 0.000802 seconds
[Mar 08 00:17:18:911280] pilight-daemon: DEBUG: new threadpool task pilight stats
activated worker 1, executing pilight stats

= 5
zeit 1457396238 922370560
pilight stats 1457396241 911555843
IPcheckTV 1457396287 906977547
ntp sync 1457482627 983220241
sunshine 1457416020 908859207
[Mar 08 00:17:18:911845] pilight-daemon: DEBUG: cpu: 101.340409%, ram: 2.265775%
[Mar 08 00:17:18:911932] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_SENT
[Mar 08 00:17:18:912047] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_SENT
activated worker 2, executing trigger REASON_CODE_SENT
[Mar 08 00:17:18:912160] pilight-daemon: DEBUG: task pilight stats executed in 0.000645 seconds
activated worker 1, executing trigger REASON_CODE_SENT
[Mar 08 00:17:18:912183] pilight-daemon: DEBUG: broadcasted: {"values":{"cpu":101.340409,"ram":2.265775},"origin":"core","type":-1}
[Mar 08 00:17:18:912300] pilight-daemon: DEBUG: task trigger REASON_CODE_SENT executed in 0.000006 seconds
[Mar 08 00:17:18:912344] pilight-daemon: DEBUG: new threadpool task trigger REASON_BROADCAST_CORE
activated worker 1, executing trigger REASON_BROADCAST_CORE
[Mar 08 00:17:18:912450] pilight-daemon: DEBUG: task trigger REASON_CODE_SENT executed in 0.000292 seconds
[Mar 08 00:17:18:912529] pilight-daemon: DEBUG: task trigger REASON_BROADCAST_CORE executed in 0.000019 seconds
[Mar 08 00:17:18:922440] pilight-daemon: DEBUG: new threadpool task zeit
activated worker 1, executing zeit
[Mar 08 00:17:18:923152] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[Mar 08 00:17:18:923286] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
activated worker 2, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:17:18:923399] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[Mar 08 00:17:18:923421] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":8,"weekday":3,"hour":1,"minute":17,"second":18,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}

= 5
zeit 1457396239 923489081
[Mar 08 00:17:18:923498] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000095 seconds
activated worker 2, executing trigger REASON_CODE_RECEIVED
pilight stats 1457396241 911555843
IPcheckTV 1457396287 906977547
ntp sync 1457482627 983220241
sunshine 1457416020 908859207
[Mar 08 00:17:18:923676] pilight-daemon: DEBUG: task zeit executed in 0.001072 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:17:18:923849] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000034 seconds
[Mar 08 00:17:18:924965] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925116] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925225] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925310] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925344] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000127 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925406] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.001811 seconds
activated worker 2, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925459] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000025 seconds
activated worker 1, executing trigger REASON_CONFIG_UPDATE
[Mar 08 00:17:18:925528] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000030 seconds
[Mar 08 00:17:18:925581] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[Mar 08 00:17:18:925686] pilight-daemon: DEBUG: task trigger REASON_CONFIG_UPDATE executed in 0.000142 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND (sunshine.sunset == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[Mar 08 00:17:18:926010] pilight-daemon: DEBUG: rule #7 RuleLampen_on_sunset was parsed in 0.000271 seconds
[Mar 08 00:17:18:926079] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[Mar 08 00:17:18:926180] pilight-daemon: DEBUG: task rules loop executed in 0.000445 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunset + 5.00
sunshine.sunset / + / 5.00
replace (sunshine.sunset + 5.00) with 23.110000 in (sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.170000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.170000
zeit.hour / + / 0.170000
replace (zeit.hour + (zeit.minute / 100)) with 1.170000 in 23.110000 == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) 23.110000 == 1.170000 AND zeit.second == 0
23.110000 / == / 1.170000
skip (AND) zeit.second == 0
replace ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunset + 5.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[Mar 08 00:17:18:927110] pilight-daemon: DEBUG: rule #8 RuleLampen_off_4h_sunset was parsed in 0.000872 seconds
[Mar 08 00:17:18:927174] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 2, executing rules loop
[Mar 08 00:17:18:927280] pilight-daemon: DEBUG: task rules loop executed in 0.001046 seconds
evaluate (AND) LampenZeitschaltUhr.state IS off AND ((sunshine.sunrise - 1.00) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / off
skip (AND) 0
[Mar 08 00:17:18:927497] pilight-daemon: DEBUG: rule #9 RuleLampen_on_1h_sunrise was parsed in 0.000168 seconds
[Mar 08 00:17:18:927558] pilight-daemon: DEBUG: new threadpool task rules loop
activated worker 1, executing rules loop
[Mar 08 00:17:18:927656] pilight-daemon: DEBUG: task rules loop executed in 0.000330 seconds
evaluate (AND) LampenZeitschaltUhr.state IS on AND ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
LampenZeitschaltUhr.state / IS / on
evaluate (OR) sunshine.sunrise
replace (sunshine.sunrise) with sunshine.sunrise in (sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (OR) zeit.minute / 100
zeit.minute / / / 100
replace (zeit.minute / 100) with 0.170000 in zeit.hour + (zeit.minute / 100)
evaluate (OR) zeit.hour + 0.170000
zeit.hour / + / 0.170000
replace (zeit.hour + (zeit.minute / 100)) with 1.170000 in sunshine.sunrise == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0
evaluate (AND) sunshine.sunrise == 1.170000 AND zeit.second == 0
sunshine.sunrise / == / 1.170000
skip (AND) zeit.second == 0
replace ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0) with 0 in ((sunshine.sunrise) == (zeit.hour + (zeit.minute / 100)) AND zeit.second == 0)
skip (AND) 0
[Mar 08 00:17:18:928455] pilight-daemon: DEBUG: rule #10 RuleLampen_off_sunrise was parsed in 0.000740 seconds
[Mar 08 00:17:18:928522] pilight-daemon: DEBUG: task rules loop executed in 0.000810 seconds
[Mar 08 00:17:19:923572] pilight-daemon: DEBUG: new threadpool task zeit
[   Mar 08 00:17:29:107] pilight-daemon: DEBUG: removed worker: 2
[Mar 08 00:18:55:396262] pilight-daemon: DEBUG: new threadpool task trigger REASON_SOCKET_RECEIVED
activated worker 1, executing zeit
[Mar 08 00:18:55:396723] pilight-daemon: DEBUG: new threadpool task pilight stats
[Mar 08 00:18:55:397179] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[Mar 08 00:18:55:397254] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED
[Mar 08 00:18:55:397306] pilight-daemon: DEBUG: new threadpool task IPcheckTV
[Mar 08 00:18:55:397319] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_RECEIVED

= 3
zeit 1457396336 397422028
ntp sync 1457482627 983220241
sunshine 1457416020 908859207
[Mar 08 00:18:55:397494] pilight-daemon: DEBUG: task zeit executed in 0.000820 seconds
activated worker 1, executing trigger REASON_SOCKET_RECEIVED
[Mar 08 00:18:55:397607] pilight-daemon: DEBUG: socket recv: {"action":"control","code":{"device":"EG_Buero","state":"off"}}
[Mar 08 00:18:55:397882] pilight-daemon: DEBUG: new threadpool task trigger REASON_SEND_CODE
[Mar 08 00:18:55:398000] pilight-daemon: DEBUG: new threadpool task trigger REASON_SOCKET_SEND
[Mar 08 00:18:55:398061] pilight-daemon: DEBUG: new threadpool task trigger REASON_SOCKET_SEND
[Mar 08 00:18:55:398129] pilight-daemon: DEBUG: task trigger REASON_SOCKET_RECEIVED executed in 0.000569 seconds
activated worker 1, executing pilight stats

= 4
zeit 1457396336 397422028
pilight stats 1457396338 398227338
sunshine 1457416020 908859207
ntp sync 1457482627 983220241
[Mar 08 00:18:55:398413] pilight-daemon: DEBUG: cpu: 100.011967%, ram: 2.265775%
[Mar 08 00:18:55:398474] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_SENT
[Mar 08 00:18:55:398577] pilight-daemon: DEBUG: new threadpool task trigger REASON_CODE_SENT
[Mar 08 00:18:55:398652] pilight-daemon: DEBUG: task pilight stats executed in 0.000452 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:18:55:398734] pilight-daemon: DEBUG: broadcasted: {"message":{"longitude":10.559400,"latitude":52.244300,"year":2016,"month":3,"day":8,"weekday":3,"hour":1,"minute":18,"second":55,"dst":0},"origin":"receiver","protocol":"datetime","uuid":"0000-b8-27-eb-13d705","repeats":1}
[Mar 08 00:18:55:398782] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000063 seconds
activated worker 1, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:18:55:400019] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:18:55:400154] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:18:55:400238] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE
[Mar 08 00:18:55:400453] pilight-daemon: DEBUG: added worker: 2
activated worker 2, executing IPcheckTV
[Mar 08 00:18:55:400516] pilight-daemon: DEBUG: new threadpool task trigger REASON_CONFIG_UPDATE

= 5
zeit 1457396336 397422028
pilight stats 1457396338 398227338
sunshine 1457416020 908859207
ntp sync 1457482627 983220241
IPcheckTV 1457396395 400540767
[Mar 08 00:18:55:400615] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.001777 seconds
[Mar 08 00:18:55:400646] pilight-daemon: DEBUG: task IPcheckTV executed in 0.000106 seconds
activated worker 2, executing trigger REASON_SEND_CODE
activated worker 1, executing trigger REASON_CODE_RECEIVED
[Mar 08 00:18:55:400805] pilight-daemon: DEBUG: new threadpool task trigger REASON_SEND_BEGIN
[Mar 08 00:18:55:400820] pilight-daemon: DEBUG: task trigger REASON_CODE_RECEIVED executed in 0.000025 seconds
activated worker 1, executing trigger REASON_SOCKET_SEN
 
Reply
#10
Noticed that the webserver is rewritten for 50%. So any 100% CPU issues related to it can easily be solved now.

The other issues that might be present need a new topic because else it get's a mess in this one.

So, can we focus in this topic on the 100% issue? Any idea what happens when you get to a near 100% CPU?

Another idea could be increasing the CPU polling time to 1 sec to better see the exact moment it happens.
 
Reply
  


Forum Jump:


Browsing: 2 Guest(s)