1) Give a description of the problem
Smarthings isn’t super reliable regarding making sure that your commands actually are implemented. As such I took some code from these forums that would check the state of the objects I want to manage and, if they are not in the desired state after 5 seconds will go and reissue the command. This worked great up until the recent update to The Smartthings V2 Hub which broke all of my Wemo and some of my Hue objects.
I ended up with my piston in an endless loop because some of the devices would simply never change to the desired state (at least not until I get this nonsense sorted with Samsung).
I added a counter to each loop with the intent that it give up after 5 attempts. It’s code I want in place even after my various devices are working again to avoid infinite loops should some other similar issue occur.
2) What is the expected behavior?
Once the “Loopcounter” variable hits 5 it should satisfy the “UNTIL” condition and exit the loop.
3) What is happening/not happening?
After the 5th iteration, the message in the UNTIL section when Loopcounter exceeds 5 is sent but then Loopcounter resets to zero and it starts the loop again.
Removing ALL of the Loopcounter initialization statements allows the piston to run to the end. Of course it isn’t executing any of the loops properly after the first one.
If I remove the WAIT statement within the loop it also executes OK but of course this does not allow any time for the device to report back before trying to push the command again.
4) Post a Green Snapshot of the piston
5) Attach any logs (From ST IDE and by turning logging level to Full)
+0ms ╔Starting piston... (v0.2.100.20171211) +597ms ║╔Subscribing to devices... +705ms ║║Subscribing to Home.routineExecuted.d9fc45eb-656d-4fa9-a343-114def288be0... +718ms ║║Subscribing to Home.:e7485d7911337d06e230038a35981b32:.@WhoIsHome... +939ms ║╚Finished subscribing (344ms) +962ms ║Comparison (string) null executes (string) :ed291579d99773614e4f5cc0f2b74dff: = false (1ms) +965ms ║Cancelling condition #129's schedules... +968ms ║Comparison (dynamic) Michelle's iPhone changes = false (1ms) +972ms ║Comparison (boolean) false is (boolean) false = true (1ms) +973ms ║Cancelling condition #22's schedules... +1082ms ║Comparison (time) 70489251 is_between (datetime) 1513463460000 .. (datetime) 1513427760000 = true (5ms) +1083ms ║Time restriction check passed +1099ms ║Comparison (enum) on is (string) off = false (1ms) +1107ms ║Comparison (enum) on is (string) on = true (1ms) +1111ms ║Comparison (integer) 3 is_greater_than (integer) 4 = false (1ms) +1112ms ║Cancelling condition #101's schedules... +1156ms ║Comparison (enum) off is (string) off = true (2ms) +1158ms ║Comparison (enum) on is (string) off = false (0ms) +1159ms ║Comparison (enum) off is (string) off = true (1ms) +1161ms ║Comparison (enum) off is (string) off = true (1ms) +1163ms ║Comparison (enum) on is (string) off = false (1ms) +1165ms ║Comparison (enum) on is (string) off = false (1ms) +1198ms ║Comparison (enum) off is (string) on = false (1ms) +1204ms ║Comparison (integer) 3 is_greater_than (integer) 4 = false (1ms) +1220ms ║Comparison (enum) off is (string) on = false (1ms) +1229ms ║Comparison (integer) 3 is_greater_than (integer) 4 = false (1ms) +1230ms ║Cancelling condition #113's schedules... +1242ms ║Comparison (enum) unlocked is (string) locked = false (1ms) +1253ms ║Comparison (enum) unlocked is (string) unlocked = true (2ms) +1257ms ║Comparison (integer) 3 is_greater_than (integer) 4 = false (1ms) +1258ms ║Cancelling condition #119's schedules... +1271ms ║Comparison (enum) off is (string) off = true (1ms) +1278ms ║Comparison (boolean) false is (boolean) true = false (1ms) +1288ms ║Comparison (enum) off is (string) on = false (1ms) +1291ms ║Comparison (integer) 3 is_greater_than (integer) 4 = false (1ms) +1292ms ║Cancelling condition #126's schedules... +1295ms ║Cancelling condition #89's schedules... +1307ms ╚Piston successfully started (1307ms) 12/16/2017, 7:34:43 PM +506ms +239ms ╔Stopping piston... +406ms ╚Piston successfully stopped (167ms) 12/16/2017, 7:34:39 PM +262ms +0ms ╔Received event [Home].time = 1513470880774 with a delay of -1513ms +432ms ║RunTime Analysis CS > 19ms > PS > 63ms > PE > 350ms > CE +437ms ║Runtime (59493 bytes) successfully initialized in 63ms (v0.2.100.20171211) (435ms) +438ms ║╔Execution stage started +531ms ║║Comparison (enum) off is (string) on = false (2ms) +535ms ║║Condition #18 evaluated false (42ms) +541ms ║║Comparison (integer) 1 is_greater_than (integer) 4 = false (1ms) +543ms ║║Condition #107 evaluated false (6ms) +544ms ║║Condition group #11 evaluated false (state did not change) (52ms) +579ms ║║Comparison (enum) off is (string) off = true (2ms) +582ms ║║Comparison (enum) on is (string) off = false (2ms) +584ms ║║Comparison (enum) off is (string) off = true (1ms) +586ms ║║Comparison (enum) off is (string) off = true (1ms) +588ms ║║Comparison (enum) on is (string) off = false (1ms) +591ms ║║Comparison (enum) on is (string) off = false (2ms) +598ms ║║Condition #13 evaluated true (49ms) +599ms ║║Condition group #12 evaluated true (state did not change) (52ms) +603ms ║║Cancelling statement #14's schedules... +615ms ║║Calculating (integer) 1 + (integer) 1 >> (integer) 2 +625ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +653ms ║║Executed physical command [Coffee Maker].on() (26ms) +654ms ║║Executed [Coffee Maker].on (28ms) +678ms ║║Executed physical command [Gym Air Conditioner].on() (22ms) +679ms ║║Executed [Gym Air Conditioner].on (24ms) +704ms ║║Executed physical command [Living Room Acccent].on() (23ms) +705ms ║║Executed [Living Room Acccent].on (25ms) +708ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +709ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:34:44 PM EST (in 5.0s) +716ms ║╚Execution stage complete. (277ms) +718ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:34:44 PM EST (in 4.993s) +739ms ╚Event processed successfully (739ms) 12/16/2017, 7:34:35 PM +302ms +1ms ╔Received event [Home].time/recovery = 1513470875301 with a delay of 1ms +243ms ║RunTime Analysis CS > 13ms > PS > 47ms > PE > 184ms > CE +247ms ║Runtime (59498 bytes) successfully initialized in 47ms (v0.2.100.20171211) (245ms) +248ms ║╔Execution stage started +324ms ║║Comparison (enum) off is (string) on = false (2ms) +327ms ║║Condition #18 evaluated false (38ms) +332ms ║║Comparison (integer) 0 is_greater_than (integer) 4 = false (1ms) +334ms ║║Condition #107 evaluated false (6ms) +335ms ║║Condition group #11 evaluated false (state did not change) (46ms) +369ms ║║Comparison (enum) off is (string) off = true (2ms) +371ms ║║Comparison (enum) on is (string) off = false (1ms) +374ms ║║Comparison (enum) off is (string) off = true (2ms) +377ms ║║Comparison (enum) off is (string) off = true (1ms) +380ms ║║Comparison (enum) on is (string) off = false (1ms) +382ms ║║Comparison (enum) on is (string) off = false (2ms) +388ms ║║Condition #13 evaluated true (50ms) +389ms ║║Condition group #12 evaluated true (state did not change) (51ms) +392ms ║║Cancelling statement #14's schedules... +400ms ║║Calculating (integer) 0 + (integer) 1 >> (integer) 1 +405ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +424ms ║║Executed physical command [Coffee Maker].on() (17ms) +425ms ║║Executed [Coffee Maker].on (19ms) +444ms ║║Executed physical command [Gym Air Conditioner].on() (18ms) +446ms ║║Executed [Gym Air Conditioner].on (19ms) +464ms ║║Executed physical command [Living Room Acccent].on() (17ms) +465ms ║║Executed [Living Room Acccent].on (18ms) +469ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +471ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:34:40 PM EST (in 5.0s) +478ms ║╚Execution stage complete. (230ms) +480ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:34:40 PM EST (in 4.993s) +501ms ╚Event processed successfully (501ms) 12/16/2017, 7:34:09 PM +287ms +1ms ╔Received event [Home].time = 1513470850801 with a delay of -1514ms +476ms ║RunTime Analysis CS > 22ms > PS > 68ms > PE > 385ms > CE +480ms ║Runtime (59493 bytes) successfully initialized in 68ms (v0.2.100.20171211) (478ms) +481ms ║╔Execution stage started +572ms ║║Comparison (enum) off is (string) on = false (2ms) +575ms ║║Condition #18 evaluated false (44ms) +579ms ║║Comparison (integer) 4 is_greater_than (integer) 4 = false (1ms) +581ms ║║Condition #107 evaluated false (5ms) +582ms ║║Condition group #11 evaluated false (state did not change) (52ms) +619ms ║║Comparison (enum) off is (string) off = true (2ms) +622ms ║║Comparison (enum) on is (string) off = false (2ms) +624ms ║║Comparison (enum) off is (string) off = true (1ms) +626ms ║║Comparison (enum) off is (string) off = true (1ms) +628ms ║║Comparison (enum) on is (string) off = false (1ms) +630ms ║║Comparison (enum) on is (string) off = false (1ms) +635ms ║║Condition #13 evaluated true (50ms) +636ms ║║Condition group #12 evaluated true (state did not change) (52ms) +639ms ║║Cancelling statement #14's schedules... +645ms ║║Calculating (integer) 4 + (integer) 1 >> (integer) 5 +652ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (4ms) +676ms ║║Executed physical command [Coffee Maker].on() (23ms) +677ms ║║Executed [Coffee Maker].on (24ms) +700ms ║║Executed physical command [Gym Air Conditioner].on() (22ms) +701ms ║║Executed [Gym Air Conditioner].on (23ms) +725ms ║║Executed physical command [Living Room Acccent].on() (22ms) +725ms ║║Executed [Living Room Acccent].on (23ms) +729ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +730ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:34:15 PM EST (in 5.0s) +735ms ║╚Execution stage complete. (254ms) +736ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:34:15 PM EST (in 4.994s) +746ms ╚Event processed successfully (746ms) 12/16/2017, 7:34:05 PM +275ms +0ms ╔Received event [Home].time = 1513470846919 with a delay of -1645ms +269ms ║RunTime Analysis CS > 17ms > PS > 83ms > PE > 170ms > CE +273ms ║Runtime (59493 bytes) successfully initialized in 83ms (v0.2.100.20171211) (272ms) +274ms ║╔Execution stage started +350ms ║║Comparison (enum) off is (string) on = false (2ms) +353ms ║║Condition #18 evaluated false (41ms) +358ms ║║Comparison (integer) 3 is_greater_than (integer) 4 = false (1ms) +359ms ║║Condition #107 evaluated false (5ms) +361ms ║║Condition group #11 evaluated false (state did not change) (49ms) +391ms ║║Comparison (enum) off is (string) off = true (1ms) +394ms ║║Comparison (enum) on is (string) off = false (1ms) +396ms ║║Comparison (enum) off is (string) off = true (2ms) +399ms ║║Comparison (enum) off is (string) off = true (2ms) +402ms ║║Comparison (enum) on is (string) off = false (2ms) +405ms ║║Comparison (enum) on is (string) off = false (2ms) +412ms ║║Condition #13 evaluated true (47ms) +413ms ║║Condition group #12 evaluated true (state did not change) (50ms) +416ms ║║Cancelling statement #14's schedules... +423ms ║║Calculating (integer) 3 + (integer) 1 >> (integer) 4 +429ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (4ms) +459ms ║║Executed physical command [Coffee Maker].on() (28ms) +460ms ║║Executed [Coffee Maker].on (30ms) +489ms ║║Executed physical command [Gym Air Conditioner].on() (28ms) +490ms ║║Executed [Gym Air Conditioner].on (29ms) +520ms ║║Executed physical command [Living Room Acccent].on() (27ms) +521ms ║║Executed [Living Room Acccent].on (29ms) +524ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +526ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:34:10 PM EST (in 5.0s) +532ms ║╚Execution stage complete. (258ms) +534ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:34:10 PM EST (in 4.993s) +543ms ╚Event processed successfully (543ms) 12/16/2017, 7:34:01 PM +195ms +0ms ╔Received event [Home].time = 1513470842560 with a delay of -1366ms +354ms ║RunTime Analysis CS > 62ms > PS > 116ms > PE > 177ms > CE +358ms ║Runtime (59495 bytes) successfully initialized in 116ms (v0.2.100.20171211) (357ms) +359ms ║╔Execution stage started +432ms ║║Comparison (enum) off is (string) on = false (2ms) +434ms ║║Condition #18 evaluated false (43ms) +438ms ║║Comparison (integer) 2 is_greater_than (integer) 4 = false (1ms) +440ms ║║Condition #107 evaluated false (5ms) +441ms ║║Condition group #11 evaluated false (state did not change) (51ms) +469ms ║║Comparison (enum) off is (string) off = true (1ms) +471ms ║║Comparison (enum) on is (string) off = false (2ms) +473ms ║║Comparison (enum) off is (string) off = true (1ms) +475ms ║║Comparison (enum) off is (string) off = true (1ms) +477ms ║║Comparison (enum) on is (string) off = false (1ms) +479ms ║║Comparison (enum) on is (string) off = false (1ms) +484ms ║║Condition #13 evaluated true (41ms) +485ms ║║Condition group #12 evaluated true (state did not change) (42ms) +488ms ║║Cancelling statement #14's schedules... +493ms ║║Calculating (integer) 2 + (integer) 1 >> (integer) 3 +498ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +527ms ║║Executed physical command [Coffee Maker].on() (27ms) +528ms ║║Executed [Coffee Maker].on (29ms) +688ms ║║Executed physical command [Gym Air Conditioner].on() (158ms) +689ms ║║Executed [Gym Air Conditioner].on (160ms) +717ms ║║Executed physical command [Living Room Acccent].on() (27ms) +718ms ║║Executed [Living Room Acccent].on (28ms) +722ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +723ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:34:06 PM EST (in 5.0s) +729ms ║╚Execution stage complete. (371ms) +730ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:34:06 PM EST (in 4.994s) +813ms ╚Event processed successfully (813ms) 12/16/2017, 7:33:57 PM +106ms +0ms ╔Received event [Home].time = 1513470838755 with a delay of -1650ms +236ms ║RunTime Analysis CS > 30ms > PS > 53ms > PE > 154ms > CE +240ms ║Runtime (59493 bytes) successfully initialized in 53ms (v0.2.100.20171211) (239ms) +241ms ║╔Execution stage started +308ms ║║Comparison (enum) off is (string) on = false (2ms) +310ms ║║Condition #18 evaluated false (32ms) +315ms ║║Comparison (integer) 1 is_greater_than (integer) 4 = false (2ms) +316ms ║║Condition #107 evaluated false (5ms) +318ms ║║Condition group #11 evaluated false (state did not change) (40ms) +349ms ║║Comparison (enum) off is (string) off = true (1ms) +351ms ║║Comparison (enum) on is (string) off = false (2ms) +353ms ║║Comparison (enum) off is (string) off = true (1ms) +355ms ║║Comparison (enum) off is (string) off = true (1ms) +358ms ║║Comparison (enum) on is (string) off = false (2ms) +360ms ║║Comparison (enum) on is (string) off = false (1ms) +365ms ║║Condition #13 evaluated true (45ms) +366ms ║║Condition group #12 evaluated true (state did not change) (46ms) +369ms ║║Cancelling statement #14's schedules... +376ms ║║Calculating (integer) 1 + (integer) 1 >> (integer) 2 +381ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +411ms ║║Executed physical command [Coffee Maker].on() (29ms) +412ms ║║Executed [Coffee Maker].on (30ms) +430ms ║║Executed physical command [Gym Air Conditioner].on() (17ms) +431ms ║║Executed [Gym Air Conditioner].on (17ms) +448ms ║║Executed physical command [Living Room Acccent].on() (16ms) +449ms ║║Executed [Living Room Acccent].on (18ms) +452ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +453ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:34:02 PM EST (in 5.0s) +459ms ║╚Execution stage complete. (219ms) +461ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:34:02 PM EST (in 4.994s) +479ms ╚Event processed successfully (479ms) 12/16/2017, 7:33:53 PM +285ms +1ms ╔Received event [Home].time/recovery = 1513470833284 with a delay of 1ms +227ms ║RunTime Analysis CS > 17ms > PS > 67ms > PE > 143ms > CE +231ms ║Runtime (59498 bytes) successfully initialized in 67ms (v0.2.100.20171211) (229ms) +232ms ║╔Execution stage started +317ms ║║Comparison (enum) off is (string) on = false (1ms) +319ms ║║Condition #18 evaluated false (42ms) +324ms ║║Comparison (integer) 0 is_greater_than (integer) 4 = false (1ms) +325ms ║║Condition #107 evaluated false (5ms) +326ms ║║Condition group #11 evaluated false (state did not change) (49ms) +357ms ║║Comparison (enum) off is (string) off = true (2ms) +360ms ║║Comparison (enum) on is (string) off = false (2ms) +362ms ║║Comparison (enum) off is (string) off = true (1ms) +365ms ║║Comparison (enum) off is (string) off = true (1ms) +367ms ║║Comparison (enum) on is (string) off = false (2ms) +369ms ║║Comparison (enum) on is (string) off = false (1ms) +375ms ║║Condition #13 evaluated true (45ms) +376ms ║║Condition group #12 evaluated true (state did not change) (46ms) +378ms ║║Cancelling statement #14's schedules... +384ms ║║Calculating (integer) 0 + (integer) 1 >> (integer) 1 +390ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (4ms) +414ms ║║Executed physical command [Coffee Maker].on() (23ms) +415ms ║║Executed [Coffee Maker].on (24ms) +438ms ║║Executed physical command [Gym Air Conditioner].on() (22ms) +439ms ║║Executed [Gym Air Conditioner].on (24ms) +462ms ║║Executed physical command [Living Room Acccent].on() (22ms) +463ms ║║Executed [Living Room Acccent].on (23ms) +468ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +470ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:33:58 PM EST (in 5.0s) +476ms ║╚Execution stage complete. (244ms) +478ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:33:58 PM EST (in 4.993s) +496ms ╚Event processed successfully (496ms) 12/16/2017, 7:33:28 PM +462ms +1ms ╔Received event [Home].time = 1513470809274 with a delay of -812ms +275ms ║RunTime Analysis CS > 22ms > PS > 69ms > PE > 184ms > CE +280ms ║Runtime (59492 bytes) successfully initialized in 69ms (v0.2.100.20171211) (278ms) +281ms ║╔Execution stage started +351ms ║║Comparison (enum) off is (string) on = false (1ms) +353ms ║║Condition #18 evaluated false (37ms) +357ms ║║Comparison (integer) 4 is_greater_than (integer) 4 = false (1ms) +358ms ║║Condition #107 evaluated false (4ms) +359ms ║║Condition group #11 evaluated false (state did not change) (43ms) +387ms ║║Comparison (enum) off is (string) off = true (2ms) +389ms ║║Comparison (enum) on is (string) off = false (1ms) +391ms ║║Comparison (enum) off is (string) off = true (1ms) +393ms ║║Comparison (enum) off is (string) off = true (1ms) +395ms ║║Comparison (enum) on is (string) off = false (1ms) +397ms ║║Comparison (enum) on is (string) off = false (1ms) +403ms ║║Condition #13 evaluated true (40ms) +404ms ║║Condition group #12 evaluated true (state did not change) (42ms) +406ms ║║Cancelling statement #14's schedules... +412ms ║║Calculating (integer) 4 + (integer) 1 >> (integer) 5 +416ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +448ms ║║Executed physical command [Coffee Maker].on() (30ms) +449ms ║║Executed [Coffee Maker].on (32ms) +477ms ║║Executed physical command [Gym Air Conditioner].on() (27ms) +478ms ║║Executed [Gym Air Conditioner].on (28ms) +506ms ║║Executed physical command [Living Room Acccent].on() (27ms) +507ms ║║Executed [Living Room Acccent].on (28ms) +510ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +511ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:33:33 PM EST (in 5.0s) +517ms ║╚Execution stage complete. (236ms) +518ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:33:33 PM EST (in 4.994s) +539ms ╚Event processed successfully (539ms) 12/16/2017, 7:33:23 PM +505ms +1ms ╔Received event [Home].time = 1513470804179 with a delay of -675ms +286ms ║RunTime Analysis CS > 20ms > PS > 54ms > PE > 213ms > CE +291ms ║Runtime (59492 bytes) successfully initialized in 54ms (v0.2.100.20171211) (288ms) +292ms ║╔Execution stage started +572ms ║║Comparison (enum) off is (string) on = false (2ms) +575ms ║║Condition #18 evaluated false (241ms) +580ms ║║Comparison (integer) 3 is_greater_than (integer) 4 = false (2ms) +582ms ║║Condition #107 evaluated false (5ms) +583ms ║║Condition group #11 evaluated false (state did not change) (248ms) +635ms ║║Comparison (enum) off is (string) off = true (2ms) +638ms ║║Comparison (enum) on is (string) off = false (2ms) +641ms ║║Comparison (enum) off is (string) off = true (1ms) +644ms ║║Comparison (enum) off is (string) off = true (2ms) +647ms ║║Comparison (enum) on is (string) off = false (2ms) +650ms ║║Comparison (enum) on is (string) off = false (2ms) +656ms ║║Condition #13 evaluated true (70ms) +657ms ║║Condition group #12 evaluated true (state did not change) (72ms) +661ms ║║Cancelling statement #14's schedules... +671ms ║║Calculating (integer) 3 + (integer) 1 >> (integer) 4 +678ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +699ms ║║Executed physical command [Coffee Maker].on() (18ms) +700ms ║║Executed [Coffee Maker].on (20ms) +726ms ║║Executed physical command [Gym Air Conditioner].on() (24ms) +728ms ║║Executed [Gym Air Conditioner].on (26ms) +760ms ║║Executed physical command [Living Room Acccent].on() (30ms) +761ms ║║Executed [Living Room Acccent].on (32ms) +767ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +768ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:33:29 PM EST (in 5.0s) +777ms ║╚Execution stage complete. (485ms) +779ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:33:29 PM EST (in 4.99s) +799ms ╚Event processed successfully (800ms) 12/16/2017, 7:33:18 PM +77ms +1ms ╔Received event [Home].time = 1513470798956 with a delay of -879ms +283ms ║RunTime Analysis CS > 18ms > PS > 90ms > PE > 175ms > CE +287ms ║Runtime (59492 bytes) successfully initialized in 90ms (v0.2.100.20171211) (285ms) +288ms ║╔Execution stage started +400ms ║║Comparison (enum) off is (string) on = false (2ms) +403ms ║║Condition #18 evaluated false (49ms) +408ms ║║Comparison (integer) 2 is_greater_than (integer) 4 = false (2ms) +410ms ║║Condition #107 evaluated false (6ms) +411ms ║║Condition group #11 evaluated false (state did not change) (58ms) +453ms ║║Comparison (enum) off is (string) off = true (2ms) +455ms ║║Comparison (enum) on is (string) off = false (2ms) +457ms ║║Comparison (enum) off is (string) off = true (1ms) +460ms ║║Comparison (enum) off is (string) off = true (2ms) +462ms ║║Comparison (enum) on is (string) off = false (1ms) +464ms ║║Comparison (enum) on is (string) off = false (1ms) +471ms ║║Condition #13 evaluated true (56ms) +472ms ║║Condition group #12 evaluated true (state did not change) (59ms) +474ms ║║Cancelling statement #14's schedules... +481ms ║║Calculating (integer) 2 + (integer) 1 >> (integer) 3 +488ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +511ms ║║Executed physical command [Coffee Maker].on() (22ms) +512ms ║║Executed [Coffee Maker].on (23ms) +531ms ║║Executed physical command [Gym Air Conditioner].on() (18ms) +532ms ║║Executed [Gym Air Conditioner].on (19ms) +1093ms ║║Executed physical command [Living Room Acccent].on() (559ms) +1094ms ║║Executed [Living Room Acccent].on (561ms) +1099ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (1ms) +1101ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:33:24 PM EST (in 5.0s) +1120ms ║╚Execution stage complete. (832ms) +1123ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:33:24 PM EST (in 4.981s) +1145ms ╚Event processed successfully (1145ms) 12/16/2017, 7:33:13 PM +288ms +0ms ╔Received event [Home].time = 1513470794908 with a delay of -1620ms +449ms ║RunTime Analysis CS > 17ms > PS > 207ms > PE > 226ms > CE +453ms ║Runtime (59493 bytes) successfully initialized in 207ms (v0.2.100.20171211) (451ms) +455ms ║╔Execution stage started +532ms ║║Comparison (enum) off is (string) on = false (2ms) +534ms ║║Condition #18 evaluated false (34ms) +539ms ║║Comparison (integer) 1 is_greater_than (integer) 4 = false (2ms) +540ms ║║Cancelling condition #107's schedules... +541ms ║║Condition #107 evaluated false (6ms) +542ms ║║Cancelling condition #11's schedules... +543ms ║║Condition group #11 evaluated false (state changed) (43ms) +572ms ║║Comparison (enum) off is (string) off = true (1ms) +574ms ║║Comparison (enum) on is (string) off = false (2ms) +576ms ║║Comparison (enum) off is (string) off = true (1ms) +578ms ║║Comparison (enum) off is (string) off = true (1ms) +580ms ║║Comparison (enum) on is (string) off = false (1ms) +583ms ║║Comparison (enum) on is (string) off = false (2ms) +588ms ║║Condition #13 evaluated true (42ms) +589ms ║║Condition group #12 evaluated true (state did not change) (44ms) +592ms ║║Cancelling statement #14's schedules... +599ms ║║Calculating (integer) 1 + (integer) 1 >> (integer) 2 +604ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (2ms) +623ms ║║Executed physical command [Coffee Maker].on() (18ms) +624ms ║║Executed [Coffee Maker].on (19ms) +643ms ║║Executed physical command [Gym Air Conditioner].on() (17ms) +644ms ║║Executed [Gym Air Conditioner].on (19ms) +662ms ║║Executed physical command [Living Room Acccent].on() (15ms) +662ms ║║Executed [Living Room Acccent].on (17ms) +666ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (1ms) +667ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:33:18 PM EST (in 5.0s) +673ms ║╚Execution stage complete. (219ms) +674ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:33:18 PM EST (in 4.994s) +693ms ╚Event processed successfully (693ms) 12/16/2017, 7:33:09 PM +491ms +0ms ╔Received event [Home].routineExecuted = d9fc45eb-656d-4fa9-a343-114def288be0 with a delay of 75ms +203ms ║RunTime Analysis CS > 11ms > PS > 36ms > PE > 155ms > CE +206ms ║Runtime (59494 bytes) successfully initialized in 36ms (v0.2.100.20171211) (205ms) +207ms ║╔Execution stage started +214ms ║║Comparison (string) :ed291579d99773614e4f5cc0f2b74dff: executes (string) :ed291579d99773614e4f5cc0f2b74dff: = true (1ms) +215ms ║║Cancelling condition #129's schedules... +216ms ║║Condition #129 evaluated true (5ms) +217ms ║║Cancelling condition #22's schedules... +218ms ║║Condition group #22 evaluated true (state changed) (6ms) +268ms ║║Comparison (time) 70389711 is_between (datetime) 1513463460000 .. (datetime) 1513427760000 = true (5ms) +269ms ║║Time restriction check passed +270ms ║║Condition #86 evaluated true (51ms) +271ms ║║Condition group #76 evaluated true (state did not change) (52ms) +273ms ║║Cancelling statement #136's schedules... +278ms ║║Executed virtual command setVariable (2ms) +287ms ║║Comparison (enum) on is (string) off = false (1ms) +288ms ║║Condition #82 evaluated false (7ms) +289ms ║║Condition group #78 evaluated false (state did not change) (8ms) +296ms ║║Comparison (enum) on is (string) on = true (1ms) +297ms ║║Condition #83 evaluated true (7ms) +298ms ║║Condition group #77 evaluated true (state did not change) (8ms) +300ms ║║Cancelling statement #138's schedules... +305ms ║║Executed virtual command setVariable (1ms) +337ms ║║Comparison (enum) off is (string) off = true (2ms) +338ms ║║Comparison (enum) on is (string) off = false (1ms) +340ms ║║Comparison (enum) off is (string) off = true (1ms) +342ms ║║Comparison (enum) off is (string) off = true (1ms) +343ms ║║Comparison (enum) on is (string) off = false (1ms) +345ms ║║Comparison (enum) on is (string) off = false (1ms) +349ms ║║Condition #13 evaluated true (40ms) +349ms ║║Condition group #12 evaluated true (state did not change) (42ms) +351ms ║║Cancelling statement #14's schedules... +356ms ║║Calculating (integer) 0 + (integer) 1 >> (integer) 1 +360ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +377ms ║║Executed physical command [Coffee Maker].on() (16ms) +378ms ║║Executed [Coffee Maker].on (17ms) +395ms ║║Executed physical command [Gym Air Conditioner].on() (15ms) +395ms ║║Executed [Gym Air Conditioner].on (16ms) +412ms ║║Executed physical command [Living Room Acccent].on() (16ms) +413ms ║║Executed [Living Room Acccent].on (17ms) +415ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +416ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:33:14 PM EST (in 5.0s) +421ms ║╚Execution stage complete. (214ms) +422ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:33:14 PM EST (in 4.995s) +439ms ╚Event processed successfully (439ms) 12/16/2017, 7:33:00 PM +490ms +1ms ╔Starting piston... (v0.2.100.20171211) +255ms ║╔Subscribing to devices... +337ms ║║Subscribing to Home.routineExecuted.d9fc45eb-656d-4fa9-a343-114def288be0... +352ms ║║Subscribing to Home.:e7485d7911337d06e230038a35981b32:.@WhoIsHome... +490ms ║╚Finished subscribing (240ms) +514ms ║Comparison (string) null executes (string) :ed291579d99773614e4f5cc0f2b74dff: = false (2ms) +525ms ║Comparison (dynamic) Michelle's iPhone changes = false (5ms) +529ms ║Comparison (boolean) false is (boolean) false = true (1ms) +636ms ║Comparison (time) 70381021 is_between (datetime) 1513463460000 .. (datetime) 1513427760000 = true (7ms) +639ms ║Time restriction check passed +652ms ║Comparison (enum) on is (string) off = false (1ms) +662ms ║Comparison (enum) on is (string) on = true (1ms) +667ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (3ms) +706ms ║Comparison (enum) off is (string) off = true (1ms) +709ms ║Comparison (enum) on is (string) off = false (2ms) +711ms ║Comparison (enum) off is (string) off = true (2ms) +713ms ║Comparison (enum) off is (string) off = true (1ms) +714ms ║Comparison (enum) on is (string) off = false (1ms) +716ms ║Comparison (enum) on is (string) off = false (1ms) +750ms ║Comparison (enum) off is (string) on = false (1ms) +756ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +773ms ║Comparison (enum) off is (string) on = false (1ms) +782ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +793ms ║Comparison (enum) unlocked is (string) locked = false (1ms) +802ms ║Comparison (enum) unlocked is (string) unlocked = true (1ms) +805ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +817ms ║Comparison (enum) off is (string) off = true (1ms) +827ms ║Comparison (boolean) false is (boolean) true = false (1ms) +837ms ║Comparison (enum) off is (string) on = false (1ms) +840ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +853ms ╚Piston successfully started (852ms) 12/16/2017, 7:32:58 PM +294ms +199ms ╔Stopping piston... +314ms ╚Piston successfully stopped (115ms) 12/16/2017, 7:32:51 PM +66ms +7ms ╔Starting piston... (v0.2.100.20171211) +797ms ║╔Subscribing to devices... +1027ms ║║Subscribing to Home.routineExecuted.d9fc45eb-656d-4fa9-a343-114def288be0... +1042ms ║║Subscribing to Home.:e7485d7911337d06e230038a35981b32:.@WhoIsHome... +1297ms ║╚Finished subscribing (521ms) +1354ms ║Comparison (string) null executes (string) :ed291579d99773614e4f5cc0f2b74dff: = false (7ms) +1360ms ║Cancelling condition #129's schedules... +1372ms ║Comparison (dynamic) Michelle's iPhone changes = false (4ms) +1377ms ║Comparison (boolean) false is (boolean) false = true (2ms) +1379ms ║Cancelling condition #22's schedules... +1513ms ║Comparison (time) 70372449 is_between (datetime) 1513463460000 .. (datetime) 1513427760000 = true (14ms) +1516ms ║Time restriction check passed +1553ms ║Comparison (enum) on is (string) off = false (2ms) +1582ms ║Comparison (enum) on is (string) on = true (1ms) +1588ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (4ms) +1590ms ║Cancelling condition #101's schedules... +1636ms ║Comparison (enum) off is (string) off = true (1ms) +1638ms ║Comparison (enum) on is (string) off = false (1ms) +1640ms ║Comparison (enum) off is (string) off = true (1ms) +1642ms ║Comparison (enum) off is (string) off = true (1ms) +1645ms ║Comparison (enum) on is (string) off = false (2ms) +1647ms ║Comparison (enum) on is (string) off = false (2ms) +1684ms ║Comparison (enum) off is (string) on = false (2ms) +1691ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (2ms) +1709ms ║Comparison (enum) off is (string) on = false (1ms) +1720ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (2ms) +1721ms ║Cancelling condition #113's schedules... +1738ms ║Comparison (enum) unlocked is (string) locked = false (1ms) +1751ms ║Comparison (enum) unlocked is (string) unlocked = true (1ms) +1757ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +1758ms ║Cancelling condition #119's schedules... +1774ms ║Comparison (enum) off is (string) off = true (1ms) +2099ms ║Comparison (boolean) false is (boolean) true = false (2ms) +2111ms ║Comparison (enum) off is (string) on = false (2ms) +2115ms ║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +2160ms ╚Piston successfully started (2156ms) 12/16/2017, 7:27:59 PM +157ms +0ms ╔Received event [Home].time = 1513470480594 with a delay of -1438ms +297ms ║RunTime Analysis CS > 22ms > PS > 64ms > PE > 212ms > CE +301ms ║Runtime (58745 bytes) successfully initialized in 64ms (v0.2.100.20171211) (299ms) +302ms ║╔Execution stage started +370ms ║║Comparison (enum) off is (string) on = false (2ms) +373ms ║║Condition #18 evaluated false (36ms) +378ms ║║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +380ms ║║Cancelling condition #107's schedules... +383ms ║║Cancelling statement #108's schedules... +400ms ║║Executed virtual command sendPushNotification (13ms) +402ms ║║Condition #107 evaluated true (28ms) +403ms ║║Cancelling condition #11's schedules... +404ms ║║Condition group #11 evaluated true (state changed) (67ms) +419ms ║║Comparison (enum) off is (string) on = false (2ms) +422ms ║║Condition #34 evaluated false (12ms) +423ms ║║Condition group #29 evaluated false (state did not change) (13ms) +427ms ║║Condition #35 evaluated true (2ms) +428ms ║║Condition group #28 evaluated true (state did not change) (4ms) +441ms ║║Comparison (enum) unlocked is (string) locked = false (2ms) +442ms ║║Condition #68 evaluated false (10ms) +443ms ║║Condition group #63 evaluated false (state did not change) (11ms) +452ms ║║Comparison (enum) unlocked is (string) unlocked = true (2ms) +454ms ║║Condition #69 evaluated true (8ms) +455ms ║║Condition group #62 evaluated true (state did not change) (10ms) +468ms ║║Comparison (enum) off is (string) off = true (2ms) +472ms ║║Condition #94 evaluated true (13ms) +477ms ║║Comparison (boolean) false is (boolean) true = false (2ms) +479ms ║║Condition #125 evaluated false (6ms) +480ms ║║Condition group #90 evaluated false (state did not change) (21ms) +491ms ║║Comparison (enum) off is (string) on = false (2ms) +493ms ║║Condition #95 evaluated false (11ms) +497ms ║║Comparison (integer) 5 is_greater_than (integer) 4 = true (1ms) +499ms ║║Cancelling condition #126's schedules... +502ms ║║Cancelling statement #127's schedules... +522ms ║║Executed virtual command sendPushNotification (15ms) +524ms ║║Condition #126 evaluated true (30ms) +525ms ║║Cancelling condition #89's schedules... +526ms ║║Condition group #89 evaluated true (state changed) (45ms) +530ms ║║Cancelling statement #133's schedules... +549ms ║║Executed virtual command sendPushNotification (15ms) +553ms ║╚Execution stage complete. (250ms) +554ms ╚Event processed successfully (555ms) 12/16/2017, 7:27:55 PM +145ms +0ms ╔Received event [Home].time = 1513470476726 with a delay of -1581ms +244ms ║RunTime Analysis CS > 20ms > PS > 66ms > PE > 158ms > CE +248ms ║Runtime (58745 bytes) successfully initialized in 66ms (v0.2.100.20171211) (247ms) +249ms ║╔Execution stage started +308ms ║║Comparison (enum) off is (string) on = false (1ms) +310ms ║║Condition #18 evaluated false (36ms) +314ms ║║Comparison (integer) 4 is_greater_than (integer) 4 = false (1ms) +315ms ║║Condition #107 evaluated false (4ms) +316ms ║║Condition group #11 evaluated false (state did not change) (42ms) +340ms ║║Comparison (enum) off is (string) off = true (1ms) +342ms ║║Comparison (enum) on is (string) off = false (1ms) +344ms ║║Comparison (enum) off is (string) off = true (1ms) +346ms ║║Comparison (enum) off is (string) off = true (1ms) +347ms ║║Comparison (enum) on is (string) off = false (1ms) +349ms ║║Comparison (enum) on is (string) off = false (1ms) +353ms ║║Condition #13 evaluated true (36ms) +354ms ║║Condition group #12 evaluated true (state did not change) (37ms) +356ms ║║Cancelling statement #14's schedules... +361ms ║║Calculating (integer) 4 + (integer) 1 >> (integer) 5 +366ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].setVariable (3ms) +393ms ║║Executed physical command [Coffee Maker].on() (25ms) +394ms ║║Executed [Coffee Maker].on (26ms) +418ms ║║Executed physical command [Gym Air Conditioner].on() (23ms) +419ms ║║Executed [Gym Air Conditioner].on (25ms) +444ms ║║Executed physical command [Living Room Acccent].on() (24ms) +445ms ║║Executed [Living Room Acccent].on (25ms) +448ms ║║Executed virtual command [Coffee Maker, Gym Air Conditioner, Living Room Acccent].wait (0ms) +449ms ║║Requesting a wake up for Sat, Dec 16 2017 @ 7:28:00 PM EST (in 5.0s) +453ms ║╚Execution stage complete. (204ms) +455ms ║Setting up scheduled job for Sat, Dec 16 2017 @ 7:28:00 PM EST (in 4.995s) +466ms ╚Event processed successfully (466ms)