1) Give a description of the problem
Light is not turning off after motion event
2) What is the expected behavior?
5-10 mins after motion event/button event light should return to previous state
3) What is happening/not happening?
Light triggers on motion, but when light is already off it is not returning to an off state.
4) Post a Green Snapshot of the piston
5) Attach any logs (From ST IDE and by turning logging level to Full)
1/8/2018, 6:57:37 AM +940ms +1ms ╔Received event [Front Door].motion = active with a delay of 61ms +119ms ║RunTime Analysis CS > 15ms > PS > 45ms > PE > 59ms > CE +121ms ║Runtime (39286 bytes) successfully initialized in 45ms (v0.2.101.20171227) (119ms) +122ms ║╔Execution stage started +152ms ║║Condition #10 evaluated false (21ms) +156ms ║║Comparison (enum) active changes_to (string) active = true (1ms) +158ms ║║Cancelling condition #12's schedules... +159ms ║║Condition #12 evaluated true (6ms) +160ms ║║Cancelling condition #15's schedules... +161ms ║║Condition group #15 evaluated true (state changed) (30ms) +165ms ║║Comparison (boolean) true is (boolean) false = false (1ms) +167ms ║║Cancelling condition #16's schedules... +168ms ║║Condition #16 evaluated false (7ms) +169ms ║║Condition group #1 evaluated false (state did not change) (40ms) +172ms ║╚Execution stage complete. (49ms) +173ms ╚Event processed successfully (174ms) 1/8/2018, 6:17:32 AM +638ms +1ms ╔Received event [Home].time = 1515417453448 with a delay of -811ms +121ms ║RunTime Analysis CS > 25ms > PS > 39ms > PE > 58ms > CE +123ms ║Runtime (39287 bytes) successfully initialized in 39ms (v0.2.101.20171227) (121ms) +124ms ║╔Execution stage started +125ms ║╚Execution stage complete. (1ms) +127ms ╚Event processed successfully (126ms) 1/8/2018, 6:11:23 AM +592ms +1ms ╔Received event [Front Door].button = Ready with a delay of 94ms +10181ms ║RunTime Analysis CS > 22ms > PS > 10087ms > PE > 72ms > CE +10182ms ║Piston waited at a semaphore for 10034ms +10184ms ║Runtime (39365 bytes) successfully initialized in 10087ms (v0.2.101.20171227) (10182ms) +10185ms ║╔Execution stage started +10195ms ║║Comparison (enum) Ready gets (string) pushed = false (1ms) +10196ms ║║Condition #10 evaluated false (5ms) +10205ms ║║Condition #12 evaluated false (7ms) +10206ms ║║Condition group #15 evaluated false (state did not change) (14ms) +10206ms ║║Condition group #1 evaluated false (state did not change) (16ms) +10209ms ║╚Execution stage complete. (23ms) +10210ms ╚Event processed successfully (10210ms) 1/8/2018, 6:11:23 AM +573ms +1ms ╔Received event [Front Door].motion = inactive with a delay of 84ms +115ms ║RunTime Analysis CS > 19ms > PS > 41ms > PE > 54ms > CE +117ms ║Runtime (39280 bytes) successfully initialized in 41ms (v0.2.101.20171227) (115ms) +118ms ║╔Execution stage started +132ms ║║Condition #10 evaluated false (7ms) +136ms ║║Comparison (enum) inactive changes_to (string) active = false (1ms) +138ms ║║Cancelling condition #12's schedules... +138ms ║║Condition #12 evaluated false (5ms) +140ms ║║Cancelling condition #15's schedules... +140ms ║║Condition group #15 evaluated false (state changed) (16ms) +141ms ║║Cancelling condition #1's schedules... +142ms ║║Condition group #1 evaluated false (state changed) (19ms) +145ms ║╚Execution stage complete. (27ms) +146ms ╚Event processed successfully (146ms) 1/8/2018, 6:10:53 AM +153ms +1ms ╔Received event [Front Door].motion = active with a delay of 66ms +110ms ║RunTime Analysis CS > 17ms > PS > 40ms > PE > 54ms > CE +113ms ║Runtime (39286 bytes) successfully initialized in 40ms (v0.2.101.20171227) (111ms) +114ms ║╔Execution stage started +127ms ║║Condition #10 evaluated false (7ms) +131ms ║║Comparison (enum) active changes_to (string) active = true (1ms) +133ms ║║Cancelling condition #12's schedules... +133ms ║║Condition #12 evaluated true (5ms) +134ms ║║Cancelling condition #15's schedules... +135ms ║║Condition group #15 evaluated true (state changed) (15ms) +139ms ║║Comparison (boolean) false is (boolean) false = true (1ms) +141ms ║║Condition #16 evaluated true (4ms) +142ms ║║Cancelling condition #1's schedules... +142ms ║║Condition group #1 evaluated true (state changed) (23ms) +176ms ║║Comparison (time) 22253298 is_between (time) 1515458280000 .. (time) 1515422100000 = true (8ms) +177ms ║║Time restriction check passed +178ms ║║Condition #7 evaluated true (34ms) +179ms ║║Condition group #2 evaluated true (state did not change) (35ms) +180ms ║║Cancelling statement #2's schedules... +187ms ║║Executed virtual command [Porch Light].setVariable (3ms) +541ms ║║Executed virtual command [Porch Light].saveStateLocally (351ms) +555ms ║║Executed physical command [Porch Light].setLevel([100]) (10ms) +556ms ║║Executed [Porch Light].setLevel (12ms) +561ms ║║Executed virtual command [Porch Light].waitRandom (1ms) +563ms ║║Requesting a wake up for Mon, Jan 8 2018 @ 6:17:33 AM MST (in 399.732s) +567ms ║╚Execution stage complete. (454ms) +569ms ║Setting up scheduled job for Mon, Jan 8 2018 @ 6:17:33 AM MST (in 399.727s) +576ms ╚Event processed successfully (576ms)
