Second IF doesn't evaluate

jamesxheath · 2020-06-23 14:47:10 UTC

1) Give a description of the problem
Newb here…
I have two concurrent if statements under another if.
When the top level if (#26) is true, the first of the lower level gets evaluated (#28), but the second one does not (#32).
This is clearly going to be my fault, but I can’t work out what’s wrong!

2) What is the expected behaviour?
whenever #28 is evaluated, #32 is evaluated after

3) What is happening/not happening?
only #28 is being evaluated,

**4) Post a Green Snapshot of the piston!

5) Attach logs after turning logging level to Full

    23/06/2020, 15:45:39 +78ms
    +1ms ╔Received event [Home].time = 1592923540309 with a delay of -1231ms
    +169ms ║RunTime Analysis CS &gt; 44ms &gt; PS &gt; 76ms &gt; PE &gt; 48ms &gt; CE
    +171ms ║Runtime (44162 bytes) successfully initialized in 76ms (v0.3.110.20191009) (169ms)
    +172ms ║╔Execution stage started
    +195ms ║║Comparison (string) 529 is_not (integer) 0 = true (2ms)
    +197ms ║║Condition #24 evaluated true (9ms)
    +198ms ║║Condition group #23 evaluated true (state did not change) (12ms)
    +208ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (1ms)
    +209ms ║║Condition #26 evaluated true (8ms)
    +211ms ║║Condition group #25 evaluated true (state did not change) (10ms)
    +219ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (1ms)
    +221ms ║║Condition #28 evaluated true (7ms)
    +222ms ║║Condition group #27 evaluated true (state did not change) (9ms)
    +224ms ║║Cancelling statement #29's schedules...
    +229ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
    +230ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:50:39 PM BST (in 300.0s)
    +237ms ║╚Execution stage complete. (64ms)
    +238ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:47:40 PM BST (in 120.993s), with 1 more job pending
    +247ms ╚Event processed successfully (247ms)
    23/06/2020, 15:43:39 +126ms
    +0ms ╔Received event [Home].time = 1592923420309 with a delay of -1184ms
    +157ms ║RunTime Analysis CS &gt; 36ms &gt; PS &gt; 68ms &gt; PE &gt; 54ms &gt; CE
    +160ms ║Runtime (44162 bytes) successfully initialized in 68ms (v0.3.110.20191009) (159ms)
    +161ms ║╔Execution stage started
    +183ms ║║Comparison (string) 529 is_not (integer) 0 = true (2ms)
    +185ms ║║Condition #24 evaluated true (9ms)
    +186ms ║║Condition group #23 evaluated true (state did not change) (10ms)
    +194ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (1ms)
    +196ms ║║Condition #26 evaluated true (8ms)
    +197ms ║║Condition group #25 evaluated true (state did not change) (9ms)
    +205ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
    +206ms ║║Condition #28 evaluated true (7ms)
    +208ms ║║Condition group #27 evaluated true (state did not change) (8ms)
    +210ms ║║Cancelling statement #29's schedules...
    +215ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
    +217ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:48:39 PM BST (in 300.0s)
    +223ms ║╚Execution stage complete. (63ms)
    +225ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:45:40 PM BST (in 120.959s), with 1 more job pending
    +232ms ╚Event processed successfully (232ms)
    23/06/2020, 15:41:40 +265ms
    +0ms ╔Received event [Home].time = 1592923300309 with a delay of -45ms
    +152ms ║RunTime Analysis CS &gt; 27ms &gt; PS &gt; 66ms &gt; PE &gt; 59ms &gt; CE
    +155ms ║Runtime (44160 bytes) successfully initialized in 66ms (v0.3.110.20191009) (154ms)
    +156ms ║╔Execution stage started
    +182ms ║║Comparison (string) 527 is_not (integer) 0 = true (1ms)
    +184ms ║║Condition #24 evaluated true (8ms)
    +185ms ║║Condition group #23 evaluated true (state did not change) (9ms)
    +194ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (1ms)
    +195ms ║║Condition #26 evaluated true (8ms)
    +196ms ║║Condition group #25 evaluated true (state did not change) (10ms)
    +204ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
    +206ms ║║Condition #28 evaluated true (7ms)
    +207ms ║║Condition group #27 evaluated true (state did not change) (9ms)
    +210ms ║║Cancelling statement #29's schedules...
    +215ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
    +216ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:46:40 PM BST (in 300.0s)
    +223ms ║╚Execution stage complete. (67ms)
    +225ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:43:40 PM BST (in 119.82s), with 1 more job pending
    +232ms ╚Event processed successfully (231ms)
    23/06/2020, 15:39:40 +82ms
    +1ms ╔Received event [Home].time = 1592923180309 with a delay of -227ms
    +144ms ║RunTime Analysis CS &gt; 40ms &gt; PS &gt; 60ms &gt; PE &gt; 43ms &gt; CE
    +147ms ║Runtime (44161 bytes) successfully initialized in 60ms (v0.3.110.20191009) (144ms)
    +148ms ║╔Execution stage started
    +169ms ║║Comparison (string) 529 is_not (integer) 0 = true (1ms)
    +171ms ║║Condition #24 evaluated true (8ms)
    +173ms ║║Condition group #23 evaluated true (state did not change) (10ms)
    +182ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (2ms)
    +184ms ║║Condition #26 evaluated true (8ms)
    +185ms ║║Condition group #25 evaluated true (state did not change) (9ms)
    +192ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
    +194ms ║║Condition #28 evaluated true (7ms)
    +195ms ║║Condition group #27 evaluated true (state did not change) (8ms)
    +198ms ║║Cancelling statement #29's schedules...
    +202ms ║║Executed virtual command [Belvedere Front Blind].wait (0ms)
    +204ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:44:40 PM BST (in 300.0s)
    +210ms ║╚Execution stage complete. (63ms)
    +212ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:41:40 PM BST (in 120.016s), with 1 more job pending
    +220ms ╚Event processed successfully (220ms)
    23/06/2020, 15:37:43 +61ms
    +1ms ╔Received event [Home].time = 1592923063778 with a delay of -717ms
    +141ms ║RunTime Analysis CS &gt; 31ms &gt; PS &gt; 59ms &gt; PE &gt; 51ms &gt; CE
    +144ms ║Runtime (44162 bytes) successfully initialized in 59ms (v0.3.110.20191009) (141ms)
    +145ms ║╔Execution stage started
    +152ms ║║Cancelling condition #22's schedules...
    +153ms ║║Condition #22 evaluated true (1ms)
    +158ms ║║Comparison (integer) 15 is_less_than (integer) 12 = false (2ms)
    +160ms ║║Condition #40 evaluated false (6ms)
    +161ms ║║Condition group #2 evaluated false (state did not change) (10ms)
    +162ms ║║Condition group #1 evaluated false (state did not change) (11ms)
    +167ms ║╚Execution stage complete. (23ms)
    +169ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:39:40 PM BST (in 117.08s), with 1 more job pending
    +182ms ╚Event processed successfully (181ms)
    23/06/2020, 15:37:39 +51ms
    +1ms ╔Received event [Home].time = 1592923060309 with a delay of -1258ms
    +310ms ║RunTime Analysis CS &gt; 23ms &gt; PS &gt; 230ms &gt; PE &gt; 58ms &gt; CE
    +314ms ║Runtime (44165 bytes) successfully initialized in 230ms (v0.3.110.20191009) (311ms)
    +315ms ║╔Execution stage started
    +341ms ║║Comparison (string) 529 is_not (integer) 0 = true (1ms)
    +342ms ║║Condition #24 evaluated true (8ms)
    +343ms ║║Condition group #23 evaluated true (state did not change) (10ms)
    +352ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (2ms)
    +353ms ║║Condition #26 evaluated true (8ms)
    +354ms ║║Condition group #25 evaluated true (state did not change) (9ms)
    +366ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
    +369ms ║║Condition #28 evaluated true (10ms)
    +370ms ║║Condition group #27 evaluated true (state did not change) (12ms)
    +373ms ║║Cancelling statement #29's schedules...
    +378ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
    +380ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:42:39 PM BST (in 300.0s)
    +387ms ║╚Execution stage complete. (73ms)
    +389ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:37:43 PM BST (in 4.339s), with 2 more jobs pending
    +398ms ╚Event processed successfully (398ms)
    23/06/2020, 15:35:43 +656ms
    +1ms ╔Received event [Belvedere Sensor].illuminance = 777 with a delay of 54ms
    +102ms ║RunTime Analysis CS &gt; 15ms &gt; PS &gt; 33ms &gt; PE &gt; 53ms &gt; CE
    +105ms ║Runtime (44168 bytes) successfully initialized in 33ms (v0.3.110.20191009) (102ms)
    +106ms ║╔Execution stage started
    +118ms ║║Comparison (integer) 777 stays_greater_than (integer) 500 = true (2ms)
    +121ms ║║Adding a timed trigger schedule for condition 22
    +124ms ║║Cancelling condition #22's schedules...
    +125ms ║║Condition #22 evaluated false (13ms)
    +126ms ║║Condition group #2 evaluated false (state did not change) (14ms)
    +128ms ║║Condition group #1 evaluated false (state did not change) (16ms)
    +134ms ║╚Execution stage complete. (27ms)
    +136ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:37:40 PM BST (in 116.518s), with 2 more jobs pending
    +240ms ╚Event processed successfully (239ms)
    23/06/2020, 15:35:39 +84ms
    +0ms ╔Received event [Home].time = 1592922940309 with a delay of -1225ms
    +111ms ║RunTime Analysis CS &gt; 17ms &gt; PS &gt; 52ms &gt; PE &gt; 41ms &gt; CE
    +113ms ║Runtime (44162 bytes) successfully initialized in 52ms (v0.3.110.20191009) (111ms)
    +114ms ║╔Execution stage started
    +137ms ║║Comparison (string) 529 is_not (integer) 0 = true (2ms)
    +139ms ║║Condition #24 evaluated true (9ms)
    +140ms ║║Condition group #23 evaluated true (state did not change) (10ms)
    +149ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (1ms)
    +150ms ║║Condition #26 evaluated true (8ms)
    +151ms ║║Condition group #25 evaluated true (state did not change) (9ms)
    +160ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
    +162ms ║║Condition #28 evaluated true (8ms)
    +164ms ║║Condition group #27 evaluated true (state did not change) (9ms)
    +167ms ║║Cancelling statement #29's schedules...
    +172ms ║║Executed virtual command [Belvedere Front Blind].wait (0ms)
    +174ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:40:39 PM BST (in 300.0s)
    +180ms ║╚Execution stage complete. (66ms)
    +182ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:37:40 PM BST (in 121.044s), with 1 more job pending
    +190ms ╚Event processed successfully (190ms)
    23/06/2020, 15:33:39 +83ms
    +1ms ╔Received event [Home].time = 1592922820309 with a delay of -1226ms
    +121ms ║RunTime Analysis CS &gt; 21ms &gt; PS &gt; 51ms &gt; PE &gt; 48ms &gt; CE
    +124ms ║Runtime (44162 bytes) successfully initialized in 51ms (v0.3.110.20191009) (121ms)
    +125ms ║╔Execution stage started
    +161ms ║║Comparison (string) 529 is_not (integer) 0 = true (3ms)
    +164ms ║║Condition #24 evaluated true (13ms)
    +166ms ║║Condition group #23 evaluated true (state did not change) (16ms)
    +182ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (3ms)
    +185ms ║║Condition #26 evaluated true (14ms)
    +187ms ║║Condition group #25 evaluated true (state did not change) (17ms)
    +203ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (4ms)
    +207ms ║║Condition #28 evaluated true (14ms)
    +209ms ║║Condition group #27 evaluated true (state did not change) (18ms)
    +214ms ║║Cancelling statement #29's schedules...
    +223ms ║║Executed virtual command [Belvedere Front Blind].wait (2ms)
    +226ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:38:39 PM BST (in 300.0s)
    +239ms ║╚Execution stage complete. (115ms)
    +243ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:35:40 PM BST (in 120.985s), with 1 more job pending
    +256ms ╚Event processed successfully (256ms)
    23/06/2020, 15:31:39 +109ms
    +1ms ╔Received event [Home].time = 1592922700309 with a delay of -1201ms
    +165ms ║RunTime Analysis CS &gt; 57ms &gt; PS &gt; 59ms &gt; PE &gt; 49ms &gt; CE
    +168ms ║Runtime (44162 bytes) successfully initialized in 59ms (v0.3.110.20191009) (166ms)
    +169ms ║╔Execution stage started
    +196ms ║║Comparison (string) 530 is_not (integer) 0 = true (1ms)
    +198ms ║║Condition #24 evaluated true (9ms)
    +199ms ║║Condition group #23 evaluated true (state did not change) (11ms)
    +209ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (2ms)
    +211ms ║║Condition #26 evaluated true (8ms)
    +212ms ║║Condition group #25 evaluated true (state did not change) (11ms)
    +221ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
    +223ms ║║Condition #28 evaluated true (7ms)
    +224ms ║║Condition group #27 evaluated true (state did not change) (10ms)
    +227ms ║║Cancelling statement #29's schedules...
    +232ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
    +234ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:36:39 PM BST (in 300.0s)
    +241ms ║╚Execution stage complete. (72ms)
    +243ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:33:40 PM BST (in 120.958s), with 1 more job pending
    +253ms ╚Event processed successfully (253ms)
    23/06/2020, 15:30:20 +306ms
    +0ms ╔Received event [Home].time = 1592922620954 with a delay of -648ms
    +469ms ║RunTime Analysis CS &gt; 186ms &gt; PS &gt; 242ms &gt; PE &gt; 40ms &gt; CE
    +472ms ║Runtime (44165 bytes) successfully initialized in 242ms (v0.3.110.20191009) (470ms)
    +473ms ║╔Execution stage started
    +480ms ║║Cancelling condition #22's schedules...
    +481ms ║║Condition #22 evaluated true (1ms)
    +486ms ║║Comparison (integer) 15 is_less_than (integer) 12 = false (1ms)
    +488ms ║║Condition #40 evaluated false (6ms)
    +489ms ║║Condition group #2 evaluated false (state did not change) (10ms)
    +491ms ║║Condition group #1 evaluated false (state did not change) (11ms)
    +668ms ║╚Execution stage complete. (196ms)
    +670ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:31:40 PM BST (in 79.334s), with 1 more job pending
    +678ms ╚Event processed successfully (677ms)
    23/06/2020, 15:29:39 +68ms
    +0ms ╔Received event [Home].time = 1592922580309 with a delay of -1242ms
    +117ms ║RunTime Analysis CS &gt; 33ms &gt; PS &gt; 43ms &gt; PE &gt; 42ms &gt; CE
    +120ms ║Runtime (44163 bytes) successfully initialized in 43ms (v0.3.110.20191009) (118ms)
    +121ms ║╔Execution stage started
    +142ms ║║Comparison (string) 530 is_not (integer) 0 = true (1ms)
    +143ms ║║Condition #24 evaluated true (9ms)
    +144ms ║║Condition group #23 evaluated true (state did not change) (10ms)
    +154ms ║║Comparison (decimal) 24.3 is_greater_than (integer) 22 = true (1ms)
    +155ms ║║Condition #26 evaluated true (9ms)
    +156ms ║║Condition group #25 evaluated true (state did not change) (10ms)
    +164ms ║║Comparison (integer) 15 is_inside_of_range (integer) 10 .. (integer) 17 = true (1ms)
    +165ms ║║Condition #28 evaluated true (7ms)
    +166ms ║║Condition group #27 evaluated true (state did not change) (8ms)
    +169ms ║║Cancelling statement #29's schedules...
    +174ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
    +176ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 3:34:39 PM BST (in 300.0s)
    +182ms ║╚Execution stage complete. (62ms)
    +184ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:30:20 PM BST (in 41.703s), with 2 more jobs pending
    +196ms ╚Event processed successfully (197ms)
    23/06/2020, 15:29:26 +203ms
    +1ms ╔Received event [Belvedere Sensor].illuminance = 728 with a delay of 49ms
    +106ms ║RunTime Analysis CS &gt; 13ms &gt; PS &gt; 30ms &gt; PE &gt; 63ms &gt; CE
    +109ms ║Runtime (44169 bytes) successfully initialized in 30ms (v0.3.110.20191009) (107ms)
    +111ms ║╔Execution stage started
    +123ms ║║Comparison (integer) 728 stays_greater_than (integer) 500 = true (2ms)
    +126ms ║║Condition #22 evaluated false (8ms)
    +127ms ║║Condition group #2 evaluated false (state did not change) (11ms)
    +128ms ║║Condition group #1 evaluated false (state did not change) (12ms)
    +134ms ║╚Execution stage complete. (23ms)
    +136ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:29:40 PM BST (in 13.971s), with 1 more job pending
    +148ms ╚Event processed successfully (147ms)
    23/06/2020, 15:28:20 +819ms
    +0ms ╔Received event [Home].test = 1592922500818 with a delay of 0ms
    +109ms ║RunTime Analysis CS &gt; 20ms &gt; PS &gt; 34ms &gt; PE &gt; 55ms &gt; CE
    +112ms ║Runtime (44158 bytes) successfully initialized in 34ms (v0.3.110.20191009) (111ms)
    +113ms ║╔Execution stage started
    +126ms ║║Comparison (integer) 532 stays_greater_than (integer) 500 = true (2ms)
    +134ms ║║Adding a timed trigger schedule for condition 22
    +139ms ║║Cancelling condition #22's schedules...
    +141ms ║║Condition #22 evaluated false (22ms)
    +142ms ║║Condition group #2 evaluated false (state did not change) (24ms)
    +143ms ║║Condition group #1 evaluated false (state did not change) (26ms)
    +182ms ║╚Execution stage complete. (68ms)
    +186ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 3:29:40 PM BST (in 79.305s), with 1 more job pending
    +221ms ╚Event processed successfully (221ms)

WCmore · 2020-06-23 15:29:46 UTC

I believe you can click on the WITH on line 42, and change Task Cancellation Policy to “Never”.
(There is a chance you may need to do that with the other two WITH blocks that hold the WAITS)

Pro Tip:

You may also want to re-consider this portion:

Every 2 minutes
    Wait 5 minutes
    Do something
END EVERY

Ideally, a piston should run to completion before running again.

jamesxheath · 2020-06-23 15:45:06 UTC

Thanks for the tips - I’ve tried changing the cancellation policy (something I need to read up on - I’ve not messed with them before), and that does not seem to make a difference.

Your pro tip is very valid… I’m not clear yet on how / whether threading works in WebCore, and I could be getting myself in a mess - I may just move it to executing on a longer timescale.
(unfortunately the range of triggers available for a genericValue are limited, so I’ve dropped back to using a condition & a timer)

I’ll have a further play…

WCmore · 2020-06-23 15:47:05 UTC

Can you clear your old log, set log level to full, and run a new test?

We’d need to see the new piston (green snapshot) as well as the new logs.

jamesxheath · 2020-06-23 16:00:50 UTC

OK - I’m doing that now, with the times as originally scripted.
However, in the interim I tried it reducing the waits to one minute, so below the repeat time for the statement, and it then works - which makes sense I think…
I was asking it to execute the statement every 2 mins, but wait within it for up to 10 mins (if both blinds needed closing) - not clever!
I’ll let the current run for long enough to pass the 1st 5 min timer…

WCmore · 2020-06-23 16:02:21 UTC

In general, it is good practice to let the piston complete the full cycle before starting again.

There’s no need to force back to bad times. Just make sure the next green snapshot matches the next log you post. (with old logs deleted please)

jamesxheath · 2020-06-23 16:11:30 UTC

Here you go, though the more I look at this, the more I’m convinced I ought to re-write this to use triggers instead of conditions.

23/06/2020, 17:09:55 +74ms
+1ms ╔Received event [Home].time = 1592928595506 with a delay of -432ms
+102ms ║RunTime Analysis CS &gt; 19ms &gt; PS &gt; 40ms &gt; PE &gt; 43ms &gt; CE
+105ms ║Runtime (44141 bytes) successfully initialized in 40ms (v0.3.110.20191009) (103ms)
+106ms ║╔Execution stage started
+127ms ║║Comparison (string) 526 is_not (integer) 0 = true (2ms)
+128ms ║║Condition #24 evaluated true (8ms)
+129ms ║║Condition group #23 evaluated true (state did not change) (9ms)
+138ms ║║Comparison (decimal) 24.8 is_greater_than (integer) 22 = true (1ms)
+139ms ║║Condition #26 evaluated true (7ms)
+140ms ║║Condition group #25 evaluated true (state did not change) (9ms)
+147ms ║║Comparison (integer) 17 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
+149ms ║║Condition #28 evaluated true (7ms)
+150ms ║║Condition group #27 evaluated true (state did not change) (8ms)
+152ms ║║Cancelling statement #29's schedules...
+157ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
+158ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 5:14:55 PM BST (in 300.0s)
+164ms ║╚Execution stage complete. (58ms)
+166ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 5:11:55 PM BST (in 120.267s), with 1 more job pending
+177ms ╚Event processed successfully (177ms)
23/06/2020, 17:07:55 +64ms
+0ms ╔Received event [Home].time = 1592928475506 with a delay of -443ms
+115ms ║RunTime Analysis CS &gt; 17ms &gt; PS &gt; 39ms &gt; PE &gt; 60ms &gt; CE
+118ms ║Runtime (44141 bytes) successfully initialized in 39ms (v0.3.110.20191009) (117ms)
+119ms ║╔Execution stage started
+145ms ║║Comparison (string) 526 is_not (integer) 0 = true (2ms)
+147ms ║║Condition #24 evaluated true (10ms)
+148ms ║║Condition group #23 evaluated true (state did not change) (11ms)
+158ms ║║Comparison (decimal) 24.8 is_greater_than (integer) 22 = true (1ms)
+159ms ║║Condition #26 evaluated true (8ms)
+161ms ║║Condition group #25 evaluated true (state did not change) (9ms)
+170ms ║║Comparison (integer) 17 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
+172ms ║║Condition #28 evaluated true (8ms)
+173ms ║║Condition group #27 evaluated true (state did not change) (10ms)
+176ms ║║Cancelling statement #29's schedules...
+181ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
+183ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 5:12:55 PM BST (in 300.0s)
+190ms ║╚Execution stage complete. (71ms)
+192ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 5:09:55 PM BST (in 120.251s), with 1 more job pending
+200ms ╚Event processed successfully (200ms)
23/06/2020, 17:05:55 +72ms
+0ms ╔Received event [Home].time = 1592928355506 with a delay of -435ms
+93ms ║RunTime Analysis CS &gt; 16ms &gt; PS &gt; 40ms &gt; PE &gt; 37ms &gt; CE
+95ms ║Runtime (44140 bytes) successfully initialized in 40ms (v0.3.110.20191009) (94ms)
+96ms ║╔Execution stage started
+116ms ║║Comparison (string) 523 is_not (integer) 0 = true (2ms)
+118ms ║║Condition #24 evaluated true (8ms)
+119ms ║║Condition group #23 evaluated true (state did not change) (9ms)
+127ms ║║Comparison (decimal) 24.8 is_greater_than (integer) 22 = true (1ms)
+129ms ║║Condition #26 evaluated true (8ms)
+130ms ║║Condition group #25 evaluated true (state did not change) (9ms)
+138ms ║║Comparison (integer) 17 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
+140ms ║║Condition #28 evaluated true (7ms)
+141ms ║║Condition group #27 evaluated true (state did not change) (8ms)
+143ms ║║Cancelling statement #29's schedules...
+149ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
+150ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 5:10:55 PM BST (in 300.0s)
+156ms ║╚Execution stage complete. (60ms)
+157ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 5:07:55 PM BST (in 120.277s), with 1 more job pending
+165ms ╚Event processed successfully (165ms)
23/06/2020, 17:03:55 +81ms
+0ms ╔Received event [Home].time = 1592928235506 with a delay of -426ms
+181ms ║RunTime Analysis CS &gt; 56ms &gt; PS &gt; 84ms &gt; PE &gt; 41ms &gt; CE
+184ms ║Runtime (44141 bytes) successfully initialized in 84ms (v0.3.110.20191009) (182ms)
+185ms ║╔Execution stage started
+208ms ║║Comparison (string) 524 is_not (integer) 0 = true (2ms)
+210ms ║║Condition #24 evaluated true (11ms)
+211ms ║║Condition group #23 evaluated true (state did not change) (12ms)
+221ms ║║Comparison (decimal) 24.8 is_greater_than (integer) 22 = true (2ms)
+222ms ║║Condition #26 evaluated true (9ms)
+223ms ║║Condition group #25 evaluated true (state did not change) (10ms)
+232ms ║║Comparison (integer) 17 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
+234ms ║║Condition #28 evaluated true (8ms)
+235ms ║║Condition group #27 evaluated true (state did not change) (10ms)
+238ms ║║Cancelling statement #29's schedules...
+244ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
+245ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 5:08:55 PM BST (in 300.0s)
+252ms ║╚Execution stage complete. (68ms)
+254ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 5:05:55 PM BST (in 120.172s), with 1 more job pending
+262ms ╚Event processed successfully (262ms)
23/06/2020, 17:01:55 +79ms
+0ms ╔Received event [Home].time = 1592928115506 with a delay of -428ms
+94ms ║RunTime Analysis CS &gt; 20ms &gt; PS &gt; 38ms &gt; PE &gt; 37ms &gt; CE
+97ms ║Runtime (44140 bytes) successfully initialized in 38ms (v0.3.110.20191009) (95ms)
+97ms ║╔Execution stage started
+119ms ║║Comparison (string) 526 is_not (integer) 0 = true (2ms)
+120ms ║║Condition #24 evaluated true (8ms)
+121ms ║║Condition group #23 evaluated true (state did not change) (10ms)
+129ms ║║Comparison (decimal) 24.8 is_greater_than (integer) 22 = true (2ms)
+130ms ║║Condition #26 evaluated true (7ms)
+131ms ║║Condition group #25 evaluated true (state did not change) (8ms)
+139ms ║║Comparison (integer) 17 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
+141ms ║║Condition #28 evaluated true (6ms)
+142ms ║║Condition group #27 evaluated true (state did not change) (8ms)
+144ms ║║Cancelling statement #29's schedules...
+149ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
+150ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 5:06:55 PM BST (in 300.0s)
+156ms ║╚Execution stage complete. (59ms)
+158ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 5:03:55 PM BST (in 120.27s), with 1 more job pending
+166ms ╚Event processed successfully (166ms)
23/06/2020, 16:59:54 +227ms
+1ms ╔Received event [Home].time = 1592927995506 with a delay of -1279ms
+148ms ║RunTime Analysis CS &gt; 31ms &gt; PS &gt; 59ms &gt; PE &gt; 57ms &gt; CE
+150ms ║Runtime (44142 bytes) successfully initialized in 59ms (v0.3.110.20191009) (149ms)
+151ms ║╔Execution stage started
+173ms ║║Comparison (string) 526 is_not (integer) 0 = true (2ms)
+174ms ║║Condition #24 evaluated true (8ms)
+175ms ║║Condition group #23 evaluated true (state did not change) (9ms)
+184ms ║║Comparison (decimal) 24.8 is_greater_than (integer) 22 = true (1ms)
+186ms ║║Condition #26 evaluated true (8ms)
+186ms ║║Condition group #25 evaluated true (state did not change) (9ms)
+194ms ║║Comparison (integer) 16 is_inside_of_range (integer) 10 .. (integer) 17 = true (1ms)
+195ms ║║Condition #28 evaluated true (6ms)
+196ms ║║Condition group #27 evaluated true (state did not change) (8ms)
+198ms ║║Cancelling statement #29's schedules...
+203ms ║║Executed virtual command [Belvedere Front Blind].wait (1ms)
+204ms ║║Requesting a wake up for Tue, Jun 23 2020 @ 5:04:54 PM BST (in 300.0s)
+209ms ║╚Execution stage complete. (58ms)
+211ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 5:01:55 PM BST (in 121.069s), with 1 more job pending
+223ms ╚Event processed successfully (223ms)
23/06/2020, 16:57:55 +68ms
+1ms ╔Starting piston... (v0.3.110.20191009)
+288ms ║╔Subscribing to devices...
+313ms ║║Subscribing to Belvedere Sensor.illuminance...
+388ms ║║Subscribing to Belvedere East Blind...
+389ms ║║Subscribing to RESOL Solar Flowrate...
+390ms ║║Subscribing to Belvedere Front Blind...
+392ms ║║Subscribing to Belvedere West Blind...
+393ms ║║Subscribing to Dining Room Sensor...
+394ms ║║Subscribing to LGF Sensor...
+395ms ║║Subscribing to Hall Blind...
+396ms ║╚Finished subscribing (116ms)
+421ms ║Comparison (integer) 321 stays_greater_than (integer) 500 = false (1ms)
+426ms ║Comparison (integer) 16 is_less_than (integer) 12 = false (1ms)
+453ms ║Comparison (string) 524 is_not (integer) 0 = true (2ms)
+463ms ║Comparison (decimal) 24.5 is_greater_than (integer) 22 = true (1ms)
+471ms ║Comparison (integer) 16 is_inside_of_range (integer) 10 .. (integer) 17 = true (2ms)
+483ms ║Comparison (integer) 16 is_inside_of_range (integer) 14 .. (integer) 18 = true (2ms)
+500ms ║Comparison (decimal) 25.2 is_greater_than (integer) 22 = true (1ms)
+507ms ║Comparison (integer) 16 is_inside_of_range (integer) 11 .. (integer) 16 = true (2ms)
+514ms ║Setting up scheduled job for Tue, Jun 23 2020 @ 4:59:55 PM BST (in 119.925s)
+530ms ╚Piston successfully started (528ms)

WCmore · 2020-06-23 16:20:24 UTC

If you mean a single trigger, then I agree wholeheartedly…

Notice in your last post, your tiny 2 min loop will trump all the longer 5 min loops inside…

jamesxheath · 2020-06-23 16:22:42 UTC

Oh yes - I get that - I moved it back to that to run & get the logs with it failing.

Sorry - I should have been clearer - picking up your pro tip DOES fix it - so I’ll go back to that & call it fixed!

(and yes - apologies for my loose grammar - I did mean a SINGLE trigger)

Best,

James

WCmore · 2020-06-23 16:24:01 UTC

Thanks for the feedback.