Single Motion Sensor Triggering Two Similar Pistons

sechen43 · 2018-01-14 18:07:29 UTC

1) Give a description of the problem
I have two similar pistons being triggered by motion active/inactive from a single motion detector. These pistons are based on one of the motion light examples. I broke them out to keep things simple as I will introduce different lighting to the patio lights and I may want to switch the patio lights off/on to keep them on if we’re sitting out late night, but still have the street lights go off automatically after 5 min of inactivity. In general these pistons are set to run from midnight - sunrise (time currently adjusted for testing). Sometimes everything fires as intended for both the turn lights on and turn lights off conditions. However, sometimes one pistons lights either wont come on or shut off. It seems as though i’m having trouble running the pistons concurrently with a common triggering device. Any suggestions are appreciated.

2) What is the expected behavior?
Both pistons fire at the same time and consistently turn their respective lights on/off when there is motion / inactivity.

3) What is happening/not happening?
Sometimes a piston seems not to fire (typically the street side piston). Either the lights don’t come on, or they don’t shut off. On one occasion (at least), the street lights remand on, when the patio lights shut off as expected, and then the piston triggered and shut them off several hours later (on its own w/o intervention).

4) Post a Green Snapshot of the piston!

5) Attach any logs (From ST IDE and by turning logging level to Full)
`(Street side didnt come on but patio side did both sets of logs below:

Street Side:

1/14/2018, 11:58:50 AM +221ms
+2ms ╔Received event [Front Doorbell].motion = inactive with a delay of 80ms
+145ms ║RunTime Analysis CS > 22ms > PS > 50ms > PE > 73ms > CE
+148ms ║Runtime (42712 bytes) successfully initialized in 50ms (v0.2.101.20171227) (146ms)
+149ms ║╔Execution stage started
+156ms ║║Comparison (enum) inactive changes_to (string) active = false (1ms)
+157ms ║║Cancelling condition #9’s schedules…
+158ms ║║Condition #9 evaluated false (5ms)
+159ms ║║Condition group #3 evaluated false (state did not change) (6ms)
+163ms ║║Comparison (enum) inactive changes_to (string) inactive = true (0ms)
+165ms ║║Cancelling condition #21’s schedules…
+165ms ║║Condition #21 evaluated true (4ms)
+169ms ║║Comparison (boolean) false is (boolean) true = false (1ms)
+170ms ║║Condition #24 evaluated false (3ms)
+170ms ║║Condition group #15 evaluated false (state did not change) (9ms)
+217ms ║║Condition #28 evaluated false (44ms)
+218ms ║║Condition group #25 evaluated false (state did not change) (45ms)
+219ms ║╚Execution stage complete. (71ms)
+220ms ╚Event processed successfully (220ms)
1/14/2018, 11:58:20 AM +703ms
+2ms ╔Received event [Front Doorbell].motion = active with a delay of 93ms
+141ms ║RunTime Analysis CS > 24ms > PS > 51ms > PE > 66ms > CE
+143ms ║Runtime (42712 bytes) successfully initialized in 51ms (v0.2.101.20171227) (141ms)
+144ms ║╔Execution stage started
+151ms ║║Comparison (enum) active changes_to (string) active = true (1ms)
+152ms ║║Cancelling condition #9’s schedules…
+153ms ║║Condition #9 evaluated true (5ms)
+193ms ║║Comparison (enum) off is (string) off = true (1ms)
+195ms ║║Comparison (enum) off is (string) off = true (1ms)
+197ms ║║Comparison (enum) off is (string) off = true (1ms)
+199ms ║║Comparison (enum) off is (string) off = true (1ms)
+200ms ║║Comparison (enum) off is (string) off = true (1ms)
+202ms ║║Comparison (enum) off is (string) off = true (1ms)
+204ms ║║Comparison (enum) off is (string) off = true (1ms)
+205ms ║║Comparison (enum) on is (string) off = false (1ms)
+208ms ║║Cancelling condition #32’s schedules…
+208ms ║║Condition #32 evaluated false (54ms)
+209ms ║║Condition group #3 evaluated false (state did not change) (61ms)
+215ms ║║Comparison (enum) active changes_to (string) inactive = false (0ms)
+216ms ║║Condition #21 evaluated false (3ms)
+217ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+245ms ║║Condition #28 evaluated false (25ms)
+246ms ║║Condition group #25 evaluated false (state did not change) (27ms)
+248ms ║╚Execution stage complete. (104ms)
+249ms ╚Event processed successfully (249ms)

Patio Side:

1/14/2018, 12:03:50 PM +162ms
+0ms ╔Received event [Patio West Coach Light].switch = off with a delay of 900ms
+10176ms ║RunTime Analysis CS > 11ms > PS > 10056ms > PE > 110ms > CE
+10177ms ║Piston waited at a semaphore for 10021ms
+10179ms ║Runtime (41019 bytes) successfully initialized in 10056ms (v0.2.101.20171227) (10178ms)
+10180ms ║╔Execution stage started
+10191ms ║║Condition #9 evaluated false (7ms)
+10192ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+10201ms ║║Condition #21 evaluated false (6ms)
+10201ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+10211ms ║║Comparison (enum) off changes_to (string) off = false (1ms)
+10212ms ║║Cancelling condition #28’s schedules…
+10213ms ║║Condition #28 evaluated false (9ms)
+10214ms ║║Cancelling condition #25’s schedules…
+10215ms ║║Condition group #25 evaluated false (state changed) (10ms)
+10216ms ║╚Execution stage complete. (37ms)
+10217ms ╚Event processed successfully (10218ms)
1/14/2018, 12:03:50 PM +145ms
+2ms ╔Received event [Patio East Coach Light].switch = off with a delay of 950ms
+147ms ║RunTime Analysis CS > 23ms > PS > 48ms > PE > 75ms > CE
+149ms ║Runtime (40939 bytes) successfully initialized in 48ms (v0.2.101.20171227) (146ms)
+150ms ║╔Execution stage started
+160ms ║║Condition #9 evaluated false (6ms)
+161ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+168ms ║║Cancelling condition #21’s schedules…
+168ms ║║Condition #21 evaluated false (5ms)
+169ms ║║Cancelling condition #15’s schedules…
+170ms ║║Condition group #15 evaluated false (state changed) (7ms)
+180ms ║║Comparison (enum) off changes_to (string) off = true (0ms)
+182ms ║║Cancelling condition #28’s schedules…
+182ms ║║Condition #28 evaluated true (10ms)
+183ms ║║Cancelling condition #25’s schedules…
+184ms ║║Condition group #25 evaluated true (state changed) (12ms)
+186ms ║║Cancelling statement #26’s schedules…
+191ms ║║Executed virtual command setVariable (3ms)
+193ms ║╚Execution stage complete. (43ms)
+194ms ╚Event processed successfully (194ms)
1/14/2018, 12:03:49 PM +629ms
+1ms ╔Received event [Home].time = 1515953030359 with a delay of -730ms
+162ms ║RunTime Analysis CS > 19ms > PS > 54ms > PE > 88ms > CE
+165ms ║Runtime (40926 bytes) successfully initialized in 54ms (v0.2.101.20171227) (163ms)
+166ms ║╔Execution stage started
+207ms ║║Executed physical command [Patio East Coach Light].off() (14ms)
+208ms ║║Executed [Patio East Coach Light].off (15ms)
+224ms ║║Executed physical command [Patio West Coach Light].off() (13ms)
+225ms ║║Executed [Patio West Coach Light].off (14ms)
+232ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].setVariable (3ms)
+249ms ║║Condition #28 evaluated false (11ms)
+250ms ║║Condition group #25 evaluated false (state did not change) (13ms)
+252ms ║╚Execution stage complete. (86ms)
+254ms ╚Event processed successfully (253ms)
1/14/2018, 11:58:50 AM +217ms
+0ms ╔Received event [Front Doorbell].motion = inactive with a delay of 75ms
+108ms ║RunTime Analysis CS > 11ms > PS > 26ms > PE > 71ms > CE
+110ms ║Runtime (40936 bytes) successfully initialized in 26ms (v0.2.101.20171227) (109ms)
+111ms ║╔Execution stage started
+118ms ║║Comparison (enum) inactive changes_to (string) active = false (1ms)
+119ms ║║Condition #9 evaluated false (4ms)
+120ms ║║Condition group #3 evaluated false (state did not change) (5ms)
+124ms ║║Comparison (enum) inactive changes_to (string) inactive = true (0ms)
+126ms ║║Cancelling condition #21’s schedules…
+126ms ║║Condition #21 evaluated true (4ms)
+130ms ║║Comparison (boolean) true is (boolean) true = true (1ms)
+131ms ║║Cancelling condition #24’s schedules…
+132ms ║║Condition #24 evaluated true (5ms)
+133ms ║║Cancelling condition #15’s schedules…
+134ms ║║Condition group #15 evaluated true (state changed) (11ms)
+136ms ║║Cancelling statement #18’s schedules…
+141ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].wait (0ms)
+142ms ║║Requesting a wake up for Sun, Jan 14 2018 @ 12:03:50 PM CST (in 300.0s)
+145ms ║╚Execution stage complete. (34ms)
+147ms ║Setting up scheduled job for Sun, Jan 14 2018 @ 12:03:50 PM CST (in 299.996s)
+157ms ╚Event processed successfully (158ms)
1/14/2018, 11:58:21 AM +245ms
+0ms ╔Received event [Patio West Coach Light].switch = on with a delay of 862ms
+10237ms ║RunTime Analysis CS > 15ms > PS > 10132ms > PE > 91ms > CE
+10238ms ║Piston waited at a semaphore for 10106ms
+10240ms ║Runtime (41019 bytes) successfully initialized in 10132ms (v0.2.101.20171227) (10239ms)
+10241ms ║╔Execution stage started
+10250ms ║║Condition #9 evaluated false (5ms)
+10251ms ║║Condition group #3 evaluated false (state did not change) (6ms)
+10258ms ║║Condition #21 evaluated false (5ms)
+10259ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+10270ms ║║Comparison (enum) on changes_to (string) off = false (1ms)
+10271ms ║║Condition #28 evaluated false (10ms)
+10272ms ║║Condition group #25 evaluated false (state did not change) (11ms)
+10274ms ║╚Execution stage complete. (32ms)
+10275ms ╚Event processed successfully (10275ms)
1/14/2018, 11:58:21 AM +163ms
+2ms ╔Received event [Patio East Coach Light].switch = on with a delay of 874ms
+125ms ║RunTime Analysis CS > 19ms > PS > 39ms > PE > 67ms > CE
+127ms ║Runtime (40940 bytes) successfully initialized in 39ms (v0.2.101.20171227) (125ms)
+128ms ║╔Execution stage started
+139ms ║║Cancelling condition #9’s schedules…
+139ms ║║Condition #9 evaluated false (7ms)
+140ms ║║Cancelling condition #3’s schedules…
+141ms ║║Condition group #3 evaluated false (state changed) (9ms)
+148ms ║║Condition #21 evaluated false (5ms)
+149ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+158ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+160ms ║║Condition #28 evaluated false (8ms)
+160ms ║║Condition group #25 evaluated false (state did not change) (9ms)
+162ms ║╚Execution stage complete. (34ms)
+163ms ╚Event processed successfully (163ms)
1/14/2018, 11:58:20 AM +680ms
+1ms ╔Received event [Front Doorbell].motion = active with a delay of 69ms
+102ms ║RunTime Analysis CS > 12ms > PS > 26ms > PE > 65ms > CE
+104ms ║Runtime (40937 bytes) successfully initialized in 26ms (v0.2.101.20171227) (103ms)
+105ms ║╔Execution stage started
+112ms ║║Comparison (enum) active changes_to (string) active = true (0ms)
+113ms ║║Cancelling condition #9’s schedules…
+114ms ║║Condition #9 evaluated true (4ms)
+125ms ║║Comparison (enum) off is (string) off = true (2ms)
+126ms ║║Comparison (enum) off is (string) off = true (1ms)
+128ms ║║Condition #32 evaluated true (12ms)
+134ms ║║Comparison (time) 43100808 is_between (time) 0 … (time) 70200000 = true (4ms)
+135ms ║║Time restriction check passed
+137ms ║║Condition #33 evaluated true (8ms)
+137ms ║║Cancelling condition #3’s schedules…
+138ms ║║Condition group #3 evaluated true (state changed) (29ms)
+140ms ║║Cancelling statement #29’s schedules…
+145ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].setVariable (2ms)
+154ms ║║Executed physical command [Patio East Coach Light].on() (8ms)
+154ms ║║Executed [Patio East Coach Light].on (8ms)
+162ms ║║Executed physical command [Patio West Coach Light].on() (7ms)
+163ms ║║Executed [Patio West Coach Light].on (8ms)
+169ms ║║Executed physical command [Patio East Coach Light].setColorTemperature([2700]) (4ms)
+170ms ║║Executed [Patio East Coach Light].setColorTemperature (5ms)
+174ms ║║Executed physical command [Patio West Coach Light].setColorTemperature([2700]) (3ms)
+175ms ║║Executed [Patio West Coach Light].setColorTemperature (5ms)
+186ms ║║Executed physical command [Patio East Coach Light].setLevel([60]) (9ms)
+186ms ║║Executed [Patio East Coach Light].setLevel (9ms)
+195ms ║║Executed physical command [Patio West Coach Light].setLevel([60]) (8ms)
+196ms ║║Executed [Patio West Coach Light].setLevel (9ms)
+201ms ║║Comparison (enum) active changes_to (string) inactive = false (0ms)
+202ms ║║Condition #21 evaluated false (3ms)
+203ms ║║Condition group #15 evaluated false (state did not change) (4ms)
+214ms ║║Condition #28 evaluated false (9ms)
+215ms ║║Condition group #25 evaluated false (state did not change) (10ms)
+216ms ║╚Execution stage complete. (111ms)
+217ms ╚Event processed successfully (217ms)
)`

bangali · 2018-01-15 20:04:59 UTC

in the last couple of days ST upgrades have caused unpredictable issues with lights not turning on or off when they should.

also trace needs to be on for the logs to make sense. please turn trace on then share the snapshot and full logs.

in both snapshots line #47 seems to have been added … why add that line?

sechen43 · 2018-01-15 21:54:10 UTC

Thanks! These same coach lights come on automatically between sunset and midnight via another piston. Then between midnight and sunrise, these pistons run these lights as motion lights. If motion tripped at 2AM last night, then the lights went on at sunset this evening and I had motion at 7PM tonight, the lights would go off at 7:05 (assuming inactivity at the motion sensor and the five minutes lapsed). Unless I added line 47, nothing flipped the variable to false which allowed for this condition. It seemed to fix the issue, but please let me know if this is the wrong way to address this.

I enabled trace and took screenshots, full logs are also included. Screenshot doesn’t seem to show the black trace column, let me know if I’m doing it wrong (first time for some of this).

Thanks!

1/15/2018, 3:38:02 PM +331ms
+1ms ╔Received event [Patio West Coach Light].switch = off with a delay of 79ms
+10154ms ║RunTime Analysis CS > 20ms > PS > 10056ms > PE > 77ms > CE
+10154ms ║Piston waited at a semaphore for 10017ms
+10157ms ║Runtime (41409 bytes) successfully initialized in 10056ms (v0.2.101.20171227) (10155ms)
+10157ms ║╔Execution stage started
+10168ms ║║Condition #9 evaluated false (6ms)
+10169ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+10178ms ║║Condition #21 evaluated false (6ms)
+10180ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+10192ms ║║Comparison (enum) off changes_to (string) off = false (0ms)
+10193ms ║║Cancelling condition #28’s schedules…
+10194ms ║║Condition #28 evaluated false (11ms)
+10195ms ║║Cancelling condition #25’s schedules…
+10196ms ║║Condition group #25 evaluated false (state changed) (14ms)
+10198ms ║╚Execution stage complete. (40ms)
+10199ms ╚Event processed successfully (10198ms)
1/15/2018, 3:38:02 PM +192ms
+2ms ╔Received event [Patio East Coach Light].switch = off with a delay of 137ms
+144ms ║RunTime Analysis CS > 17ms > PS > 50ms > PE > 77ms > CE
+147ms ║Runtime (41331 bytes) successfully initialized in 50ms (v0.2.101.20171227) (144ms)
+148ms ║╔Execution stage started
+173ms ║║Condition #9 evaluated false (21ms)
+174ms ║║Condition group #3 evaluated false (state did not change) (22ms)
+181ms ║║Cancelling condition #21’s schedules…
+182ms ║║Condition #21 evaluated false (5ms)
+183ms ║║Cancelling condition #15’s schedules…
+184ms ║║Condition group #15 evaluated false (state changed) (7ms)
+195ms ║║Comparison (enum) off changes_to (string) off = true (1ms)
+196ms ║║Cancelling condition #28’s schedules…
+197ms ║║Condition #28 evaluated true (11ms)
+198ms ║║Cancelling condition #25’s schedules…
+199ms ║║Condition group #25 evaluated true (state changed) (13ms)
+201ms ║║Cancelling statement #26’s schedules…
+206ms ║║Executed virtual command setVariable (3ms)
+209ms ║╚Execution stage complete. (62ms)
+210ms ╚Event processed successfully (210ms)
1/15/2018, 3:38:00 PM +485ms
+1ms ╔Received event [Home].time = 1516052281255 with a delay of -770ms
+164ms ║RunTime Analysis CS > 20ms > PS > 58ms > PE > 85ms > CE
+166ms ║Runtime (41318 bytes) successfully initialized in 58ms (v0.2.101.20171227) (164ms)
+167ms ║╔Execution stage started
+1257ms ║║Executed physical command [Patio East Coach Light].off() (1061ms)
+1258ms ║║Executed [Patio East Coach Light].off (1063ms)
+1290ms ║║Executed physical command [Patio West Coach Light].off() (29ms)
+1291ms ║║Executed [Patio West Coach Light].off (30ms)
+1297ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].setVariable (3ms)
+1487ms ║║Condition #28 evaluated false (186ms)
+1487ms ║║Condition group #25 evaluated false (state did not change) (187ms)
+1489ms ║╚Execution stage complete. (1322ms)
+1490ms ╚Event processed successfully (1490ms)
1/15/2018, 3:33:01 PM +118ms
+1ms ╔Received event [Front Doorbell].motion = inactive with a delay of 78ms
+101ms ║RunTime Analysis CS > 11ms > PS > 26ms > PE > 64ms > CE
+104ms ║Runtime (41328 bytes) successfully initialized in 26ms (v0.2.101.20171227) (103ms)
+105ms ║╔Execution stage started
+112ms ║║Comparison (enum) inactive changes_to (string) active = false (1ms)
+113ms ║║Condition #9 evaluated false (4ms)
+114ms ║║Condition group #3 evaluated false (state did not change) (5ms)
+119ms ║║Comparison (enum) inactive changes_to (string) inactive = true (1ms)
+120ms ║║Cancelling condition #21’s schedules…
+121ms ║║Condition #21 evaluated true (5ms)
+124ms ║║Comparison (boolean) true is (boolean) true = true (1ms)
+126ms ║║Cancelling condition #24’s schedules…
+126ms ║║Condition #24 evaluated true (5ms)
+127ms ║║Cancelling condition #15’s schedules…
+128ms ║║Condition group #15 evaluated true (state changed) (12ms)
+130ms ║║Cancelling statement #18’s schedules…
+135ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].wait (1ms)
+136ms ║║Requesting a wake up for Mon, Jan 15 2018 @ 3:38:01 PM CST (in 300.0s)
+140ms ║╚Execution stage complete. (36ms)
+141ms ║Setting up scheduled job for Mon, Jan 15 2018 @ 3:38:01 PM CST (in 299.996s)
+149ms ╚Event processed successfully (149ms)
1/15/2018, 3:32:31 PM +899ms
+1ms ╔Received event [Patio East Coach Light].switch = on with a delay of 96ms
+10179ms ║RunTime Analysis CS > 17ms > PS > 10065ms > PE > 98ms > CE
+10180ms ║Piston waited at a semaphore for 10020ms
+10182ms ║Runtime (41410 bytes) successfully initialized in 10065ms (v0.2.101.20171227) (10180ms)
+10183ms ║╔Execution stage started
+10195ms ║║Condition #9 evaluated false (6ms)
+10195ms ║║Condition group #3 evaluated false (state did not change) (8ms)
+10202ms ║║Condition #21 evaluated false (4ms)
+10203ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+10213ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+10215ms ║║Condition #28 evaluated false (10ms)
+10216ms ║║Condition group #25 evaluated false (state did not change) (11ms)
+10217ms ║╚Execution stage complete. (35ms)
+10218ms ╚Event processed successfully (10218ms)
1/15/2018, 3:32:31 PM +883ms
+2ms ╔Received event [Patio West Coach Light].switch = on with a delay of 147ms
+142ms ║RunTime Analysis CS > 21ms > PS > 50ms > PE > 70ms > CE
+144ms ║Runtime (41332 bytes) successfully initialized in 50ms (v0.2.101.20171227) (142ms)
+145ms ║╔Execution stage started
+158ms ║║Cancelling condition #9’s schedules…
+159ms ║║Condition #9 evaluated false (9ms)
+160ms ║║Cancelling condition #3’s schedules…
+160ms ║║Condition group #3 evaluated false (state changed) (11ms)
+170ms ║║Condition #21 evaluated false (6ms)
+170ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+182ms ║║Comparison (enum) on changes_to (string) off = false (1ms)
+183ms ║║Condition #28 evaluated false (10ms)
+184ms ║║Condition group #25 evaluated false (state did not change) (11ms)
+186ms ║╚Execution stage complete. (41ms)
+187ms ╚Event processed successfully (187ms)
1/15/2018, 3:32:31 PM +223ms
+1ms ╔Received event [Front Doorbell].motion = active with a delay of 74ms
+126ms ║RunTime Analysis CS > 14ms > PS > 41ms > PE > 71ms > CE
+128ms ║Runtime (41332 bytes) successfully initialized in 41ms (v0.2.101.20171227) (126ms)
+129ms ║╔Execution stage started
+136ms ║║Comparison (enum) active changes_to (string) active = true (0ms)
+138ms ║║Cancelling condition #9’s schedules…
+138ms ║║Condition #9 evaluated true (5ms)
+150ms ║║Comparison (enum) off is (string) off = true (1ms)
+152ms ║║Comparison (enum) off is (string) off = true (1ms)
+153ms ║║Condition #32 evaluated true (14ms)
+160ms ║║Comparison (time) 55951377 is_between (time) 0 … (time) 70200000 = true (5ms)
+161ms ║║Time restriction check passed
+163ms ║║Condition #33 evaluated true (9ms)
+164ms ║║Cancelling condition #3’s schedules…
+164ms ║║Condition group #3 evaluated true (state changed) (31ms)
+166ms ║║Cancelling statement #29’s schedules…
+173ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].setVariable (3ms)
+206ms ║║Executed physical command [Patio East Coach Light].on() (32ms)
+207ms ║║Executed [Patio East Coach Light].on (33ms)
+228ms ║║Executed physical command [Patio West Coach Light].on() (20ms)
+228ms ║║Executed [Patio West Coach Light].on (20ms)
+254ms ║║Executed physical command [Patio East Coach Light].setColorTemperature([2700]) (23ms)
+255ms ║║Executed [Patio East Coach Light].setColorTemperature (24ms)
+279ms ║║Executed physical command [Patio West Coach Light].setColorTemperature([2700]) (22ms)
+279ms ║║Executed [Patio West Coach Light].setColorTemperature (23ms)
+296ms ║║Executed physical command [Patio East Coach Light].setLevel([60]) (14ms)
+297ms ║║Executed [Patio East Coach Light].setLevel (15ms)
+311ms ║║Executed physical command [Patio West Coach Light].setLevel([60]) (13ms)
+311ms ║║Executed [Patio West Coach Light].setLevel (13ms)
+317ms ║║Comparison (enum) active changes_to (string) inactive = false (1ms)
+318ms ║║Condition #21 evaluated false (4ms)
+319ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+330ms ║║Condition #28 evaluated false (9ms)
+331ms ║║Condition group #25 evaluated false (state did not change) (10ms)
+333ms ║╚Execution stage complete. (203ms)
+334ms ╚Event processed successfully (334ms)

sechen43 · 2018-01-15 21:58:22 UTC

Sorry, here is both sets of logs. Guess I didn’t get it all pasted above. Thanks.

Street:

1/15/2018, 3:38:03 PM +918ms
+1ms ╔Received event [South Garage Doors West Coach Light].switch = off with a delay of 106ms
+10080ms ║RunTime Analysis CS > 21ms > PS > 9914ms > PE > 145ms > CE
+10081ms ║Piston waited at a semaphore for 9873ms
+10083ms ║Runtime (43272 bytes) successfully initialized in 9914ms (v0.2.101.20171227) (10081ms)
+10084ms ║╔Execution stage started
+10095ms ║║Condition #9 evaluated false (7ms)
+10096ms ║║Condition group #3 evaluated false (state did not change) (8ms)
+10105ms ║║Condition #21 evaluated false (7ms)
+10106ms ║║Condition group #15 evaluated false (state did not change) (8ms)
+10145ms ║║Comparison (enum) off changes_to (string) off = false (0ms)
+10147ms ║║Condition #28 evaluated false (38ms)
+10148ms ║║Condition group #25 evaluated false (state did not change) (40ms)
+10150ms ║╚Execution stage complete. (67ms)
+10152ms ╚Event processed successfully (10151ms)
1/15/2018, 3:38:02 PM +673ms
+1ms ╔Received event [Main Garage Door North Coach Light].switch = off with a delay of 142ms
+10187ms ║RunTime Analysis CS > 18ms > PS > 10068ms > PE > 101ms > CE
+10187ms ║Piston waited at a semaphore for 10019ms
+10190ms ║Runtime (43273 bytes) successfully initialized in 10068ms (v0.2.101.20171227) (10188ms)
+10191ms ║╔Execution stage started
+10203ms ║║Condition #9 evaluated false (8ms)
+10204ms ║║Condition group #3 evaluated false (state did not change) (9ms)
+10212ms ║║Condition #21 evaluated false (4ms)
+10212ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+10251ms ║║Comparison (enum) off changes_to (string) off = false (0ms)
+10254ms ║║Cancelling condition #28’s schedules…
+10254ms ║║Condition #28 evaluated false (39ms)
+10255ms ║║Cancelling condition #25’s schedules…
+10256ms ║║Condition group #25 evaluated false (state changed) (42ms)
+10258ms ║╚Execution stage complete. (68ms)
+10259ms ╚Event processed successfully (10259ms)
1/15/2018, 3:38:02 PM +657ms
+0ms ╔Received event [Main Garage Door South Coach Light].switch = off with a delay of 93ms
+10160ms ║RunTime Analysis CS > 19ms > PS > 10043ms > PE > 98ms > CE
+10160ms ║Piston waited at a semaphore for 10017ms
+10163ms ║Runtime (43271 bytes) successfully initialized in 10043ms (v0.2.101.20171227) (10162ms)
+10164ms ║╔Execution stage started
+10174ms ║║Condition #9 evaluated false (6ms)
+10175ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+10182ms ║║Condition #21 evaluated false (4ms)
+10183ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+10216ms ║║Comparison (enum) off changes_to (string) off = false (0ms)
+10219ms ║║Cancelling condition #28’s schedules…
+10219ms ║║Condition #28 evaluated false (33ms)
+10220ms ║║Cancelling condition #25’s schedules…
+10221ms ║║Condition group #25 evaluated false (state changed) (36ms)
+10223ms ║╚Execution stage complete. (60ms)
+10224ms ╚Event processed successfully (10224ms)
1/15/2018, 3:38:08 PM +370ms
+1ms ╔Received event [South Garage Doors East Coach Light].switch = off with a delay of 76ms
+130ms ║RunTime Analysis CS > 11ms > PS > 28ms > PE > 90ms > CE
+132ms ║Runtime (43191 bytes) successfully initialized in 28ms (v0.2.101.20171227) (131ms)
+133ms ║╔Execution stage started
+144ms ║║Condition #9 evaluated false (6ms)
+145ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+152ms ║║Condition #21 evaluated false (5ms)
+153ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+185ms ║║Comparison (enum) off changes_to (string) off = true (0ms)
+188ms ║║Condition #28 evaluated true (32ms)
+188ms ║║Condition group #25 evaluated true (state did not change) (33ms)
+190ms ║║Cancelling statement #26’s schedules…
+194ms ║║Executed virtual command setVariable (1ms)
+197ms ║╚Execution stage complete. (63ms)
+198ms ╚Event processed successfully (197ms)
1/15/2018, 3:38:03 PM +687ms
+1ms ╔Received event [South Garage Doors Center Coach Light].switch = off with a delay of 130ms
+156ms ║RunTime Analysis CS > 15ms > PS > 43ms > PE > 99ms > CE
+159ms ║Runtime (43192 bytes) successfully initialized in 43ms (v0.2.101.20171227) (157ms)
+160ms ║╔Execution stage started
+170ms ║║Condition #9 evaluated false (6ms)
+171ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+180ms ║║Condition #21 evaluated false (5ms)
+181ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+216ms ║║Comparison (enum) off changes_to (string) off = true (1ms)
+218ms ║║Condition #28 evaluated true (35ms)
+219ms ║║Condition group #25 evaluated true (state did not change) (36ms)
+221ms ║║Cancelling statement #26’s schedules…
+226ms ║║Executed virtual command setVariable (3ms)
+228ms ║╚Execution stage complete. (69ms)
+229ms ╚Event processed successfully (229ms)
1/15/2018, 3:38:02 PM +930ms
+1ms ╔Received event [Garage Man Door Coach Light].switch = off with a delay of 118ms
+164ms ║RunTime Analysis CS > 15ms > PS > 27ms > PE > 121ms > CE
+166ms ║Runtime (43181 bytes) successfully initialized in 27ms (v0.2.101.20171227) (164ms)
+167ms ║╔Execution stage started
+178ms ║║Condition #9 evaluated false (6ms)
+178ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+186ms ║║Condition #21 evaluated false (5ms)
+187ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+227ms ║║Comparison (enum) off changes_to (string) off = true (0ms)
+229ms ║║Condition #28 evaluated true (40ms)
+230ms ║║Condition group #25 evaluated true (state did not change) (41ms)
+232ms ║║Cancelling statement #26’s schedules…
+238ms ║║Executed virtual command setVariable (2ms)
+240ms ║╚Execution stage complete. (73ms)
+241ms ╚Event processed successfully (241ms)
1/15/2018, 3:38:02 PM +632ms
+0ms ╔Received event [Front Door South Coach Light].switch = off with a delay of 242ms
+136ms ║RunTime Analysis CS > 16ms > PS > 29ms > PE > 92ms > CE
+138ms ║Runtime (43184 bytes) successfully initialized in 29ms (v0.2.101.20171227) (137ms)
+139ms ║╔Execution stage started
+150ms ║║Condition #9 evaluated false (6ms)
+151ms ║║Condition group #3 evaluated false (state did not change) (8ms)
+158ms ║║Condition #21 evaluated false (5ms)
+159ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+191ms ║║Comparison (enum) off changes_to (string) off = true (0ms)
+193ms ║║Cancelling condition #28’s schedules…
+193ms ║║Condition #28 evaluated true (32ms)
+194ms ║║Cancelling condition #25’s schedules…
+195ms ║║Condition group #25 evaluated true (state changed) (34ms)
+197ms ║║Cancelling statement #26’s schedules…
+202ms ║║Executed virtual command setVariable (2ms)
+204ms ║╚Execution stage complete. (65ms)
+205ms ╚Event processed successfully (205ms)
1/15/2018, 3:38:02 PM +53ms
+1ms ╔Received event [Front Door North Coach Light].switch = off with a delay of 142ms
+206ms ║RunTime Analysis CS > 42ms > PS > 46ms > PE > 119ms > CE
+209ms ║Runtime (43183 bytes) successfully initialized in 46ms (v0.2.101.20171227) (207ms)
+210ms ║╔Execution stage started
+226ms ║║Condition #9 evaluated false (12ms)
+227ms ║║Condition group #3 evaluated false (state did not change) (13ms)
+234ms ║║Cancelling condition #21’s schedules…
+235ms ║║Condition #21 evaluated false (6ms)
+236ms ║║Cancelling condition #15’s schedules…
+236ms ║║Condition group #15 evaluated false (state changed) (7ms)
+273ms ║║Comparison (enum) off changes_to (string) off = false (0ms)
+275ms ║║Condition #28 evaluated false (36ms)
+276ms ║║Condition group #25 evaluated false (state did not change) (38ms)
+278ms ║╚Execution stage complete. (68ms)
+279ms ╚Event processed successfully (280ms)
1/15/2018, 3:38:00 PM +495ms
+0ms ╔Received event [Home].time = 1516052281325 with a delay of -831ms
+176ms ║RunTime Analysis CS > 18ms > PS > 59ms > PE > 100ms > CE
+179ms ║Runtime (43163 bytes) successfully initialized in 59ms (v0.2.101.20171227) (178ms)
+181ms ║╔Execution stage started
+1247ms ║║Executed physical command [Front Door North Coach Light].off() (1037ms)
+1248ms ║║Executed [Front Door North Coach Light].off (1039ms)
+1281ms ║║Executed physical command [Front Door South Coach Light].off() (30ms)
+1282ms ║║Executed [Front Door South Coach Light].off (31ms)
+1314ms ║║Executed physical command [Garage Man Door Coach Light].off() (29ms)
+1315ms ║║Executed [Garage Man Door Coach Light].off (31ms)
+1347ms ║║Executed physical command [Main Garage Door North Coach Light].off() (29ms)
+1348ms ║║Executed [Main Garage Door North Coach Light].off (31ms)
+1380ms ║║Executed physical command [Main Garage Door South Coach Light].off() (30ms)
+1381ms ║║Executed [Main Garage Door South Coach Light].off (30ms)
+1414ms ║║Executed physical command [South Garage Doors Center Coach Light].off() (31ms)
+1414ms ║║Executed [South Garage Doors Center Coach Light].off (31ms)
+1445ms ║║Executed physical command [South Garage Doors East Coach Light].off() (28ms)
+1446ms ║║Executed [South Garage Doors East Coach Light].off (29ms)
+1476ms ║║Executed physical command [South Garage Doors West Coach Light].off() (27ms)
+1477ms ║║Executed [South Garage Doors West Coach Light].off (29ms)
+1483ms ║║Executed virtual command [Front Door North Coach Light, Front Door South Coach Light, Garage Man Door Coach Light, Main Garage Door North Coach Light, Main Garage Door South Coach Light, South Garage Doors Center Coach Light, South Garage Doors East Coach Light, South Garage Doors West Coach Light].setVariable (4ms)
+1516ms ║║Condition #28 evaluated false (30ms)
+1517ms ║║Condition group #25 evaluated false (state did not change) (31ms)
+1519ms ║╚Execution stage complete. (1339ms)
+1520ms ╚Event processed successfully (1521ms)
1/15/2018, 3:33:01 PM +123ms
+1ms ╔Received event [Front Doorbell].motion = inactive with a delay of 83ms
+164ms ║RunTime Analysis CS > 23ms > PS > 50ms > PE > 90ms > CE
+166ms ║Runtime (43172 bytes) successfully initialized in 50ms (v0.2.101.20171227) (164ms)
+167ms ║╔Execution stage started
+174ms ║║Comparison (enum) inactive changes_to (string) active = false (0ms)
+176ms ║║Condition #9 evaluated false (4ms)
+177ms ║║Condition group #3 evaluated false (state did not change) (5ms)
+182ms ║║Comparison (enum) inactive changes_to (string) inactive = true (1ms)
+183ms ║║Cancelling condition #21’s schedules…
+184ms ║║Condition #21 evaluated true (5ms)
+188ms ║║Comparison (boolean) true is (boolean) true = true (1ms)
+189ms ║║Cancelling condition #24’s schedules…
+190ms ║║Condition #24 evaluated true (5ms)
+191ms ║║Cancelling condition #15’s schedules…
+191ms ║║Condition group #15 evaluated true (state changed) (12ms)
+193ms ║║Cancelling statement #18’s schedules…
+201ms ║║Executed virtual command [Front Door North Coach Light, Front Door South Coach Light, Garage Man Door Coach Light, Main Garage Door North Coach Light, Main Garage Door South Coach Light, South Garage Doors Center Coach Light, South Garage Doors East Coach Light, South Garage Doors West Coach Light].wait (1ms)
+202ms ║║Requesting a wake up for Mon, Jan 15 2018 @ 3:38:01 PM CST (in 300.0s)
+206ms ║╚Execution stage complete. (39ms)
+208ms ║Setting up scheduled job for Mon, Jan 15 2018 @ 3:38:01 PM CST (in 299.995s)
+214ms ╚Event processed successfully (214ms)
1/15/2018, 3:32:33 PM +178ms
+2ms ╔Received event [South Garage Doors Center Coach Light].switch = on with a delay of 108ms
+10274ms ║RunTime Analysis CS > 16ms > PS > 10137ms > PE > 121ms > CE
+10275ms ║Piston waited at a semaphore for 10089ms
+10277ms ║Runtime (43271 bytes) successfully initialized in 10137ms (v0.2.101.20171227) (10275ms)
+10278ms ║╔Execution stage started
+10290ms ║║Condition #9 evaluated false (8ms)
+10291ms ║║Condition group #3 evaluated false (state did not change) (9ms)
+10300ms ║║Condition #21 evaluated false (6ms)
+10301ms ║║Condition group #15 evaluated false (state did not change) (8ms)
+10341ms ║║Comparison (enum) on changes_to (string) off = false (1ms)
+10343ms ║║Condition #28 evaluated false (40ms)
+10344ms ║║Condition group #25 evaluated false (state did not change) (41ms)
+10346ms ║╚Execution stage complete. (68ms)
+10347ms ╚Event processed successfully (10347ms)
1/15/2018, 3:32:33 PM +41ms
+2ms ╔Received event [Garage Man Door Coach Light].switch = on with a delay of 118ms
+9942ms ║RunTime Analysis CS > 22ms > PS > 9813ms > PE > 107ms > CE
+9943ms ║Piston waited at a semaphore for 9769ms
+9945ms ║Runtime (43255 bytes) successfully initialized in 9813ms (v0.2.101.20171227) (9943ms)
+9946ms ║╔Execution stage started
+9956ms ║║Condition #9 evaluated false (6ms)
+9957ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+9965ms ║║Condition #21 evaluated false (6ms)
+9966ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+10003ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+10005ms ║║Condition #28 evaluated false (36ms)
+10006ms ║║Condition group #25 evaluated false (state did not change) (38ms)
+10008ms ║╚Execution stage complete. (62ms)
+10009ms ╚Event processed successfully (10009ms)
1/15/2018, 3:32:32 PM +71ms
+1ms ╔Received event [Front Door North Coach Light].switch = on with a delay of 131ms
+9431ms ║RunTime Analysis CS > 16ms > PS > 9314ms > PE > 100ms > CE
+9432ms ║Piston waited at a semaphore for 9267ms
+9434ms ║Runtime (43256 bytes) successfully initialized in 9314ms (v0.2.101.20171227) (9432ms)
+9435ms ║╔Execution stage started
+9447ms ║║Condition #9 evaluated false (8ms)
+9448ms ║║Condition group #3 evaluated false (state did not change) (9ms)
+9457ms ║║Condition #21 evaluated false (6ms)
+9458ms ║║Condition group #15 evaluated false (state did not change) (8ms)
+9498ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+9501ms ║║Condition #28 evaluated false (41ms)
+9502ms ║║Condition group #25 evaluated false (state did not change) (42ms)
+9503ms ║╚Execution stage complete. (69ms)
+9505ms ╚Event processed successfully (9504ms)
1/15/2018, 3:32:35 PM +806ms
+2ms ╔Received event [South Garage Doors East Coach Light].switch = on with a delay of 110ms
+156ms ║RunTime Analysis CS > 22ms > PS > 50ms > PE > 84ms > CE
+159ms ║Runtime (43189 bytes) successfully initialized in 50ms (v0.2.101.20171227) (156ms)
+160ms ║╔Execution stage started
+173ms ║║Condition #9 evaluated false (8ms)
+173ms ║║Condition group #3 evaluated false (state did not change) (9ms)
+180ms ║║Condition #21 evaluated false (4ms)
+181ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+219ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+222ms ║║Condition #28 evaluated false (37ms)
+222ms ║║Condition group #25 evaluated false (state did not change) (39ms)
+224ms ║╚Execution stage complete. (65ms)
+225ms ╚Event processed successfully (225ms)
1/15/2018, 3:32:33 PM +570ms
+1ms ╔Received event [South Garage Doors West Coach Light].switch = on with a delay of 121ms
+117ms ║RunTime Analysis CS > 11ms > PS > 28ms > PE > 77ms > CE
+119ms ║Runtime (43190 bytes) successfully initialized in 28ms (v0.2.101.20171227) (118ms)
+120ms ║╔Execution stage started
+131ms ║║Condition #9 evaluated false (6ms)
+132ms ║║Condition group #3 evaluated false (state did not change) (8ms)
+140ms ║║Condition #21 evaluated false (5ms)
+141ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+175ms ║║Comparison (enum) on changes_to (string) off = false (1ms)
+177ms ║║Condition #28 evaluated false (34ms)
+178ms ║║Condition group #25 evaluated false (state did not change) (35ms)
+180ms ║╚Execution stage complete. (59ms)
+181ms ╚Event processed successfully (181ms)
1/15/2018, 3:32:33 PM +116ms
+0ms ╔Received event [Main Garage Door South Coach Light].switch = on with a delay of 119ms
+110ms ║RunTime Analysis CS > 11ms > PS > 28ms > PE > 72ms > CE
+113ms ║Runtime (43194 bytes) successfully initialized in 28ms (v0.2.101.20171227) (112ms)
+114ms ║╔Execution stage started
+124ms ║║Condition #9 evaluated false (6ms)
+125ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+133ms ║║Condition #21 evaluated false (6ms)
+134ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+166ms ║║Comparison (enum) on changes_to (string) off = false (1ms)
+169ms ║║Condition #28 evaluated false (32ms)
+170ms ║║Condition group #25 evaluated false (state did not change) (33ms)
+171ms ║╚Execution stage complete. (57ms)
+172ms ╚Event processed successfully (172ms)
1/15/2018, 3:32:32 PM +771ms
+2ms ╔Received event [Main Garage Door North Coach Light].switch = on with a delay of 142ms
+141ms ║RunTime Analysis CS > 15ms > PS > 45ms > PE > 81ms > CE
+144ms ║Runtime (43194 bytes) successfully initialized in 45ms (v0.2.101.20171227) (142ms)
+145ms ║╔Execution stage started
+156ms ║║Condition #9 evaluated false (6ms)
+156ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+164ms ║║Condition #21 evaluated false (4ms)
+164ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+261ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+263ms ║║Condition #28 evaluated false (96ms)
+264ms ║║Condition group #25 evaluated false (state did not change) (98ms)
+266ms ║╚Execution stage complete. (122ms)
+267ms ╚Event processed successfully (267ms)
1/15/2018, 3:32:32 PM +306ms
+0ms ╔Received event [Front Door South Coach Light].switch = on with a delay of 107ms
+185ms ║RunTime Analysis CS > 17ms > PS > 27ms > PE > 142ms > CE
+188ms ║Runtime (43187 bytes) successfully initialized in 27ms (v0.2.101.20171227) (187ms)
+189ms ║╔Execution stage started
+200ms ║║Cancelling condition #9’s schedules…
+201ms ║║Condition #9 evaluated false (7ms)
+202ms ║║Cancelling condition #3’s schedules…
+203ms ║║Condition group #3 evaluated false (state changed) (9ms)
+210ms ║║Condition #21 evaluated false (5ms)
+211ms ║║Condition group #15 evaluated false (state did not change) (6ms)
+244ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+247ms ║║Condition #28 evaluated false (34ms)
+248ms ║║Condition group #25 evaluated false (state did not change) (34ms)
+249ms ║╚Execution stage complete. (61ms)
+250ms ╚Event processed successfully (250ms)
1/15/2018, 3:32:31 PM +235ms
+1ms ╔Received event [Front Doorbell].motion = active with a delay of 86ms
+166ms ║RunTime Analysis CS > 22ms > PS > 54ms > PE > 91ms > CE
+169ms ║Runtime (43181 bytes) successfully initialized in 54ms (v0.2.101.20171227) (166ms)
+170ms ║╔Execution stage started
+177ms ║║Comparison (enum) active changes_to (string) active = true (1ms)
+178ms ║║Cancelling condition #9’s schedules…
+179ms ║║Condition #9 evaluated true (5ms)
+220ms ║║Comparison (enum) off is (string) off = true (1ms)
+221ms ║║Comparison (enum) off is (string) off = true (1ms)
+223ms ║║Comparison (enum) off is (string) off = true (1ms)
+225ms ║║Comparison (enum) off is (string) off = true (1ms)
+227ms ║║Comparison (enum) off is (string) off = true (1ms)
+229ms ║║Comparison (enum) off is (string) off = true (1ms)
+230ms ║║Comparison (enum) off is (string) off = true (1ms)
+232ms ║║Comparison (enum) off is (string) off = true (1ms)
+234ms ║║Condition #32 evaluated true (54ms)
+242ms ║║Comparison (time) 55951470 is_between (time) 0 … (time) 70200000 = true (5ms)
+243ms ║║Time restriction check passed
+244ms ║║Condition #33 evaluated true (9ms)
+245ms ║║Cancelling condition #3’s schedules…
+246ms ║║Condition group #3 evaluated true (state changed) (72ms)
+248ms ║║Cancelling statement #29’s schedules…
+255ms ║║Executed virtual command [Front Door North Coach Light, Front Door South Coach Light, Garage Man Door Coach Light, Main Garage Door North Coach Light, Main Garage Door South Coach Light, South Garage Doors Center Coach Light, South Garage Doors East Coach Light, South Garage Doors West Coach Light].setVariable (2ms)
+295ms ║║Executed physical command [Front Door North Coach Light].on() (38ms)
+296ms ║║Executed [Front Door North Coach Light].on (40ms)
+318ms ║║Executed physical command [Front Door South Coach Light].on() (21ms)
+319ms ║║Executed [Front Door South Coach Light].on (23ms)
+340ms ║║Executed physical command [Garage Man Door Coach Light].on() (20ms)
+341ms ║║Executed [Garage Man Door Coach Light].on (22ms)
+363ms ║║Executed physical command [Main Garage Door North Coach Light].on() (21ms)
+364ms ║║Executed [Main Garage Door North Coach Light].on (22ms)
+386ms ║║Executed physical command [Main Garage Door South Coach Light].on() (21ms)
+387ms ║║Executed [Main Garage Door South Coach Light].on (22ms)
+408ms ║║Executed physical command [South Garage Doors Center Coach Light].on() (20ms)
+409ms ║║Executed [South Garage Doors Center Coach Light].on (22ms)
+430ms ║║Executed physical command [South Garage Doors East Coach Light].on() (19ms)
+430ms ║║Executed [South Garage Doors East Coach Light].on (21ms)
+451ms ║║Executed physical command [South Garage Doors West Coach Light].on() (20ms)
+452ms ║║Executed [South Garage Doors West Coach Light].on (21ms)
+483ms ║║Executed physical command [Front Door North Coach Light].setColorTemperature([2700]) (28ms)
+484ms ║║Executed [Front Door North Coach Light].setColorTemperature (30ms)
+509ms ║║Executed physical command [Front Door South Coach Light].setColorTemperature([2700]) (24ms)
+509ms ║║Executed [Front Door South Coach Light].setColorTemperature (24ms)
+534ms ║║Executed physical command [Garage Man Door Coach Light].setColorTemperature([2700]) (24ms)
+535ms ║║Executed [Garage Man Door Coach Light].setColorTemperature (24ms)
+561ms ║║Executed physical command [Main Garage Door North Coach Light].setColorTemperature([2700]) (25ms)
+562ms ║║Executed [Main Garage Door North Coach Light].setColorTemperature (27ms)
+587ms ║║Executed physical command [Main Garage Door South Coach Light].setColorTemperature([2700]) (24ms)
+588ms ║║Executed [Main Garage Door South Coach Light].setColorTemperature (26ms)
+613ms ║║Executed physical command [South Garage Doors Center Coach Light].setColorTemperature([2700]) (23ms)
+613ms ║║Executed [South Garage Doors Center Coach Light].setColorTemperature (24ms)
+639ms ║║Executed physical command [South Garage Doors East Coach Light].setColorTemperature([2700]) (25ms)
+639ms ║║Executed [South Garage Doors East Coach Light].setColorTemperature (25ms)
+664ms ║║Executed physical command [South Garage Doors West Coach Light].setColorTemperature([2700]) (24ms)
+665ms ║║Executed [South Garage Doors West Coach Light].setColorTemperature (25ms)
+682ms ║║Executed physical command [Front Door North Coach Light].setLevel([60]) (13ms)
+682ms ║║Executed [Front Door North Coach Light].setLevel (15ms)
+698ms ║║Executed physical command [Front Door South Coach Light].setLevel([60]) (15ms)
+699ms ║║Executed [Front Door South Coach Light].setLevel (15ms)
+835ms ║║Executed physical command [Garage Man Door Coach Light].setLevel([60]) (134ms)
+835ms ║║Executed [Garage Man Door Coach Light].setLevel (136ms)
+852ms ║║Executed physical command [Main Garage Door North Coach Light].setLevel([60]) (15ms)
+852ms ║║Executed [Main Garage Door North Coach Light].setLevel (16ms)
+867ms ║║Executed physical command [Main Garage Door South Coach Light].setLevel([60]) (14ms)
+867ms ║║Executed [Main Garage Door South Coach Light].setLevel (14ms)
+884ms ║║Executed physical command [South Garage Doors Center Coach Light].setLevel([60]) (16ms)
+885ms ║║Executed [South Garage Doors Center Coach Light].setLevel (17ms)
+900ms ║║Executed physical command [South Garage Doors East Coach Light].setLevel([60]) (14ms)
+901ms ║║Executed [South Garage Doors East Coach Light].setLevel (14ms)
+916ms ║║Executed physical command [South Garage Doors West Coach Light].setLevel([60]) (14ms)
+917ms ║║Executed [South Garage Doors West Coach Light].setLevel (16ms)
+923ms ║║Comparison (enum) active changes_to (string) inactive = false (0ms)
+924ms ║║Condition #21 evaluated false (4ms)
+925ms ║║Condition group #15 evaluated false (state did not change) (4ms)
+956ms ║║Condition #28 evaluated false (29ms)
+957ms ║║Condition group #25 evaluated false (state did not change) (30ms)
+959ms ║╚Execution stage complete. (790ms)
+960ms ╚Event processed successfully (961ms)

Patio:

1/15/2018, 3:38:02 PM +331ms
+1ms ╔Received event [Patio West Coach Light].switch = off with a delay of 79ms
+10154ms ║RunTime Analysis CS > 20ms > PS > 10056ms > PE > 77ms > CE
+10154ms ║Piston waited at a semaphore for 10017ms
+10157ms ║Runtime (41409 bytes) successfully initialized in 10056ms (v0.2.101.20171227) (10155ms)
+10157ms ║╔Execution stage started
+10168ms ║║Condition #9 evaluated false (6ms)
+10169ms ║║Condition group #3 evaluated false (state did not change) (7ms)
+10178ms ║║Condition #21 evaluated false (6ms)
+10180ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+10192ms ║║Comparison (enum) off changes_to (string) off = false (0ms)
+10193ms ║║Cancelling condition #28’s schedules…
+10194ms ║║Condition #28 evaluated false (11ms)
+10195ms ║║Cancelling condition #25’s schedules…
+10196ms ║║Condition group #25 evaluated false (state changed) (14ms)
+10198ms ║╚Execution stage complete. (40ms)
+10199ms ╚Event processed successfully (10198ms)
1/15/2018, 3:38:02 PM +192ms
+2ms ╔Received event [Patio East Coach Light].switch = off with a delay of 137ms
+144ms ║RunTime Analysis CS > 17ms > PS > 50ms > PE > 77ms > CE
+147ms ║Runtime (41331 bytes) successfully initialized in 50ms (v0.2.101.20171227) (144ms)
+148ms ║╔Execution stage started
+173ms ║║Condition #9 evaluated false (21ms)
+174ms ║║Condition group #3 evaluated false (state did not change) (22ms)
+181ms ║║Cancelling condition #21’s schedules…
+182ms ║║Condition #21 evaluated false (5ms)
+183ms ║║Cancelling condition #15’s schedules…
+184ms ║║Condition group #15 evaluated false (state changed) (7ms)
+195ms ║║Comparison (enum) off changes_to (string) off = true (1ms)
+196ms ║║Cancelling condition #28’s schedules…
+197ms ║║Condition #28 evaluated true (11ms)
+198ms ║║Cancelling condition #25’s schedules…
+199ms ║║Condition group #25 evaluated true (state changed) (13ms)
+201ms ║║Cancelling statement #26’s schedules…
+206ms ║║Executed virtual command setVariable (3ms)
+209ms ║╚Execution stage complete. (62ms)
+210ms ╚Event processed successfully (210ms)
1/15/2018, 3:38:00 PM +485ms
+1ms ╔Received event [Home].time = 1516052281255 with a delay of -770ms
+164ms ║RunTime Analysis CS > 20ms > PS > 58ms > PE > 85ms > CE
+166ms ║Runtime (41318 bytes) successfully initialized in 58ms (v0.2.101.20171227) (164ms)
+167ms ║╔Execution stage started
+1257ms ║║Executed physical command [Patio East Coach Light].off() (1061ms)
+1258ms ║║Executed [Patio East Coach Light].off (1063ms)
+1290ms ║║Executed physical command [Patio West Coach Light].off() (29ms)
+1291ms ║║Executed [Patio West Coach Light].off (30ms)
+1297ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].setVariable (3ms)
+1487ms ║║Condition #28 evaluated false (186ms)
+1487ms ║║Condition group #25 evaluated false (state did not change) (187ms)
+1489ms ║╚Execution stage complete. (1322ms)
+1490ms ╚Event processed successfully (1490ms)
1/15/2018, 3:33:01 PM +118ms
+1ms ╔Received event [Front Doorbell].motion = inactive with a delay of 78ms
+101ms ║RunTime Analysis CS > 11ms > PS > 26ms > PE > 64ms > CE
+104ms ║Runtime (41328 bytes) successfully initialized in 26ms (v0.2.101.20171227) (103ms)
+105ms ║╔Execution stage started
+112ms ║║Comparison (enum) inactive changes_to (string) active = false (1ms)
+113ms ║║Condition #9 evaluated false (4ms)
+114ms ║║Condition group #3 evaluated false (state did not change) (5ms)
+119ms ║║Comparison (enum) inactive changes_to (string) inactive = true (1ms)
+120ms ║║Cancelling condition #21’s schedules…
+121ms ║║Condition #21 evaluated true (5ms)
+124ms ║║Comparison (boolean) true is (boolean) true = true (1ms)
+126ms ║║Cancelling condition #24’s schedules…
+126ms ║║Condition #24 evaluated true (5ms)
+127ms ║║Cancelling condition #15’s schedules…
+128ms ║║Condition group #15 evaluated true (state changed) (12ms)
+130ms ║║Cancelling statement #18’s schedules…
+135ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].wait (1ms)
+136ms ║║Requesting a wake up for Mon, Jan 15 2018 @ 3:38:01 PM CST (in 300.0s)
+140ms ║╚Execution stage complete. (36ms)
+141ms ║Setting up scheduled job for Mon, Jan 15 2018 @ 3:38:01 PM CST (in 299.996s)
+149ms ╚Event processed successfully (149ms)
1/15/2018, 3:32:31 PM +899ms
+1ms ╔Received event [Patio East Coach Light].switch = on with a delay of 96ms
+10179ms ║RunTime Analysis CS > 17ms > PS > 10065ms > PE > 98ms > CE
+10180ms ║Piston waited at a semaphore for 10020ms
+10182ms ║Runtime (41410 bytes) successfully initialized in 10065ms (v0.2.101.20171227) (10180ms)
+10183ms ║╔Execution stage started
+10195ms ║║Condition #9 evaluated false (6ms)
+10195ms ║║Condition group #3 evaluated false (state did not change) (8ms)
+10202ms ║║Condition #21 evaluated false (4ms)
+10203ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+10213ms ║║Comparison (enum) on changes_to (string) off = false (0ms)
+10215ms ║║Condition #28 evaluated false (10ms)
+10216ms ║║Condition group #25 evaluated false (state did not change) (11ms)
+10217ms ║╚Execution stage complete. (35ms)
+10218ms ╚Event processed successfully (10218ms)
1/15/2018, 3:32:31 PM +883ms
+2ms ╔Received event [Patio West Coach Light].switch = on with a delay of 147ms
+142ms ║RunTime Analysis CS > 21ms > PS > 50ms > PE > 70ms > CE
+144ms ║Runtime (41332 bytes) successfully initialized in 50ms (v0.2.101.20171227) (142ms)
+145ms ║╔Execution stage started
+158ms ║║Cancelling condition #9’s schedules…
+159ms ║║Condition #9 evaluated false (9ms)
+160ms ║║Cancelling condition #3’s schedules…
+160ms ║║Condition group #3 evaluated false (state changed) (11ms)
+170ms ║║Condition #21 evaluated false (6ms)
+170ms ║║Condition group #15 evaluated false (state did not change) (7ms)
+182ms ║║Comparison (enum) on changes_to (string) off = false (1ms)
+183ms ║║Condition #28 evaluated false (10ms)
+184ms ║║Condition group #25 evaluated false (state did not change) (11ms)
+186ms ║╚Execution stage complete. (41ms)
+187ms ╚Event processed successfully (187ms)
1/15/2018, 3:32:31 PM +223ms
+1ms ╔Received event [Front Doorbell].motion = active with a delay of 74ms
+126ms ║RunTime Analysis CS > 14ms > PS > 41ms > PE > 71ms > CE
+128ms ║Runtime (41332 bytes) successfully initialized in 41ms (v0.2.101.20171227) (126ms)
+129ms ║╔Execution stage started
+136ms ║║Comparison (enum) active changes_to (string) active = true (0ms)
+138ms ║║Cancelling condition #9’s schedules…
+138ms ║║Condition #9 evaluated true (5ms)
+150ms ║║Comparison (enum) off is (string) off = true (1ms)
+152ms ║║Comparison (enum) off is (string) off = true (1ms)
+153ms ║║Condition #32 evaluated true (14ms)
+160ms ║║Comparison (time) 55951377 is_between (time) 0 … (time) 70200000 = true (5ms)
+161ms ║║Time restriction check passed
+163ms ║║Condition #33 evaluated true (9ms)
+164ms ║║Cancelling condition #3’s schedules…
+164ms ║║Condition group #3 evaluated true (state changed) (31ms)
+166ms ║║Cancelling statement #29’s schedules…
+173ms ║║Executed virtual command [Patio East Coach Light, Patio West Coach Light].setVariable (3ms)
+206ms ║║Executed physical command [Patio East Coach Light].on() (32ms)
+207ms ║║Executed [Patio East Coach Light].on (33ms)
+228ms ║║Executed physical command [Patio West Coach Light].on() (20ms)
+228ms ║║Executed [Patio West Coach Light].on (20ms)
+254ms ║║Executed physical command [Patio East Coach Light].setColorTemperature([2700]) (23ms)
+255ms ║║Executed [Patio East Coach Light].setColorTemperature (24ms)
+279ms ║║Executed physical command [Patio West Coach Light].setColorTemperature([2700]) (22ms)
+279ms ║║Executed [Patio West Coach Light].setColorTemperature (23ms)
+296ms ║║Executed physical command [Patio East Coach Light].setLevel([60]) (14ms)
+297ms ║║Executed [Patio East Coach Light].setLevel (15ms)
+311ms ║║Executed physical command [Patio West Coach Light].setLevel([60]) (13ms)
+311ms ║║Executed [Patio West Coach Light].setLevel (13ms)
+317ms ║║Comparison (enum) active changes_to (string) inactive = false (1ms)
+318ms ║║Condition #21 evaluated false (4ms)
+319ms ║║Condition group #15 evaluated false (state did not change) (5ms)
+330ms ║║Condition #28 evaluated false (9ms)
+331ms ║║Condition group #25 evaluated false (state did not change) (10ms)
+333ms ║╚Execution stage complete. (203ms)
+334ms ╚Event processed successfully (334ms)

bangali · 2018-01-15 22:03:46 UTC

thanks. how many lights do each of those device variables contain?

sechen43 · 2018-01-15 22:06:37 UTC

10 total lights. PatioCoachLights contains 2 and StreetSideCoachLights contains 8

Thank you-

bangali · 2018-01-15 22:08:56 UTC

in both pistons delete line 47 and change 51 to all instead of any.

those line numbers will be different in edit mode so compare them to snapshot line numbers.

do you need the functionality of keeping the lights on if manually turned on?

sechen43 · 2018-01-15 22:19:35 UTC

Thanks, I will make these changes. I liked the additional functionality of keeping the lights on if manually turned on. Haven’t used it yet, but may come in handy. Let me know if you see anything else that might not be right. Thanks.

bangali · 2018-01-15 22:22:05 UTC

ok. this was designed to work with 1 light. so may need little more tweaking to work with multiple lights. give it a try. if it does not work right feel free to ping me.

sechen43 · 2018-01-16 15:30:36 UTC

After removing line 47 and changing line 51 to “all”, the problem persists on two fronts. 1) the variable went back to remaining “true” after the lights shut off after inactivity. So when the lights are then turned back on purposefully (the next evening by another piston), and there is motion, the piston shuts the lights off after 5 minutes but they should stay on until the piston that turned them on at sunset shuts them off at midnight (they become motion lights from midnight to sunrise via the pistons in question.). 2) Inconsistent performance. I walk the dog at 5AM and the lights come on (via both pistons), after returning from a 15 minute walk they are all off. I walk past the motion sensor on the return and only the patio lights come on this time (the street side piston seems to be ignored). I initially broke them out into separate pistons for these reasons. 1) I will eventually want to have separate lighting settings and conditions on the patio. 2) I may add another motion sensor to the patio side which would only activate those lights… I thought separating things would make it cleaner and easier to manage as I made these changes along the way. As always, any input or suggestion is much appreciated! Thanks–

bangali · 2018-01-16 22:35:55 UTC

dont have a hub since yesterday … hub will be back online tonight / tomorrow am. will try the piston myself with multiple lights and get back to you.

sorry for the delay.

sechen43 · 2018-01-17 01:18:41 UTC

No worries, I appreciate all the help! In the meantime, I put line 47 back so it wont kick my lights off in the evening. Thanks again.

bangali · 2018-01-17 05:17:36 UTC

btw this motion sensor how long after the motion active event does it send the motion inactive event?

thanks.

sechen43 · 2018-01-17 18:45:23 UTC

Not sure how to check that. This is a ring pro doorbell. I don’t see anything in the settings for that.

bangali · 2018-01-17 21:55:02 UTC

open the ring pro device screen in the ST app. with screen on walk into range of the motion sensor and soon as it shows motion in the device screen stop in place. then countdown seconds in your head till the motion indicator changes from motion to no motion.

EDIT: if you are familiar with how to check ST logs then you could find the same information in the logs without using the ST app. otherwise the above is good start to get a sense of if its 5 sec or 50 sec or …

sechen43 · 2018-01-18 11:29:48 UTC

Thanks! I hear what you’re saying, guess I’m a bit slow. I thought there was a configuration I had set somewhere and I was trying to find what was set … The IDE logs only seem to report the “active” event. Seems odd to me. However when I use the method you describe above, I count exactly 30 seconds. The behavior seems consistent on morning dog walks, on the way out all lights come on (both pistons). On the way back 20 minutes later, only the patio lights go on. . The app for the sensor does clearly change from ready > motion > ready. Again, thanks for the help…!

bangali · 2018-01-18 17:03:08 UTC

i would classify as new to this … it just requires a certain getting used to.

probably in the settings for the ring device … click on the device in ST app then click the gear icon on top right corner-ish.

hmm … when you check the recently tab of the device does it show a discrete active event followed by an inactive event?

thanks.

JohnHoke · 2018-01-18 17:50:09 UTC

Unfortunately, I do not think Ring’s API returns to ST the inactive event - here is a cap of my lIDE recent events og

There is no ‘inactive’ event - it is just odd because in the ST App it shows Motion/No-Motion - must be a timeout somewhere … or it is tagging motion until it stops then untagging /shrug

bangali · 2018-01-18 18:09:47 UTC

try this one for both piston:

sechen43 · 2018-01-19 00:50:28 UTC

Thanks, will try this piston and get back to you. I can confirm what JohnHoke said… Never see an inactive anywhere (IDE or w/in the app). Also there aren’t any settings that can be changed for the Ring Pro other than the device name. Seems like a 30 second timeout process.