1) Give a description of the problem
My piston is designed to change the color temp of 2 bulbs based on time of day. If they’re off, nothing is supposed to happen. If switched on, set the temp based on the time. If already on, change the temp at specific times. It all works as expected EXCEPT that something else is sending an ‘ON’ at the same time every day. I’ve checked all my other pistons, the Smart Lighting app, and Alexa but I cannot find what is sending the command at 9:33am every day. What else can it be??
2) What is the expected behaviour?
As above, the piston works fine other than that it is responding to a ghost command.
3) What is happening/not happening?
Note from the log:
7:59:59 - Light switches are off, nothing happens
9:33:02 - Something sends an ON command to both switches. Time is not yet 10:14, so piston sets color temp to 5150
10:14:59 - Light switches are on, so piston sets color temp to 4250
4) Post a Green Snapshot of the piston
5) Attach logs after turning logging level to Full
12/29/2020, 10:14:59 AM +101ms
+1ms ╔Received event [Home].time = 1609258500000 with a delay of -899ms
+193ms ║RunTime Analysis CS > 28ms > PS > 10ms > PE > 154ms > CE
+197ms ║Runtime (45461 bytes) successfully initialized in 10ms (v0.3.110.20191009) (194ms)
+198ms ║╔Execution stage started
+263ms ║║Comparison (enum) on is (string) on = true (2ms)
+266ms ║║Comparison (enum) on is (string) on = true (1ms)
+267ms ║║Cancelling condition #26’s schedules…
+268ms ║║Condition #26 evaluated true (20ms)
+269ms ║║Cancelling condition #25’s schedules…
+270ms ║║Condition group #25 evaluated true (state changed) (23ms)
+272ms ║║Cancelling statement #27’s schedules…
+2718ms ║║Executed physical command [Lib 1].setColorTemperature([4250]) (2442ms)
+2719ms ║║Executed [Lib 1].setColorTemperature (2443ms)
+2745ms ║║Executed physical command [Lib 2].setColorTemperature([4250]) (25ms)
+2746ms ║║Executed [Lib 2].setColorTemperature (26ms)
+2750ms ║╚Execution stage complete. (2553ms)
+2752ms ║Setting up scheduled job for Tue, Dec 29 2020 @ 12:30:00 PM CST (in 8098.147s), with 3 more jobs pending
+2759ms ╚Event processed successfully (2759ms)
12/29/2020, 9:33:02 AM +913ms
+2ms ╔Received event [Lib 2].switch = on with a delay of 107ms
+10282ms ║RunTime Analysis CS > 24ms > PS > 10153ms > PE > 104ms > CE
+10283ms ║Piston waited at a semaphore for 10148ms
+10287ms ║Runtime (45530 bytes) successfully initialized in 10153ms (v0.3.110.20191009) (10285ms)
+10288ms ║╔Execution stage started
+10306ms ║║Comparison (enum) on changes_to (string) on = false (1ms)
+10309ms ║║Cancelling condition #2’s schedules…
+10310ms ║║Condition #2 evaluated false (16ms)
+10311ms ║║Cancelling condition #1’s schedules…
+10313ms ║║Condition group #1 evaluated false (state changed) (18ms)
+10327ms ║╚Execution stage complete. (39ms)
+10330ms ║Setting up scheduled job for Tue, Dec 29 2020 @ 10:15:00 AM CST (in 2506.758s), with 3 more jobs pending
+10341ms ╚Event processed successfully (10340ms)
12/29/2020, 9:33:02 AM +877ms
+2ms ╔Received event [Lib 1].switch = on with a delay of 164ms
+92ms ║RunTime Analysis CS > 24ms > PS > 8ms > PE > 60ms > CE
+95ms ║Runtime (45454 bytes) successfully initialized in 8ms (v0.3.110.20191009) (92ms)
+96ms ║╔Execution stage started
+110ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+112ms ║║Cancelling condition #2’s schedules…
+112ms ║║Condition #2 evaluated true (12ms)
+113ms ║║Cancelling condition #1’s schedules…
+114ms ║║Condition group #1 evaluated true (state changed) (15ms)
+123ms ║║Comparison (time) 34382994 is_before (time) 36840000 = true (5ms)
+124ms ║║Time restriction check passed
+125ms ║║Cancelling condition #4’s schedules…
+126ms ║║Condition #4 evaluated true (10ms)
+127ms ║║Cancelling condition #3’s schedules…
+128ms ║║Condition group #3 evaluated true (state changed) (12ms)
+130ms ║║Cancelling statement #5’s schedules…
+177ms ║║Executed physical command [Lib 1].setColorTemperature([5150]) (42ms)
+177ms ║║Executed [Lib 1].setColorTemperature (43ms)
+206ms ║║Executed physical command [Lib 2].setColorTemperature([5150]) (26ms)
+207ms ║║Executed [Lib 2].setColorTemperature (28ms)
+219ms ║║Comparison (time) 34383087 is_between (time) 36900000 … (time) 44940000 = false (8ms)
+220ms ║║Condition #8 evaluated false (11ms)
+221ms ║║Condition group #7 evaluated false (state did not change) (12ms)
+233ms ║║Comparison (time) 34383101 is_between (time) 45000000 … (time) 53040000 = false (7ms)
+234ms ║║Condition #12 evaluated false (10ms)
+235ms ║║Condition group #11 evaluated false (state did not change) (12ms)
+244ms ║║Comparison (time) 34383115 is_after (time) 53100000 = false (5ms)
+246ms ║║Cancelling condition #16’s schedules…
+247ms ║║Condition #16 evaluated false (9ms)
+248ms ║║Cancelling condition #15’s schedules…
+249ms ║║Condition group #15 evaluated false (state changed) (11ms)
+260ms ║╚Execution stage complete. (164ms)
+262ms ║Setting up scheduled job for Tue, Dec 29 2020 @ 10:15:00 AM CST (in 2516.861s), with 3 more jobs pending
+271ms ╚Event processed successfully (270ms)
12/29/2020, 7:59:59 AM +116ms
+1ms ╔Received event [Home].time = 1609250400000 with a delay of -884ms
+258ms ║RunTime Analysis CS > 33ms > PS > 12ms > PE > 212ms > CE
+262ms ║Runtime (45467 bytes) successfully initialized in 12ms (v0.3.110.20191009) (257ms)
+263ms ║╔Execution stage started
+325ms ║║Comparison (enum) off is (string) on = false (1ms)
+327ms ║║Condition #21 evaluated false (19ms)
+328ms ║║Condition group #20 evaluated false (state did not change) (20ms)
+333ms ║╚Execution stage complete. (71ms)
+336ms ║Setting up scheduled job for Tue, Dec 29 2020 @ 10:15:00 AM CST (in 8100.549s), with 3 more jobs pending
+348ms ╚Event processed successfully (348ms)