1) Give a description of the problem
The virtual switch either gets stuck in the wrong state or when toggled to the expected state it does not control the group of lights.
2) What is the expected behaviour?
When the virtual switch turns on, a group of lights should turn off, 2 dimmers should change to 20%, the virtual switch is turned back off.
3) What is happening/not happening?
The switch is sometimes stuck in the on position (not sure how). Or when toggled on, the piston is not executed.
**4) Post a Green Snapshot of the piston![image|45x37]
5) Attach logs after turning logging level to Full
9/23/2019, 7:42:11 PM +421ms
+1ms ╔Received event [Movie Mode].switch = off with a delay of 110ms
+90ms ║RunTime Analysis CS > 27ms > PS > 31ms > PE > 32ms > CE
+92ms ║Runtime (37927 bytes) successfully initialized in 31ms (v0.3.10f.20190822) (90ms)
+93ms ║╔Execution stage started
+100ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+102ms ║║Cancelling condition #10’s schedules…
+103ms ║║Condition #10 evaluated false (5ms)
+104ms ║║Cancelling condition #1’s schedules…
+104ms ║║Condition group #1 evaluated false (state changed) (7ms)
+106ms ║╚Execution stage complete. (13ms)
+107ms ╚Event processed successfully (107ms)
9/23/2019, 7:42:11 PM +148ms
+1ms ╔Received event [Home Swarthmore].time = 1569292932400 with a delay of -1252ms
+122ms ║RunTime Analysis CS > 35ms > PS > 48ms > PE > 39ms > CE
+125ms ║Runtime (37943 bytes) successfully initialized in 48ms (v0.3.10f.20190822) (124ms)
+126ms ║╔Execution stage started
+178ms ║║Executed physical command [Movie Mode].off() (36ms)
+179ms ║║Executed [Movie Mode].off (38ms)
+181ms ║╚Execution stage complete. (55ms)
+182ms ╚Event processed successfully (182ms)
9/23/2019, 7:42:06 PM +979ms
+1ms ╔Received event [Movie Mode].switch = on with a delay of 109ms
+106ms ║RunTime Analysis CS > 38ms > PS > 33ms > PE > 35ms > CE
+108ms ║Runtime (37933 bytes) successfully initialized in 33ms (v0.3.10f.20190822) (106ms)
+109ms ║╔Execution stage started
+117ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+118ms ║║Cancelling condition #10’s schedules…
+119ms ║║Condition #10 evaluated true (5ms)
+120ms ║║Cancelling condition #1’s schedules…
+121ms ║║Condition group #1 evaluated true (state changed) (8ms)
+123ms ║║Cancelling statement #6’s schedules…
+332ms ║║Executed physical command [Den].setLevel([20]) (202ms)
+333ms ║║Executed [Den].setLevel (204ms)
+342ms ║║Skipped execution of physical command [Rocker].setLevel([20]) because it would make no change to the device. (6ms)
+343ms ║║Executed [Rocker].setLevel (8ms)
+345ms ║║Cancelling statement #2’s schedules…
+354ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (3ms)
+355ms ║║Executed [Above Cabinets].off (5ms)
+362ms ║║Skipped execution of physical command [Dining Table].off([]) because it would make no change to the device. (4ms)
+363ms ║║Executed [Dining Table].off (5ms)
+369ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (2ms)
+369ms ║║Executed [Hall].off (4ms)
+376ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (2ms)
+377ms ║║Executed [John’s].off (4ms)
+383ms ║║Skipped execution of physical command [Kitchen].off([]) because it would make no change to the device. (3ms)
+384ms ║║Executed [Kitchen].off (5ms)
+390ms ║║Skipped execution of physical command [Kitchen Cabinets].off([]) because it would make no change to the device. (3ms)
+391ms ║║Executed [Kitchen Cabinets].off (5ms)
+397ms ║║Skipped execution of physical command [Living Room].off([]) because it would make no change to the device. (3ms)
+398ms ║║Executed [Living Room].off (5ms)
+404ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (3ms)
+405ms ║║Executed [Living Room Fan].off (5ms)
+411ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (2ms)
+412ms ║║Executed [Rebecca’s].off (4ms)
+414ms ║║Cancelling statement #14’s schedules…
+419ms ║║Executed virtual command [Movie Mode].wait (0ms)
+420ms ║║Requesting a wake up for Mon, Sep 23 2019 @ 7:42:12 PM PDT (in 5.0s)
+424ms ║╚Execution stage complete. (315ms)
+426ms ║Setting up scheduled job for Mon, Sep 23 2019 @ 7:42:12 PM PDT (in 4.996s)
+434ms ╚Event processed successfully (434ms)
9/23/2019, 7:42:02 PM +627ms
+2ms ╔Received event [Movie Mode].switch = off with a delay of 110ms
+92ms ║RunTime Analysis CS > 25ms > PS > 32ms > PE > 34ms > CE
+94ms ║Runtime (37933 bytes) successfully initialized in 32ms (v0.3.10f.20190822) (92ms)
+95ms ║╔Execution stage started
+102ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+103ms ║║Condition #10 evaluated false (4ms)
+104ms ║║Condition group #1 evaluated false (state did not change) (5ms)
+106ms ║╚Execution stage complete. (11ms)
+107ms ╚Event processed successfully (107ms)
9/23/2019, 7:22:59 PM +106ms
+1ms ╔Received event [Home Swarthmore].time/recovery = 1569291779106 with a delay of 0ms
+93ms ║RunTime Analysis CS > 26ms > PS > 34ms > PE > 33ms > CE
+95ms ║Runtime (37953 bytes) successfully initialized in 34ms (v0.3.10f.20190822) (93ms)
+96ms ║╔Execution stage started
+97ms ║╚Execution stage complete. (1ms)
+98ms ╚Event processed successfully (98ms)
9/23/2019, 7:22:20 PM +959ms
+2ms ╔Received event [Movie Mode].switch = off with a delay of 120ms
+100ms ║RunTime Analysis CS > 29ms > PS > 36ms > PE > 35ms > CE
+102ms ║Runtime (37934 bytes) successfully initialized in 36ms (v0.3.10f.20190822) (99ms)
+103ms ║╔Execution stage started
+110ms ║║Comparison (enum) off changes_to (string) on = false (0ms)
+112ms ║║Condition #10 evaluated false (5ms)
+113ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+115ms ║╚Execution stage complete. (11ms)
+116ms ╚Event processed successfully (115ms)
9/23/2019, 7:22:11 PM +131ms
+1ms ╔Received event [Home Swarthmore].time/recovery = 1569291731130 with a delay of 1ms
+151ms ║RunTime Analysis CS > 50ms > PS > 63ms > PE > 37ms > CE
+154ms ║Runtime (37954 bytes) successfully initialized in 63ms (v0.3.10f.20190822) (151ms)
+155ms ║╔Execution stage started
+156ms ║╚Execution stage complete. (1ms)
+158ms ╚Event processed successfully (157ms)
9/23/2019, 7:16:50 PM +386ms
+2ms ╔Received event [Movie Mode].switch = off with a delay of 112ms
+99ms ║RunTime Analysis CS > 27ms > PS > 38ms > PE > 33ms > CE
+101ms ║Runtime (37927 bytes) successfully initialized in 38ms (v0.3.10f.20190822) (98ms)
+102ms ║╔Execution stage started
+109ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+111ms ║║Cancelling condition #10’s schedules…
+112ms ║║Condition #10 evaluated false (5ms)
+112ms ║║Cancelling condition #1’s schedules…
+113ms ║║Condition group #1 evaluated false (state changed) (7ms)
+115ms ║╚Execution stage complete. (13ms)
+116ms ╚Event processed successfully (116ms)
9/23/2019, 7:16:50 PM +137ms
+1ms ╔Received event [Home Swarthmore].time = 1569291411521 with a delay of -1384ms
+98ms ║RunTime Analysis CS > 27ms > PS > 40ms > PE > 31ms > CE
+101ms ║Runtime (37942 bytes) successfully initialized in 40ms (v0.3.10f.20190822) (100ms)
+102ms ║╔Execution stage started
+149ms ║║Executed physical command [Movie Mode].off() (32ms)
+150ms ║║Executed [Movie Mode].off (34ms)
+152ms ║╚Execution stage complete. (50ms)
+154ms ╚Event processed successfully (153ms)
9/23/2019, 7:16:45 PM +360ms
+1ms ╔Received event [Movie Mode].switch = on with a delay of 110ms
+95ms ║RunTime Analysis CS > 27ms > PS > 34ms > PE > 35ms > CE
+97ms ║Runtime (37932 bytes) successfully initialized in 34ms (v0.3.10f.20190822) (95ms)
+98ms ║╔Execution stage started
+105ms ║║Comparison (enum) on changes_to (string) on = true (0ms)
+107ms ║║Cancelling condition #10’s schedules…
+108ms ║║Condition #10 evaluated true (6ms)
+109ms ║║Cancelling condition #1’s schedules…
+110ms ║║Condition group #1 evaluated true (state changed) (7ms)
+112ms ║║Cancelling statement #6’s schedules…
+1040ms ║║Executed physical command [Den].setLevel([20]) (923ms)
+1041ms ║║Executed [Den].setLevel (925ms)
+1050ms ║║Skipped execution of physical command [Rocker].setLevel([20]) because it would make no change to the device. (5ms)
+1051ms ║║Executed [Rocker].setLevel (7ms)
+1053ms ║║Cancelling statement #2’s schedules…
+1061ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (2ms)
+1062ms ║║Executed [Above Cabinets].off (4ms)
+1101ms ║║Executed physical command [Dining Table].off() (36ms)
+1102ms ║║Executed [Dining Table].off (38ms)
+1108ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (3ms)
+1109ms ║║Executed [Hall].off (5ms)
+1116ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (4ms)
+1117ms ║║Executed [John’s].off (6ms)
+1123ms ║║Skipped execution of physical command [Kitchen].off([]) because it would make no change to the device. (2ms)
+1124ms ║║Executed [Kitchen].off (4ms)
+1130ms ║║Skipped execution of physical command [Kitchen Cabinets].off([]) because it would make no change to the device. (2ms)
+1130ms ║║Executed [Kitchen Cabinets].off (4ms)
+1137ms ║║Skipped execution of physical command [Living Room].off([]) because it would make no change to the device. (2ms)
+1137ms ║║Executed [Living Room].off (4ms)
+1144ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (3ms)
+1145ms ║║Executed [Living Room Fan].off (5ms)
+1151ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (3ms)
+1152ms ║║Executed [Rebecca’s].off (5ms)
+1154ms ║║Cancelling statement #14’s schedules…
+1159ms ║║Executed virtual command [Movie Mode].wait (1ms)
+1160ms ║║Requesting a wake up for Mon, Sep 23 2019 @ 7:16:51 PM PDT (in 5.0s)
+1164ms ║╚Execution stage complete. (1066ms)
+1166ms ║Setting up scheduled job for Mon, Sep 23 2019 @ 7:16:51 PM PDT (in 4.996s)
+1174ms ╚Event processed successfully (1174ms)
9/23/2019, 7:10:18 PM +479ms
+2ms ╔Received event [Movie Mode].switch = off with a delay of 109ms
+97ms ║RunTime Analysis CS > 27ms > PS > 35ms > PE > 35ms > CE
+100ms ║Runtime (37927 bytes) successfully initialized in 35ms (v0.3.10f.20190822) (97ms)
+101ms ║╔Execution stage started
+108ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+110ms ║║Cancelling condition #10’s schedules…
+111ms ║║Condition #10 evaluated false (5ms)
+112ms ║║Cancelling condition #1’s schedules…
+112ms ║║Condition group #1 evaluated false (state changed) (7ms)
+114ms ║╚Execution stage complete. (14ms)
+116ms ╚Event processed successfully (115ms)
9/23/2019, 7:10:18 PM +155ms
+1ms ╔Received event [Home Swarthmore].time = 1569291019975 with a delay of -1820ms
+162ms ║RunTime Analysis CS > 65ms > PS > 63ms > PE > 33ms > CE
+164ms ║Runtime (37943 bytes) successfully initialized in 63ms (v0.3.10f.20190822) (163ms)
+165ms ║╔Execution stage started
+227ms ║║Executed physical command [Movie Mode].off() (46ms)
+228ms ║║Executed [Movie Mode].off (48ms)
+230ms ║╚Execution stage complete. (65ms)
+231ms ╚Event processed successfully (231ms)
9/23/2019, 7:10:14 PM +49ms
+2ms ╔Received event [Movie Mode].switch = on with a delay of 112ms
+91ms ║RunTime Analysis CS > 25ms > PS > 33ms > PE > 33ms > CE
+94ms ║Runtime (37932 bytes) successfully initialized in 33ms (v0.3.10f.20190822) (92ms)
+95ms ║╔Execution stage started
+102ms ║║Comparison (enum) on changes_to (string) on = true (0ms)
+103ms ║║Cancelling condition #10’s schedules…
+104ms ║║Condition #10 evaluated true (5ms)
+105ms ║║Cancelling condition #1’s schedules…
+106ms ║║Condition group #1 evaluated true (state changed) (8ms)
+108ms ║║Cancelling statement #6’s schedules…
+118ms ║║Skipped execution of physical command [Den].setLevel([20]) because it would make no change to the device. (5ms)
+119ms ║║Executed [Den].setLevel (7ms)
+128ms ║║Skipped execution of physical command [Rocker].setLevel([20]) because it would make no change to the device. (6ms)
+129ms ║║Executed [Rocker].setLevel (7ms)
+132ms ║║Cancelling statement #2’s schedules…
+140ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (3ms)
+141ms ║║Executed [Above Cabinets].off (4ms)
+147ms ║║Skipped execution of physical command [Dining Table].off([]) because it would make no change to the device. (3ms)
+148ms ║║Executed [Dining Table].off (4ms)
+153ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (2ms)
+154ms ║║Executed [Hall].off (4ms)
+160ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (3ms)
+161ms ║║Executed [John’s].off (4ms)
+887ms ║║Executed physical command [Kitchen].off() (723ms)
+888ms ║║Executed [Kitchen].off (725ms)
+895ms ║║Skipped execution of physical command [Kitchen Cabinets].off([]) because it would make no change to the device. (4ms)
+896ms ║║Executed [Kitchen Cabinets].off (5ms)
+902ms ║║Skipped execution of physical command [Living Room].off([]) because it would make no change to the device. (3ms)
+903ms ║║Executed [Living Room].off (5ms)
+909ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (3ms)
+910ms ║║Executed [Living Room Fan].off (4ms)
+916ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (2ms)
+917ms ║║Executed [Rebecca’s].off (4ms)
+919ms ║║Cancelling statement #14’s schedules…
+924ms ║║Executed virtual command [Movie Mode].wait (0ms)
+925ms ║║Requesting a wake up for Mon, Sep 23 2019 @ 7:10:19 PM PDT (in 5.0s)
+929ms ║╚Execution stage complete. (835ms)
+930ms ║Setting up scheduled job for Mon, Sep 23 2019 @ 7:10:19 PM PDT (in 4.996s)
+940ms ╚Event processed successfully (940ms)
9/23/2019, 6:26:06 PM +498ms
+1ms ╔Received event [Movie Mode].switch = off with a delay of 113ms
+91ms ║RunTime Analysis CS > 25ms > PS > 32ms > PE > 33ms > CE
+93ms ║Runtime (37927 bytes) successfully initialized in 32ms (v0.3.10f.20190822) (91ms)
+94ms ║╔Execution stage started
+101ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+102ms ║║Cancelling condition #10’s schedules…
+103ms ║║Condition #10 evaluated false (5ms)
+104ms ║║Cancelling condition #1’s schedules…
+105ms ║║Condition group #1 evaluated false (state changed) (7ms)
+107ms ║╚Execution stage complete. (13ms)
+108ms ╚Event processed successfully (108ms)
9/23/2019, 6:26:06 PM +124ms
+0ms ╔Received event [Home Swarthmore].time = 1569288367080 with a delay of -956ms
+98ms ║RunTime Analysis CS > 29ms > PS > 36ms > PE > 32ms > CE
+100ms ║Runtime (37941 bytes) successfully initialized in 36ms (v0.3.10f.20190822) (99ms)
+101ms ║╔Execution stage started
+275ms ║║Executed physical command [Movie Mode].off() (159ms)
+276ms ║║Executed [Movie Mode].off (161ms)
+279ms ║╚Execution stage complete. (177ms)
+280ms ╚Event processed successfully (279ms)
9/23/2019, 6:26:00 PM +779ms
+2ms ╔Received event [Movie Mode].switch = on with a delay of 117ms
+95ms ║RunTime Analysis CS > 29ms > PS > 33ms > PE > 33ms > CE
+98ms ║Runtime (37932 bytes) successfully initialized in 33ms (v0.3.10f.20190822) (96ms)
+99ms ║╔Execution stage started
+106ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+107ms ║║Cancelling condition #10’s schedules…
+108ms ║║Condition #10 evaluated true (5ms)
+109ms ║║Cancelling condition #1’s schedules…
+110ms ║║Condition group #1 evaluated true (state changed) (7ms)
+112ms ║║Cancelling statement #6’s schedules…
+873ms ║║Executed physical command [Den].setLevel([20]) (755ms)
+874ms ║║Executed [Den].setLevel (757ms)
+1087ms ║║Executed physical command [Rocker].setLevel([20]) (209ms)
+1088ms ║║Executed [Rocker].setLevel (211ms)
+1090ms ║║Cancelling statement #2’s schedules…
+1098ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (2ms)
+1099ms ║║Executed [Above Cabinets].off (4ms)
+1158ms ║║Executed physical command [Dining Table].off() (55ms)
+1159ms ║║Executed [Dining Table].off (57ms)
+1166ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (4ms)
+1167ms ║║Executed [Hall].off (6ms)
+1174ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (2ms)
+1175ms ║║Executed [John’s].off (4ms)
+1181ms ║║Skipped execution of physical command [Kitchen].off([]) because it would make no change to the device. (3ms)
+1182ms ║║Executed [Kitchen].off (5ms)
+1229ms ║║Executed physical command [Kitchen Cabinets].off() (44ms)
+1230ms ║║Executed [Kitchen Cabinets].off (45ms)
+1275ms ║║Executed physical command [Living Room].off() (42ms)
+1276ms ║║Executed [Living Room].off (43ms)
+1283ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (3ms)
+1284ms ║║Executed [Living Room Fan].off (5ms)
+1291ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (3ms)
+1292ms ║║Executed [Rebecca’s].off (4ms)
+1294ms ║║Cancelling statement #14’s schedules…
+1299ms ║║Executed virtual command [Movie Mode].wait (1ms)
+1300ms ║║Requesting a wake up for Mon, Sep 23 2019 @ 6:26:07 PM PDT (in 5.0s)
+1304ms ║╚Execution stage complete. (1205ms)
+1306ms ║Setting up scheduled job for Mon, Sep 23 2019 @ 6:26:07 PM PDT (in 4.996s)
+1314ms ╚Event processed successfully (1314ms)
9/22/2019, 7:30:12 PM +483ms
+1ms ╔Received event [Movie Mode].switch = off with a delay of 112ms
+96ms ║RunTime Analysis CS > 28ms > PS > 35ms > PE > 34ms > CE
+99ms ║Runtime (37927 bytes) successfully initialized in 35ms (v0.3.10f.20190822) (96ms)
+100ms ║╔Execution stage started
+107ms ║║Comparison (enum) off changes_to (string) on = false (0ms)
+108ms ║║Cancelling condition #10’s schedules…
+109ms ║║Condition #10 evaluated false (5ms)
+110ms ║║Cancelling condition #1’s schedules…
+111ms ║║Condition group #1 evaluated false (state changed) (8ms)
+113ms ║╚Execution stage complete. (14ms)
+114ms ╚Event processed successfully (115ms)
9/22/2019, 7:30:11 PM +258ms
+0ms ╔Received event [Home Swarthmore].time = 1569205812633 with a delay of -1376ms
+380ms ║RunTime Analysis CS > 126ms > PS > 220ms > PE > 35ms > CE
+383ms ║Runtime (37946 bytes) successfully initialized in 220ms (v0.3.10f.20190822) (381ms)
+384ms ║╔Execution stage started
+1127ms ║║Executed physical command [Movie Mode].off() (724ms)
+1128ms ║║Executed [Movie Mode].off (726ms)
+1130ms ║╚Execution stage complete. (747ms)
+1131ms ╚Event processed successfully (1131ms)
9/22/2019, 7:30:07 PM +372ms
+1ms ╔Received event [Movie Mode].switch = on with a delay of 110ms
+94ms ║RunTime Analysis CS > 27ms > PS > 33ms > PE > 34ms > CE
+97ms ║Runtime (37932 bytes) successfully initialized in 33ms (v0.3.10f.20190822) (95ms)
+98ms ║╔Execution stage started
+105ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+106ms ║║Cancelling condition #10’s schedules…
+107ms ║║Condition #10 evaluated true (5ms)
+108ms ║║Cancelling condition #1’s schedules…
+109ms ║║Condition group #1 evaluated true (state changed) (7ms)
+112ms ║║Cancelling statement #6’s schedules…
+123ms ║║Skipped execution of physical command [Den].setLevel([20]) because it would make no change to the device. (6ms)
+124ms ║║Executed [Den].setLevel (8ms)
+132ms ║║Skipped execution of physical command [Rocker].setLevel([20]) because it would make no change to the device. (5ms)
+133ms ║║Executed [Rocker].setLevel (7ms)
+136ms ║║Cancelling statement #2’s schedules…
+144ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (3ms)
+145ms ║║Executed [Above Cabinets].off (4ms)
+150ms ║║Skipped execution of physical command [Dining Table].off([]) because it would make no change to the device. (2ms)
+151ms ║║Executed [Dining Table].off (4ms)
+158ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (2ms)
+158ms ║║Executed [Hall].off (4ms)
+164ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (2ms)
+165ms ║║Executed [John’s].off (4ms)
+224ms ║║Executed physical command [Kitchen].off() (55ms)
+225ms ║║Executed [Kitchen].off (57ms)
+231ms ║║Skipped execution of physical command [Kitchen Cabinets].off([]) because it would make no change to the device. (2ms)
+232ms ║║Executed [Kitchen Cabinets].off (4ms)
+238ms ║║Skipped execution of physical command [Living Room].off([]) because it would make no change to the device. (3ms)
+239ms ║║Executed [Living Room].off (5ms)
+245ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (2ms)
+245ms ║║Executed [Living Room Fan].off (4ms)
+251ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (2ms)
+252ms ║║Executed [Rebecca’s].off (4ms)
+254ms ║║Cancelling statement #14’s schedules…
+259ms ║║Executed virtual command [Movie Mode].wait (0ms)
+261ms ║║Requesting a wake up for Sun, Sep 22 2019 @ 7:30:12 PM PDT (in 5.0s)
+265ms ║╚Execution stage complete. (167ms)
+266ms ║Setting up scheduled job for Sun, Sep 22 2019 @ 7:30:12 PM PDT (in 4.996s)
+275ms ╚Event processed successfully (275ms)
9/22/2019, 5:03:50 PM +403ms
+1ms ╔Received event [Movie Mode].switch = off with a delay of 105ms
+87ms ║RunTime Analysis CS > 25ms > PS > 31ms > PE > 32ms > CE
+89ms ║Runtime (37927 bytes) successfully initialized in 31ms (v0.3.10f.20190822) (87ms)
+90ms ║╔Execution stage started
+97ms ║║Comparison (enum) off changes_to (string) on = false (0ms)
+99ms ║║Cancelling condition #10’s schedules…
+100ms ║║Condition #10 evaluated false (6ms)
+101ms ║║Cancelling condition #1’s schedules…
+102ms ║║Condition group #1 evaluated false (state changed) (7ms)
+104ms ║╚Execution stage complete. (13ms)
+105ms ╚Event processed successfully (104ms)
9/22/2019, 5:03:50 PM +131ms
+1ms ╔Received event [Home Swarthmore].time = 1569197031346 with a delay of -1215ms
+121ms ║RunTime Analysis CS > 30ms > PS > 49ms > PE > 42ms > CE
+124ms ║Runtime (37943 bytes) successfully initialized in 49ms (v0.3.10f.20190822) (123ms)
+125ms ║╔Execution stage started
+179ms ║║Executed physical command [Movie Mode].off() (32ms)
+181ms ║║Executed [Movie Mode].off (34ms)
+183ms ║╚Execution stage complete. (58ms)
+185ms ╚Event processed successfully (184ms)
9/22/2019, 5:03:45 PM +434ms
+2ms ╔Received event [Movie Mode].switch = on with a delay of 109ms
+107ms ║RunTime Analysis CS > 26ms > PS > 32ms > PE > 48ms > CE
+109ms ║Runtime (37933 bytes) successfully initialized in 32ms (v0.3.10f.20190822) (106ms)
+110ms ║╔Execution stage started
+117ms ║║Comparison (enum) on changes_to (string) on = true (0ms)
+119ms ║║Cancelling condition #10’s schedules…
+120ms ║║Condition #10 evaluated true (6ms)
+121ms ║║Cancelling condition #1’s schedules…
+122ms ║║Condition group #1 evaluated true (state changed) (8ms)
+124ms ║║Cancelling statement #6’s schedules…
+134ms ║║Skipped execution of physical command [Den].setLevel([20]) because it would make no change to the device. (5ms)
+135ms ║║Executed [Den].setLevel (7ms)
+144ms ║║Skipped execution of physical command [Rocker].setLevel([20]) because it would make no change to the device. (6ms)
+145ms ║║Executed [Rocker].setLevel (8ms)
+147ms ║║Cancelling statement #2’s schedules…
+156ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (3ms)
+157ms ║║Executed [Above Cabinets].off (4ms)
+163ms ║║Skipped execution of physical command [Dining Table].off([]) because it would make no change to the device. (3ms)
+164ms ║║Executed [Dining Table].off (5ms)
+170ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (3ms)
+171ms ║║Executed [Hall].off (5ms)
+177ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (2ms)
+178ms ║║Executed [John’s].off (4ms)
+875ms ║║Executed physical command [Kitchen].off() (693ms)
+876ms ║║Executed [Kitchen].off (695ms)
+882ms ║║Skipped execution of physical command [Kitchen Cabinets].off([]) because it would make no change to the device. (3ms)
+883ms ║║Executed [Kitchen Cabinets].off (4ms)
+889ms ║║Skipped execution of physical command [Living Room].off([]) because it would make no change to the device. (3ms)
+890ms ║║Executed [Living Room].off (5ms)
+896ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (3ms)
+897ms ║║Executed [Living Room Fan].off (5ms)
+903ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (2ms)
+903ms ║║Executed [Rebecca’s].off (4ms)
+906ms ║║Cancelling statement #14’s schedules…
+910ms ║║Executed virtual command [Movie Mode].wait (0ms)
+912ms ║║Requesting a wake up for Sun, Sep 22 2019 @ 5:03:51 PM PDT (in 5.0s)
+915ms ║╚Execution stage complete. (805ms)
+917ms ║Setting up scheduled job for Sun, Sep 22 2019 @ 5:03:51 PM PDT (in 4.996s)
+926ms ╚Event processed successfully (926ms)
9/22/2019, 4:45:20 PM +99ms
+1ms ╔Received event [Movie Mode].switch = off with a delay of 115ms
+112ms ║RunTime Analysis CS > 36ms > PS > 44ms > PE > 33ms > CE
+115ms ║Runtime (37928 bytes) successfully initialized in 44ms (v0.3.10f.20190822) (113ms)
+116ms ║╔Execution stage started
+123ms ║║Comparison (enum) off changes_to (string) on = false (0ms)
+124ms ║║Cancelling condition #10’s schedules…
+125ms ║║Condition #10 evaluated false (5ms)
+126ms ║║Cancelling condition #1’s schedules…
+127ms ║║Condition group #1 evaluated false (state changed) (8ms)
+129ms ║╚Execution stage complete. (14ms)
+130ms ╚Event processed successfully (130ms)
9/22/2019, 4:45:19 PM +654ms
+1ms ╔Received event [Home Swarthmore].time = 1569195920437 with a delay of -783ms
+287ms ║RunTime Analysis CS > 103ms > PS > 145ms > PE > 39ms > CE
+290ms ║Runtime (37945 bytes) successfully initialized in 145ms (v0.3.10f.20190822) (289ms)
+291ms ║╔Execution stage started
+343ms ║║Executed physical command [Movie Mode].off() (36ms)
+344ms ║║Executed [Movie Mode].off (39ms)
+346ms ║╚Execution stage complete. (56ms)
+347ms ╚Event processed successfully (347ms)
9/22/2019, 4:45:14 PM +300ms
+2ms ╔Received event [Movie Mode].switch = on with a delay of 110ms
+99ms ║RunTime Analysis CS > 31ms > PS > 34ms > PE > 34ms > CE
+101ms ║Runtime (37932 bytes) successfully initialized in 34ms (v0.3.10f.20190822) (99ms)
+102ms ║╔Execution stage started
+110ms ║║Comparison (enum) on changes_to (string) on = true (0ms)
+111ms ║║Cancelling condition #10’s schedules…
+112ms ║║Condition #10 evaluated true (5ms)
+113ms ║║Cancelling condition #1’s schedules…
+114ms ║║Condition group #1 evaluated true (state changed) (8ms)
+116ms ║║Cancelling statement #6’s schedules…
+1007ms ║║Executed physical command [Den].setLevel([20]) (886ms)
+1008ms ║║Executed [Den].setLevel (887ms)
+1059ms ║║Executed physical command [Rocker].setLevel([20]) (48ms)
+1060ms ║║Executed [Rocker].setLevel (50ms)
+1062ms ║║Cancelling statement #2’s schedules…
+1071ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (3ms)
+1072ms ║║Executed [Above Cabinets].off (5ms)
+1078ms ║║Skipped execution of physical command [Dining Table].off([]) because it would make no change to the device. (3ms)
+1079ms ║║Executed [Dining Table].off (5ms)
+1085ms ║║Skipped execution of physical command [Hall].off([]) because it would make no change to the device. (3ms)
+1086ms ║║Executed [Hall].off (5ms)
+1092ms ║║Skipped execution of physical command [John’s].off([]) because it would make no change to the device. (3ms)
+1093ms ║║Executed [John’s].off (5ms)
+1099ms ║║Skipped execution of physical command [Kitchen].off([]) because it would make no change to the device. (3ms)
+1100ms ║║Executed [Kitchen].off (4ms)
+1106ms ║║Skipped execution of physical command [Kitchen Cabinets].off([]) because it would make no change to the device. (3ms)
+1107ms ║║Executed [Kitchen Cabinets].off (4ms)
+1114ms ║║Skipped execution of physical command [Living Room].off([]) because it would make no change to the device. (3ms)
+1114ms ║║Executed [Living Room].off (5ms)
+1121ms ║║Skipped execution of physical command [Living Room Fan].off([]) because it would make no change to the device. (3ms)
+1122ms ║║Executed [Living Room Fan].off (5ms)
+1128ms ║║Skipped execution of physical command [Rebecca’s].off([]) because it would make no change to the device. (2ms)
+1128ms ║║Executed [Rebecca’s].off (3ms)
+1131ms ║║Cancelling statement #14’s schedules…
+1136ms ║║Executed virtual command [Movie Mode].wait (0ms)
+1137ms ║║Requesting a wake up for Sun, Sep 22 2019 @ 4:45:20 PM PDT (in 5.0s)
+1141ms ║╚Execution stage complete. (1039ms)
+1142ms ║Setting up scheduled job for Sun, Sep 22 2019 @ 4:45:20 PM PDT (in 4.995s)
+1151ms ╚Event processed successfully (1151ms)
9/22/2019, 1:13:54 PM +387ms
+2ms ╔Received event [Movie Mode].switch = off with a delay of 107ms
+194ms ║RunTime Analysis CS > 26ms > PS > 32ms > PE > 135ms > CE
+196ms ║Runtime (37929 bytes) successfully initialized in 32ms (v0.3.10f.20190822) (194ms)
+197ms ║╔Execution stage started
+204ms ║║Comparison (enum) off changes_to (string) on = false (0ms)
+206ms ║║Cancelling condition #10’s schedules…
+207ms ║║Condition #10 evaluated false (5ms)
+208ms ║║Cancelling condition #1’s schedules…
+209ms ║║Condition group #1 evaluated false (state changed) (7ms)
+210ms ║╚Execution stage complete. (13ms)
+212ms ╚Event processed successfully (211ms)
9/22/2019, 1:13:54 PM +146ms
+0ms ╔Received event [Home Swarthmore].time = 1569183235018 with a delay of -873ms
+96ms ║RunTime Analysis CS > 28ms > PS > 35ms > PE > 34ms > CE
+98ms ║Runtime (37941 bytes) successfully initialized in 35ms (v0.3.10f.20190822) (97ms)
+99ms ║╔Execution stage started
+147ms ║║Executed physical command [Movie Mode].off() (32ms)
+148ms ║║Executed [Movie Mode].off (34ms)
+150ms ║╚Execution stage complete. (51ms)
+151ms ╚Event processed successfully (151ms)
9/22/2019, 1:13:49 PM +595ms
+2ms ╔Received event [Movie Mode].switch = on with a delay of 110ms
+87ms ║RunTime Analysis CS > 24ms > PS > 31ms > PE > 31ms > CE
+89ms ║Runtime (37932 bytes) successfully initialized in 31ms (v0.3.10f.20190822) (86ms)
+89ms ║╔Execution stage started
+97ms ║║Comparison (enum) on changes_to (string) on = true (0ms)
+98ms ║║Cancelling condition #10’s schedules…
+99ms ║║Condition #10 evaluated true (5ms)
+100ms ║║Cancelling condition #1’s schedules…
+101ms ║║Condition group #1 evaluated true (state changed) (7ms)
+103ms ║║Cancelling statement #6’s schedules…
+300ms ║║Executed physical command [Den].setLevel([20]) (192ms)
+301ms ║║Executed [Den].setLevel (194ms)
+349ms ║║Executed physical command [Rocker].setLevel([20]) (44ms)
+350ms ║║Executed [Rocker].setLevel (45ms)
+352ms ║║Cancelling statement #2’s schedules…
+360ms ║║Skipped execution of physical command [Above Cabinets].off([]) because it would make no change to the device. (2ms)
+361ms ║║Executed [Above Cabinets].off (4ms)
+367ms ║║Skipped execution of physical command [Dining Table].off([]) because it would make no change to the device. (3ms)