Why does this piston continue to fire?


#1

1) Give a description of the problem
This piston is called from an alarm piston that is set to fire one time in the morning. This piston is the problem and re-fires from the beginning every 15 minutes and re-runs the ‘get web request’ function.

2) What is the expected behaviour?
I would like the piston to fire only once instead of firing multiple times.

3) What is happening/not happening?
What is happening is that the ‘get web request’ fires every 15 minutes. What I’d like to happen is for the ‘web get request’ to only fire once.

**4) Post a Green Snapshot of the piston

5) Attach logs after turning logging level to Full
3/27/2020, 11:34:59 AM +156ms
+0ms ╔Received event [Home].time = 1585323300879 with a delay of -1723ms
+907ms ║RunTime Analysis CS > 20ms > PS > 830ms > PE > 57ms > CE
+910ms ║Runtime (44267 bytes) successfully initialized in 830ms (v0.3.10c.20190522) (908ms)
+911ms ║╔Execution stage started
+931ms ║║Executed physical command [Book Lamp].setLevel([51]) (11ms)
+936ms ║║Fast executing schedules, waiting for 814ms to sync up
+1765ms ║║Executed physical command [Lily Princess Lamp].setLevel([51]) (10ms)
+1772ms ║║Fast executing schedules, waiting for 4ms to sync up
+1791ms ║║Executed physical command [Lily’s Rainbow].setLevel([51]) (10ms)
+1799ms ║╚Execution stage complete. (889ms)
+1842ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:35:15 AM EDT (in 14.912s), with 30 more jobs pending
+1856ms ╚Event processed successfully (1856ms)
3/27/2020, 11:34:44 AM +509ms
+1ms ╔Received event [Home].time = 1585323285879 with a delay of -1371ms
+315ms ║RunTime Analysis CS > 21ms > PS > 239ms > PE > 55ms > CE
+317ms ║Runtime (44267 bytes) successfully initialized in 239ms (v0.3.10c.20190522) (316ms)
+319ms ║╔Execution stage started
+335ms ║║Executed physical command [Book Lamp].setLevel([50]) (8ms)
+342ms ║║Fast executing schedules, waiting for 1056ms to sync up
+1410ms ║║Executed physical command [Lily Princess Lamp].setLevel([50]) (8ms)
+1416ms ║║Fast executing schedules, waiting for 8ms to sync up
+1437ms ║║Executed physical command [Lily’s Rainbow].setLevel([50]) (8ms)
+1444ms ║╚Execution stage complete. (1126ms)
+1451ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:35:00 AM EDT (in 14.919s), with 33 more jobs pending
+1461ms ╚Event processed successfully (1460ms)
3/27/2020, 11:34:29 AM +170ms
+1ms ╔Received event [Home].time = 1585323270879 with a delay of -1709ms
+504ms ║RunTime Analysis CS > 92ms > PS > 317ms > PE > 94ms > CE
+507ms ║Runtime (44267 bytes) successfully initialized in 317ms (v0.3.10c.20190522) (504ms)
+508ms ║╔Execution stage started
+529ms ║║Executed physical command [Book Lamp].setLevel([49]) (10ms)
+535ms ║║Fast executing schedules, waiting for 1201ms to sync up
+1750ms ║║Executed physical command [Lily Princess Lamp].setLevel([49]) (10ms)
+1756ms ║║Fast executing schedules, waiting for 7ms to sync up
+1775ms ║║Executed physical command [Lily’s Rainbow].setLevel([49]) (8ms)
+1783ms ║╚Execution stage complete. (1275ms)
+1794ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:34:45 AM EDT (in 14.916s), with 36 more jobs pending
+1804ms ╚Event processed successfully (1804ms)
3/27/2020, 11:34:14 AM +185ms
+0ms ╔Received event [Home].time = 1585323255878 with a delay of -1694ms
+350ms ║RunTime Analysis CS > 25ms > PS > 263ms > PE > 63ms > CE
+353ms ║Runtime (44267 bytes) successfully initialized in 263ms (v0.3.10c.20190522) (352ms)
+354ms ║╔Execution stage started
+373ms ║║Executed physical command [Book Lamp].setLevel([48]) (9ms)
+381ms ║║Fast executing schedules, waiting for 1341ms to sync up
+1734ms ║║Executed physical command [Lily Princess Lamp].setLevel([48]) (8ms)
+1740ms ║║Fast executing schedules, waiting for 6ms to sync up
+1758ms ║║Executed physical command [Lily’s Rainbow].setLevel([48]) (8ms)
+1768ms ║╚Execution stage complete. (1415ms)
+1781ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:34:30 AM EDT (in 14.914s), with 39 more jobs pending
+1790ms ╚Event processed successfully (1791ms)
3/27/2020, 11:34:00 AM +134ms
+1ms ╔Received event [Home].time = 1585323240878 with a delay of -744ms
+388ms ║RunTime Analysis CS > 23ms > PS > 310ms > PE > 54ms > CE
+391ms ║Runtime (44266 bytes) successfully initialized in 310ms (v0.3.10c.20190522) (389ms)
+392ms ║╔Execution stage started
+411ms ║║Executed physical command [Book Lamp].setLevel([47]) (9ms)
+417ms ║║Fast executing schedules, waiting for 355ms to sync up
+784ms ║║Executed physical command [Lily Princess Lamp].setLevel([47]) (8ms)
+791ms ║║Fast executing schedules, waiting for 6ms to sync up
+811ms ║║Executed physical command [Lily’s Rainbow].setLevel([47]) (10ms)
+821ms ║╚Execution stage complete. (429ms)
+833ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:34:15 AM EDT (in 14.912s), with 42 more jobs pending
+1152ms ╚Event processed successfully (1152ms)
3/27/2020, 11:33:44 AM +242ms
+1ms ╔Received event [Home].time = 1585323225878 with a delay of -1636ms
+485ms ║RunTime Analysis CS > 99ms > PS > 331ms > PE > 54ms > CE
+487ms ║Runtime (44267 bytes) successfully initialized in 331ms (v0.3.10c.20190522) (486ms)
+489ms ║╔Execution stage started
+522ms ║║Executed physical command [Book Lamp].setLevel([46]) (10ms)
+530ms ║║Fast executing schedules, waiting for 1133ms to sync up
+1786ms ║║Executed physical command [Lily Princess Lamp].setLevel([46]) (8ms)
+1806ms ║║Executed physical command [Lily’s Rainbow].setLevel([46]) (9ms)
+1818ms ║╚Execution stage complete. (1329ms)
+1829ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:34:00 AM EDT (in 14.808s), with 45 more jobs pending
+1839ms ╚Event processed successfully (1839ms)
3/27/2020, 11:33:33 AM +377ms
+3ms ╔Received event [Home].time = 1585323210878 with a delay of 2498ms
+4357ms ║RunTime Analysis CS > 3902ms > PS > 330ms > PE > 125ms > CE
+4360ms ║Runtime (44270 bytes) successfully initialized in 330ms (v0.3.10c.20190522) (4348ms)
+4361ms ║╔Execution stage started
+4436ms ║║Executed physical command [Book Lamp].setLevel([45]) (8ms)
+4453ms ║║Executed physical command [Lily Princess Lamp].setLevel([45]) (7ms)
+4469ms ║║Executed physical command [Lily’s Rainbow].setLevel([45]) (7ms)
+4480ms ║╚Execution stage complete. (118ms)
+4577ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:33:45 AM EDT (in 7.926s), with 48 more jobs pending
+4587ms ╚Event processed successfully (4587ms)
3/27/2020, 11:33:16 AM +817ms
+2ms ╔Received event [Home].time = 1585323195877 with a delay of 939ms
+342ms ║RunTime Analysis CS > 22ms > PS > 247ms > PE > 73ms > CE
+345ms ║Runtime (44265 bytes) successfully initialized in 247ms (v0.3.10c.20190522) (340ms)
+346ms ║╔Execution stage started
+384ms ║║Executed physical command [Book Lamp].setLevel([44]) (7ms)
+402ms ║║Executed physical command [Lily Princess Lamp].setLevel([44]) (6ms)
+417ms ║║Executed physical command [Lily’s Rainbow].setLevel([44]) (6ms)
+429ms ║╚Execution stage complete. (83ms)
+467ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:33:30 AM EDT (in 13.595s), with 51 more jobs pending
+476ms ╚Event processed successfully (476ms)
3/27/2020, 11:32:59 AM +156ms
+1ms ╔Received event [Home].time = 1585323180877 with a delay of -1721ms
+313ms ║RunTime Analysis CS > 19ms > PS > 237ms > PE > 57ms > CE
+317ms ║Runtime (44267 bytes) successfully initialized in 237ms (v0.3.10c.20190522) (316ms)
+319ms ║╔Execution stage started
+339ms ║║Executed physical command [Book Lamp].setLevel([43]) (10ms)
+347ms ║║Fast executing schedules, waiting for 1402ms to sync up
+2320ms ║║Executed physical command [Lily Princess Lamp].setLevel([43]) (11ms)
+2348ms ║║Executed physical command [Lily’s Rainbow].setLevel([43]) (13ms)
+2363ms ║╚Execution stage complete. (2044ms)
+2380ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:33:15 AM EDT (in 14.342s), with 54 more jobs pending
+2392ms ╚Event processed successfully (2391ms)
3/27/2020, 11:32:44 AM +279ms
+1ms ╔Received event [Home].time = 1585323165877 with a delay of -1600ms
+436ms ║RunTime Analysis CS > 42ms > PS > 349ms > PE > 46ms > CE
+438ms ║Runtime (44267 bytes) successfully initialized in 349ms (v0.3.10c.20190522) (435ms)
+439ms ║╔Execution stage started
+460ms ║║Executed physical command [Book Lamp].setLevel([42]) (9ms)
+469ms ║║Fast executing schedules, waiting for 1156ms to sync up
+1640ms ║║Executed physical command [Lily Princess Lamp].setLevel([42]) (10ms)
+1649ms ║║Fast executing schedules, waiting for 3ms to sync up
+1663ms ║║Executed physical command [Lily’s Rainbow].setLevel([42]) (8ms)
+1676ms ║╚Execution stage complete. (1237ms)
+1692ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:33:00 AM EDT (in 14.907s), with 57 more jobs pending
+1701ms ╚Event processed successfully (1701ms)
3/27/2020, 11:32:30 AM +123ms
+0ms ╔Received event [Home].time = 1585323150877 with a delay of -755ms
+556ms ║RunTime Analysis CS > 56ms > PS > 427ms > PE > 73ms > CE
+559ms ║Runtime (44266 bytes) successfully initialized in 427ms (v0.3.10c.20190522) (558ms)
+560ms ║╔Execution stage started
+588ms ║║Executed physical command [Book Lamp].setLevel([41]) (9ms)
+597ms ║║Fast executing schedules, waiting for 184ms to sync up
+794ms ║║Executed physical command [Lily Princess Lamp].setLevel([41]) (9ms)
+803ms ║║Fast executing schedules, waiting for 4ms to sync up
+819ms ║║Executed physical command [Lily’s Rainbow].setLevel([41]) (8ms)
+832ms ║╚Execution stage complete. (272ms)
+846ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:32:45 AM EDT (in 14.908s), with 60 more jobs pending
+855ms ╚Event processed successfully (855ms)
3/27/2020, 11:32:16 AM +594ms
+3ms ╔Received event [Home].time = 1585323135876 with a delay of 717ms
+2650ms ║RunTime Analysis CS > 2172ms > PS > 363ms > PE > 116ms > CE
+2653ms ║Runtime (44269 bytes) successfully initialized in 363ms (v0.3.10c.20190522) (2646ms)
+2655ms ║╔Execution stage started
+2743ms ║║Executed physical command [Book Lamp].setLevel([40]) (10ms)
+2769ms ║║Executed physical command [Lily Princess Lamp].setLevel([40]) (9ms)
+2793ms ║║Executed physical command [Lily’s Rainbow].setLevel([40]) (8ms)
+2810ms ║╚Execution stage complete. (156ms)
+2934ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:32:30 AM EDT (in 11.354s), with 63 more jobs pending
+2949ms ╚Event processed successfully (2948ms)
3/27/2020, 11:31:59 AM +127ms
+0ms ╔Received event [Home].time = 1585323120876 with a delay of -1749ms
+374ms ║RunTime Analysis CS > 25ms > PS > 282ms > PE > 67ms > CE
+377ms ║Runtime (44267 bytes) successfully initialized in 282ms (v0.3.10c.20190522) (375ms)
+378ms ║╔Execution stage started
+401ms ║║Executed physical command [Book Lamp].setLevel([39]) (10ms)
+411ms ║║Fast executing schedules, waiting for 1367ms to sync up
+1790ms ║║Executed physical command [Lily Princess Lamp].setLevel([39]) (9ms)
+1801ms ║║Fast executing schedules, waiting for 1ms to sync up
+1815ms ║║Executed physical command [Lily’s Rainbow].setLevel([39]) (9ms)
+1829ms ║╚Execution stage complete. (1452ms)
+1845ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:32:15 AM EDT (in 14.905s), with 66 more jobs pending
+1854ms ╚Event processed successfully (1854ms)
3/27/2020, 11:31:45 AM +170ms
+1ms ╔Received event [Home].time = 1585323105876 with a delay of -706ms
+573ms ║RunTime Analysis CS > 41ms > PS > 467ms > PE > 64ms > CE
+577ms ║Runtime (44266 bytes) successfully initialized in 467ms (v0.3.10c.20190522) (575ms)
+579ms ║╔Execution stage started
+606ms ║║Executed physical command [Book Lamp].setLevel([38]) (9ms)
+618ms ║║Fast executing schedules, waiting for 116ms to sync up
+751ms ║║Executed physical command [Lily Princess Lamp].setLevel([38]) (9ms)
+775ms ║║Executed physical command [Lily’s Rainbow].setLevel([38]) (9ms)
+796ms ║╚Execution stage complete. (217ms)
+815ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:32:00 AM EDT (in 14.892s), with 69 more jobs pending
+826ms ╚Event processed successfully (825ms)
3/27/2020, 11:31:30 AM +976ms
+0ms ╔Received event [Home].time = 1585323090876 with a delay of 99ms
+386ms ║RunTime Analysis CS > 18ms > PS > 300ms > PE > 69ms > CE
+389ms ║Runtime (44264 bytes) successfully initialized in 300ms (v0.3.10c.20190522) (387ms)
+390ms ║╔Execution stage started
+411ms ║║Executed physical command [Book Lamp].setLevel([37]) (10ms)
+432ms ║║Executed physical command [Lily Princess Lamp].setLevel([37]) (7ms)
+452ms ║║Executed physical command [Lily’s Rainbow].setLevel([37]) (7ms)
+467ms ║╚Execution stage complete. (78ms)
+483ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:31:45 AM EDT (in 14.417s), with 72 more jobs pending
+492ms ╚Event processed successfully (492ms)
3/27/2020, 11:31:15 AM +220ms
+0ms ╔Received event [Home].time = 1585323075875 with a delay of -656ms
+748ms ║RunTime Analysis CS > 71ms > PS > 631ms > PE > 47ms > CE
+751ms ║Runtime (44266 bytes) successfully initialized in 631ms (v0.3.10c.20190522) (750ms)
+752ms ║╔Execution stage started
+774ms ║║Executed physical command [Book Lamp].setLevel([36]) (9ms)
+795ms ║║Executed physical command [Lily Princess Lamp].setLevel([36]) (7ms)
+818ms ║║Executed physical command [Lily’s Rainbow].setLevel([36]) (9ms)
+834ms ║╚Execution stage complete. (82ms)
+853ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:31:30 AM EDT (in 14.803s), with 75 more jobs pending
+861ms ╚Event processed successfully (861ms)
3/27/2020, 11:30:59 AM +170ms
+1ms ╔Received event [Home].time = 1585323060875 with a delay of -1705ms
+681ms ║RunTime Analysis CS > 166ms > PS > 334ms > PE > 180ms > CE
+684ms ║Runtime (44269 bytes) successfully initialized in 334ms (v0.3.10c.20190522) (681ms)
+685ms ║╔Execution stage started
+709ms ║║Executed physical command [Book Lamp].setLevel([35]) (10ms)
+720ms ║║Fast executing schedules, waiting for 1014ms to sync up
+1747ms ║║Executed physical command [Lily Princess Lamp].setLevel([35]) (7ms)
+1772ms ║║Executed physical command [Lily’s Rainbow].setLevel([35]) (8ms)
+1792ms ║╚Execution stage complete. (1107ms)
+1832ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:31:15 AM EDT (in 14.889s), with 78 more jobs pending
+1844ms ╚Event processed successfully (1844ms)
3/27/2020, 11:30:44 AM +393ms
+0ms ╔Received event [Home].time = 1585323045875 with a delay of -1482ms
+645ms ║RunTime Analysis CS > 259ms > PS > 338ms > PE > 47ms > CE
+648ms ║Runtime (44268 bytes) successfully initialized in 338ms (v0.3.10c.20190522) (646ms)
+649ms ║╔Execution stage started
+721ms ║║Executed physical command [Book Lamp].setLevel([34]) (9ms)
+737ms ║║Fast executing schedules, waiting for 772ms to sync up
+1522ms ║║Executed physical command [Lily Princess Lamp].setLevel([34]) (8ms)
+1533ms ║║Fast executing schedules, waiting for 2ms to sync up
+1546ms ║║Executed physical command [Lily’s Rainbow].setLevel([34]) (8ms)
+1563ms ║╚Execution stage complete. (915ms)
+1582ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:31:00 AM EDT (in 14.901s), with 81 more jobs pending
+1591ms ╚Event processed successfully (1591ms)
3/27/2020, 11:30:30 AM +200ms
+1ms ╔Received event [Home].time = 1585323030875 with a delay of -675ms
+539ms ║RunTime Analysis CS > 104ms > PS > 377ms > PE > 57ms > CE
+542ms ║Runtime (44267 bytes) successfully initialized in 377ms (v0.3.10c.20190522) (541ms)
+543ms ║╔Execution stage started
+564ms ║║Executed physical command [Book Lamp].setLevel([33]) (8ms)
+579ms ║║Fast executing schedules, waiting for 124ms to sync up
+714ms ║║Executed physical command [Lily Princess Lamp].setLevel([33]) (7ms)
+726ms ║║Fast executing schedules, waiting for 2ms to sync up
+744ms ║║Executed physical command [Lily’s Rainbow].setLevel([33]) (8ms)
+764ms ║╚Execution stage complete. (222ms)
+785ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:30:45 AM EDT (in 14.891s), with 84 more jobs pending
+793ms ╚Event processed successfully (793ms)
3/27/2020, 11:30:15 AM +399ms
+1ms ╔Received event [Home].time = 1585323015874 with a delay of -475ms
+696ms ║RunTime Analysis CS > 185ms > PS > 464ms > PE > 46ms > CE
+699ms ║Runtime (44267 bytes) successfully initialized in 464ms (v0.3.10c.20190522) (696ms)
+700ms ║╔Execution stage started
+949ms ║║Executed physical command [Book Lamp].setLevel([32]) (11ms)
+974ms ║║Executed physical command [Lily Princess Lamp].setLevel([32]) (9ms)
+1001ms ║║Executed physical command [Lily’s Rainbow].setLevel([32]) (10ms)
+1028ms ║╚Execution stage complete. (329ms)
+1049ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:30:30 AM EDT (in 14.428s), with 87 more jobs pending
+1057ms ╚Event processed successfully (1057ms)
3/27/2020, 11:29:59 AM +113ms
+1ms ╔Received event [Home].time = 1585323000874 with a delay of -1761ms
+308ms ║RunTime Analysis CS > 19ms > PS > 243ms > PE > 45ms > CE
+310ms ║Runtime (44267 bytes) successfully initialized in 243ms (v0.3.10c.20190522) (309ms)
+311ms ║╔Execution stage started
+341ms ║║Executed physical command [Book Lamp].setLevel([31]) (10ms)
+353ms ║║Fast executing schedules, waiting for 1437ms to sync up
+1801ms ║║Executed physical command [Lily Princess Lamp].setLevel([31]) (8ms)
+1824ms ║║Executed physical command [Lily’s Rainbow].setLevel([31]) (8ms)
+1843ms ║╚Execution stage complete. (1532ms)
+2569ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:30:15 AM EDT (in 14.205s), with 90 more jobs pending
+2580ms ╚Event processed successfully (2580ms)
3/27/2020, 11:29:45 AM +112ms
+1ms ╔Received event [Home].time = 1585322985874 with a delay of -764ms
+314ms ║RunTime Analysis CS > 21ms > PS > 242ms > PE > 53ms > CE
+319ms ║Runtime (44266 bytes) successfully initialized in 242ms (v0.3.10c.20190522) (317ms)
+320ms ║╔Execution stage started
+343ms ║║Executed physical command [Book Lamp].setLevel([30]) (8ms)
+356ms ║║Fast executing schedules, waiting for 434ms to sync up
+802ms ║║Executed physical command [Lily Princess Lamp].setLevel([30]) (8ms)
+813ms ║║Fast executing schedules, waiting for 2ms to sync up
+827ms ║║Executed physical command [Lily’s Rainbow].setLevel([30]) (9ms)
+845ms ║╚Execution stage complete. (524ms)
+863ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:30:00 AM EDT (in 14.9s), with 93 more jobs pending
+872ms ╚Event processed successfully (872ms)
3/27/2020, 11:29:32 AM +554ms
+3ms ╔Received event [Home].time = 1585322970874 with a delay of 1678ms
+381ms ║RunTime Analysis CS > 36ms > PS > 264ms > PE > 83ms > CE
+384ms ║Runtime (44266 bytes) successfully initialized in 264ms (v0.3.10c.20190522) (377ms)
+386ms ║╔Execution stage started
+460ms ║║Executed physical command [Book Lamp].setLevel([29]) (11ms)
+484ms ║║Executed physical command [Lily Princess Lamp].setLevel([29]) (8ms)
+512ms ║║Executed physical command [Lily’s Rainbow].setLevel([29]) (9ms)
+534ms ║╚Execution stage complete. (149ms)
+622ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:29:45 AM EDT (in 12.702s), with 96 more jobs pending
+636ms ╚Event processed successfully (636ms)
3/27/2020, 11:29:15 AM +926ms
+2ms ╔Received event [Home].time = 1585322955873 with a delay of 51ms
+1360ms ║RunTime Analysis CS > 911ms > PS > 336ms > PE > 114ms > CE
+1364ms ║Runtime (44267 bytes) successfully initialized in 336ms (v0.3.10c.20190522) (1357ms)
+1365ms ║╔Execution stage started
+1471ms ║║Executed physical command [Book Lamp].setLevel([28]) (10ms)
+1497ms ║║Executed physical command [Lily Princess Lamp].setLevel([28]) (8ms)
+1521ms ║║Executed physical command [Lily’s Rainbow].setLevel([28]) (7ms)
+1545ms ║╚Execution stage complete. (180ms)
+1666ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:29:30 AM EDT (in 13.287s), with 99 more jobs pending
+1677ms ╚Event processed successfully (1677ms)
3/27/2020, 11:28:59 AM +111ms
+1ms ╔Received event [Home].time = 1585322940873 with a delay of -1763ms
+335ms ║RunTime Analysis CS > 19ms > PS > 243ms > PE > 73ms > CE
+337ms ║Runtime (44267 bytes) successfully initialized in 243ms (v0.3.10c.20190522) (335ms)
+338ms ║╔Execution stage started
+364ms ║║Executed physical command [Book Lamp].setLevel([27]) (9ms)
+379ms ║║Fast executing schedules, waiting for 1411ms to sync up
+1801ms ║║Executed physical command [Lily Princess Lamp].setLevel([27]) (8ms)
+1814ms ║║Fast executing schedules, waiting for 1ms to sync up
+1827ms ║║Executed physical command [Lily’s Rainbow].setLevel([27]) (8ms)
+1846ms ║╚Execution stage complete. (1508ms)
+1870ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:29:15 AM EDT (in 14.893s), with 102 more jobs pending
+1881ms ╚Event processed successfully (1881ms)
3/27/2020, 11:28:44 AM +301ms
+1ms ╔Received event [Home].time = 1585322925873 with a delay of -1572ms
+376ms ║RunTime Analysis CS > 19ms > PS > 290ms > PE > 67ms > CE
+380ms ║Runtime (44267 bytes) successfully initialized in 290ms (v0.3.10c.20190522) (379ms)
+381ms ║╔Execution stage started
+413ms ║║Executed physical command [Book Lamp].setLevel([26]) (9ms)
+427ms ║║Fast executing schedules, waiting for 1173ms to sync up
+1613ms ║║Executed physical command [Lily Princess Lamp].setLevel([26]) (9ms)
+1638ms ║║Executed physical command [Lily’s Rainbow].setLevel([26]) (8ms)
+1661ms ║╚Execution stage complete. (1279ms)
+1682ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:29:00 AM EDT (in 14.89s), with 105 more jobs pending
+1692ms ╚Event processed successfully (1691ms)
3/27/2020, 11:28:29 AM +113ms
+0ms ╔Received event [Home].time = 1585322910873 with a delay of -1761ms
+304ms ║RunTime Analysis CS > 24ms > PS > 229ms > PE > 51ms > CE
+306ms ║Runtime (44267 bytes) successfully initialized in 229ms (v0.3.10c.20190522) (305ms)
+307ms ║╔Execution stage started
+332ms ║║Executed physical command [Book Lamp].setLevel([25]) (9ms)
+351ms ║║Fast executing schedules, waiting for 1436ms to sync up
+1798ms ║║Executed physical command [Lily Princess Lamp].setLevel([25]) (7ms)
+1811ms ║║Fast executing schedules, waiting for 2ms to sync up
+1823ms ║║Executed physical command [Lily’s Rainbow].setLevel([25]) (6ms)
+1843ms ║╚Execution stage complete. (1535ms)
+1864ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:28:45 AM EDT (in 14.896s), with 108 more jobs pending
+1873ms ╚Event processed successfully (1874ms)
3/27/2020, 11:28:15 AM +150ms
+2ms ╔Received event [Home].time = 1585322895873 with a delay of -723ms
+416ms ║RunTime Analysis CS > 75ms > PS > 292ms > PE > 48ms > CE
+418ms ║Runtime (44266 bytes) successfully initialized in 292ms (v0.3.10c.20190522) (415ms)
+419ms ║╔Execution stage started
+448ms ║║Executed physical command [Book Lamp].setLevel([24]) (12ms)
+461ms ║║Fast executing schedules, waiting for 290ms to sync up
+772ms ║║Executed physical command [Lily Princess Lamp].setLevel([24]) (11ms)
+798ms ║║Executed physical command [Lily’s Rainbow].setLevel([24]) (11ms)
+818ms ║╚Execution stage complete. (399ms)
+839ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:28:30 AM EDT (in 14.885s), with 111 more jobs pending
+847ms ╚Event processed successfully (847ms)
3/27/2020, 11:27:59 AM +120ms
+0ms ╔Received event [Home].time = 1585322880872 with a delay of -1752ms
+278ms ║RunTime Analysis CS > 18ms > PS > 215ms > PE > 44ms > CE
+280ms ║Runtime (44267 bytes) successfully initialized in 215ms (v0.3.10c.20190522) (279ms)
+281ms ║╔Execution stage started
+304ms ║║Executed physical command [Book Lamp].setLevel([23]) (7ms)
+318ms ║║Fast executing schedules, waiting for 1463ms to sync up
+1792ms ║║Executed physical command [Lily Princess Lamp].setLevel([23]) (8ms)
+1822ms ║║Executed physical command [Lily’s Rainbow].setLevel([23]) (10ms)
+1852ms ║╚Execution stage complete. (1570ms)
+1880ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:28:15 AM EDT (in 14.874s), with 114 more jobs pending
+1892ms ╚Event processed successfully (1891ms)
3/27/2020, 11:27:45 AM +160ms
+0ms ╔Received event [Home].time = 1585322865872 with a delay of -712ms
+328ms ║RunTime Analysis CS > 31ms > PS > 241ms > PE > 56ms > CE
+330ms ║Runtime (44266 bytes) successfully initialized in 241ms (v0.3.10c.20190522) (329ms)
+331ms ║╔Execution stage started
+359ms ║║Executed physical command [Book Lamp].setLevel([22]) (9ms)
+373ms ║║Fast executing schedules, waiting for 367ms to sync up
+752ms ║║Executed physical command [Lily Princess Lamp].setLevel([22]) (7ms)
+776ms ║║Executed physical command [Lily’s Rainbow].setLevel([22]) (7ms)
+798ms ║╚Execution stage complete. (466ms)
+822ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:28:00 AM EDT (in 14.891s), with 117 more jobs pending
+831ms ╚Event processed successfully (831ms)
3/27/2020, 11:27:30 AM +170ms
+1ms ╔Received event [Home].time = 1585322850872 with a delay of -702ms
+352ms ║RunTime Analysis CS > 46ms > PS > 260ms > PE > 46ms > CE
+355ms ║Runtime (44266 bytes) successfully initialized in 260ms (v0.3.10c.20190522) (351ms)
+356ms ║╔Execution stage started
+382ms ║║Executed physical command [Book Lamp].setLevel([21]) (8ms)
+396ms ║║Fast executing schedules, waiting for 333ms to sync up
+742ms ║║Executed physical command [Lily Princess Lamp].setLevel([21]) (9ms)
+772ms ║║Executed physical command [Lily’s Rainbow].setLevel([21]) (7ms)
+803ms ║╚Execution stage complete. (447ms)
+834ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:27:45 AM EDT (in 14.869s), with 120 more jobs pending
+867ms ╚Event processed successfully (867ms)
3/27/2020, 11:27:14 AM +159ms
+1ms ╔Received event [Home].time = 1585322835872 with a delay of -1714ms
+356ms ║RunTime Analysis CS > 37ms > PS > 269ms > PE > 50ms > CE
+359ms ║Runtime (44267 bytes) successfully initialized in 269ms (v0.3.10c.20190522) (355ms)
+360ms ║╔Execution stage started
+451ms ║║Executed physical command [Book Lamp].setLevel([20]) (14ms)
+480ms ║║Fast executing schedules, waiting for 1261ms to sync up
+1755ms ║║Executed physical command [Lily Princess Lamp].setLevel([20]) (10ms)
+1790ms ║║Executed physical command [Lily’s Rainbow].setLevel([20]) (9ms)
+1821ms ║╚Execution stage complete. (1462ms)
+1944ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:27:30 AM EDT (in 14.775s), with 123 more jobs pending
+1955ms ╚Event processed successfully (1955ms)
3/27/2020, 11:27:00 AM +209ms
+0ms ╔Received event [Home].time = 1585322820871 with a delay of -663ms
+524ms ║RunTime Analysis CS > 38ms > PS > 442ms > PE > 44ms > CE
+526ms ║Runtime (44266 bytes) successfully initialized in 442ms (v0.3.10c.20190522) (525ms)
+527ms ║╔Execution stage started
+553ms ║║Executed physical command [Book Lamp].setLevel([19]) (8ms)
+568ms ║║Fast executing schedules, waiting for 122ms to sync up
+701ms ║║Executed physical command [Lily Princess Lamp].setLevel([19]) (7ms)
+727ms ║║Executed physical command [Lily’s Rainbow].setLevel([19]) (7ms)
+751ms ║╚Execution stage complete. (224ms)
+779ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:27:15 AM EDT (in 14.885s), with 126 more jobs pending
+787ms ╚Event processed successfully (787ms)
3/27/2020, 11:26:56 AM +105ms
+1ms ╔Received event [Home].time = 1585322805871 with a delay of 10234ms
+288ms ║RunTime Analysis CS > 19ms > PS > 223ms > PE > 46ms > CE
+291ms ║Runtime (44267 bytes) successfully initialized in 223ms (v0.3.10c.20190522) (288ms)
+293ms ║╔Execution stage started
+355ms ║║Executed physical command [Book Lamp].setLevel([18]) (9ms)
+382ms ║║Executed physical command [Lily Princess Lamp].setLevel([18]) (8ms)
+407ms ║║Executed physical command [Lily’s Rainbow].setLevel([18]) (7ms)
+430ms ║╚Execution stage complete. (138ms)
+456ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:27:00 AM EDT (in 4.311s), with 129 more jobs pending
+465ms ╚Event processed successfully (465ms)
3/27/2020, 11:26:48 AM +27ms
+5ms ╔Received event [Home].time = 1585322805871 with a delay of 2154ms
+5027ms ║RunTime Analysis CS > 4659ms > PS > 287ms > PE > 83ms > CE
+5031ms ║Runtime (44269 bytes) successfully initialized in 287ms (v0.3.10c.20190522) (5018ms)
+5032ms ║╔Execution stage started
+5036ms ║╚Execution stage complete. (4ms)
+5208ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:26:45 AM EDT (in 1s), with 132 more jobs pending
+5219ms ╚Event processed successfully (5219ms)
3/27/2020, 11:26:29 AM +116ms
+1ms ╔Received event [Home].time = 1585322790871 with a delay of -1755ms
+322ms ║RunTime Analysis CS > 25ms > PS > 242ms > PE > 54ms > CE
+324ms ║Runtime (44267 bytes) successfully initialized in 242ms (v0.3.10c.20190522) (322ms)
+325ms ║╔Execution stage started
+352ms ║║Executed physical command [Book Lamp].setLevel([17]) (8ms)
+368ms ║║Fast executing schedules, waiting for 1414ms to sync up
+1794ms ║║Executed physical command [Lily Princess Lamp].setLevel([17]) (8ms)
+1821ms ║║Executed physical command [Lily’s Rainbow].setLevel([17]) (8ms)
+1846ms ║╚Execution stage complete. (1521ms)
+1874ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:26:45 AM EDT (in 14.881s), with 132 more jobs pending
+1883ms ╚Event processed successfully (1883ms)
3/27/2020, 11:26:15 AM +150ms
+0ms ╔Received event [Home].time = 1585322775871 with a delay of -721ms
+485ms ║RunTime Analysis CS > 19ms > PS > 406ms > PE > 59ms > CE
+487ms ║Runtime (44266 bytes) successfully initialized in 406ms (v0.3.10c.20190522) (486ms)
+489ms ║╔Execution stage started
+519ms ║║Executed physical command [Book Lamp].setLevel([16]) (9ms)
+537ms ║║Fast executing schedules, waiting for 212ms to sync up
+762ms ║║Executed physical command [Lily Princess Lamp].setLevel([16]) (9ms)
+790ms ║║Executed physical command [Lily’s Rainbow].setLevel([16]) (8ms)
+818ms ║╚Execution stage complete. (330ms)
+848ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:26:30 AM EDT (in 14.874s), with 135 more jobs pending
+872ms ╚Event processed successfully (872ms)
3/27/2020, 11:26:00 AM +341ms
+0ms ╔Received event [Home].time = 1585322760870 with a delay of -530ms
+1082ms ║RunTime Analysis CS > 34ms > PS > 970ms > PE > 79ms > CE
+1084ms ║Runtime (44267 bytes) successfully initialized in 970ms (v0.3.10c.20190522) (1083ms)
+1085ms ║╔Execution stage started
+1122ms ║║Executed physical command [Book Lamp].setLevel([15]) (9ms)
+1150ms ║║Executed physical command [Lily Princess Lamp].setLevel([15]) (8ms)
+1181ms ║║Executed physical command [Lily’s Rainbow].setLevel([15]) (8ms)
+1212ms ║╚Execution stage complete. (127ms)
+1348ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:26:15 AM EDT (in 14.185s), with 138 more jobs pending
+1366ms ╚Event processed successfully (1366ms)
3/27/2020, 11:25:45 AM +194ms
+1ms ╔Received event [Home].time = 1585322745870 with a delay of -676ms
+433ms ║RunTime Analysis CS > 61ms > PS > 314ms > PE > 58ms > CE
+436ms ║Runtime (44266 bytes) successfully initialized in 314ms (v0.3.10c.20190522) (433ms)
+437ms ║╔Execution stage started
+472ms ║║Executed physical command [Book Lamp].setLevel([14]) (10ms)
+496ms ║║Fast executing schedules, waiting for 208ms to sync up
+717ms ║║Executed physical command [Lily Princess Lamp].setLevel([14]) (9ms)
+748ms ║║Executed physical command [Lily’s Rainbow].setLevel([14]) (10ms)
+779ms ║╚Execution stage complete. (343ms)
+813ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:26:00 AM EDT (in 14.864s), with 141 more jobs pending
+841ms ╚Event processed successfully (841ms)
3/27/2020, 11:25:31 AM +182ms
+0ms ╔Received event [Home].time = 1585322715870 with a delay of 15311ms
+939ms ║RunTime Analysis CS > 127ms > PS > 476ms > PE > 329ms > CE
+945ms ║Runtime (44269 bytes) successfully initialized in 476ms (v0.3.10c.20190522) (943ms)
+946ms ║╔Execution stage started
+989ms ║║Executed physical command [Book Lamp].setLevel([12]) (12ms)
+1031ms ║║Executed physical command [Lily Princess Lamp].setLevel([12]) (8ms)
+1065ms ║║Executed physical command [Lily’s Rainbow].setLevel([12]) (9ms)
+1094ms ║║Executed physical command [Book Lamp].setLevel([13]) (5ms)
+1120ms ║║Executed physical command [Lily Princess Lamp].setLevel([13]) (6ms)
+1155ms ║║Executed physical command [Lily’s Rainbow].setLevel([13]) (6ms)
+1188ms ║╚Execution stage complete. (243ms)
+1231ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:25:45 AM EDT (in 13.469s), with 144 more jobs pending
+1240ms ╚Event processed successfully (1240ms)
3/27/2020, 11:25:21 AM +49ms
+5ms ╔Received event [Home].time = 1585322715870 with a delay of 5179ms
+7467ms ║RunTime Analysis CS > 6797ms > PS > 503ms > PE > 166ms > CE
+7469ms ║Runtime (44270 bytes) successfully initialized in 503ms (v0.3.10c.20190522) (7441ms)
+7471ms ║╔Execution stage started
+7479ms ║╚Execution stage complete. (9ms)
+7723ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:25:15 AM EDT (in 1s), with 150 more jobs pending
+7735ms ╚Event processed successfully (7735ms)
3/27/2020, 11:24:59 AM +199ms
+1ms ╔Received event [Home].time = 1585322700869 with a delay of -1670ms
+388ms ║RunTime Analysis CS > 67ms > PS > 268ms > PE > 53ms > CE
+391ms ║Runtime (44267 bytes) successfully initialized in 268ms (v0.3.10c.20190522) (389ms)
+392ms ║╔Execution stage started
+424ms ║║Executed physical command [Book Lamp].setLevel([11]) (14ms)
+439ms ║║Fast executing schedules, waiting for 1259ms to sync up
+1715ms ║║Executed physical command [Lily Princess Lamp].setLevel([11]) (10ms)
+1744ms ║║Executed physical command [Lily’s Rainbow].setLevel([11]) (10ms)
+1768ms ║╚Execution stage complete. (1377ms)
+1795ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:25:15 AM EDT (in 14.877s), with 150 more jobs pending
+1806ms ╚Event processed successfully (1805ms)
3/27/2020, 11:24:45 AM +180ms
+1ms ╔Received event [Home].time = 1585322685869 with a delay of -690ms
+306ms ║RunTime Analysis CS > 34ms > PS > 228ms > PE > 45ms > CE
+309ms ║Runtime (44266 bytes) successfully initialized in 228ms (v0.3.10c.20190522) (307ms)
+311ms ║╔Execution stage started
+388ms ║║Executed physical command [Book Lamp].setLevel([10]) (8ms)
+411ms ║║Fast executing schedules, waiting for 306ms to sync up
+729ms ║║Executed physical command [Lily Princess Lamp].setLevel([10]) (8ms)
+758ms ║║Executed physical command [Lily’s Rainbow].setLevel([10]) (7ms)
+786ms ║╚Execution stage complete. (475ms)
+818ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:25:00 AM EDT (in 14.872s), with 153 more jobs pending
+853ms ╚Event processed successfully (853ms)
3/27/2020, 11:24:29 AM +178ms
+0ms ╔Received event [Home].time = 1585322670869 with a delay of -1692ms
+321ms ║RunTime Analysis CS > 32ms > PS > 233ms > PE > 56ms > CE
+323ms ║Runtime (44267 bytes) successfully initialized in 233ms (v0.3.10c.20190522) (321ms)
+324ms ║╔Execution stage started
+361ms ║║Executed physical command [Book Lamp].setLevel([9]) (9ms)
+379ms ║║Fast executing schedules, waiting for 1339ms to sync up
+1729ms ║║Executed physical command [Lily Princess Lamp].setLevel([9]) (8ms)
+1757ms ║║Executed physical command [Lily’s Rainbow].setLevel([9]) (7ms)
+1785ms ║╚Execution stage complete. (1461ms)
+1825ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:24:45 AM EDT (in 14.868s), with 156 more jobs pending
+1852ms ╚Event processed successfully (1852ms)
3/27/2020, 11:24:22 AM +319ms
+1ms ╔Received event [Home].time = 1585322640868 with a delay of 21451ms
+351ms ║RunTime Analysis CS > 34ms > PS > 257ms > PE > 60ms > CE
+354ms ║Runtime (44267 bytes) successfully initialized in 257ms (v0.3.10c.20190522) (353ms)
+355ms ║╔Execution stage started
+390ms ║║Executed physical command [Book Lamp].setLevel([7]) (10ms)
+424ms ║║Executed physical command [Lily Princess Lamp].setLevel([7]) (8ms)
+457ms ║║Executed physical command [Lily’s Rainbow].setLevel([7]) (9ms)
+488ms ║║Executed physical command [Book Lamp].setLevel([8]) (6ms)
+518ms ║║Executed physical command [Lily Princess Lamp].setLevel([8]) (6ms)
+550ms ║║Executed physical command [Lily’s Rainbow].setLevel([8]) (6ms)
+584ms ║╚Execution stage complete. (230ms)
+620ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:24:30 AM EDT (in 7.931s), with 159 more jobs pending
+629ms ╚Event processed successfully (628ms)
3/27/2020, 11:24:09 AM +288ms
+33ms ╔Received event [Home].time = 1585322640868 with a delay of 8418ms
+9981ms ║RunTime Analysis CS > 9312ms > PS > 514ms > PE > 156ms > CE
+9984ms ║Runtime (44271 bytes) successfully initialized in 514ms (v0.3.10c.20190522) (9942ms)
+9986ms ║╔Execution stage started
+9989ms ║╚Execution stage complete. (4ms)
+10243ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:24:00 AM EDT (in 1s), with 165 more jobs pending
+10255ms ╚Event processed successfully (10255ms)
3/27/2020, 11:23:45 AM +211ms
+1ms ╔Received event [Home].time = 1585322625866 with a delay of -655ms
+392ms ║RunTime Analysis CS > 37ms > PS > 302ms > PE > 53ms > CE
+395ms ║Runtime (44266 bytes) successfully initialized in 302ms (v0.3.10c.20190522) (391ms)
+396ms ║╔Execution stage started
+433ms ║║Executed physical command [Book Lamp].setLevel([6]) (11ms)
+457ms ║║Fast executing schedules, waiting for 229ms to sync up
+699ms ║║Executed physical command [Lily Princess Lamp].setLevel([6]) (9ms)
+732ms ║║Executed physical command [Lily’s Rainbow].setLevel([6]) (7ms)
+767ms ║╚Execution stage complete. (371ms)
+803ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:24:00 AM EDT (in 14.855s), with 165 more jobs pending
+836ms ╚Event processed successfully (835ms)
3/27/2020, 11:23:30 AM +212ms
+1ms ╔Received event [Home].time = 1585322610866 with a delay of -654ms
+440ms ║RunTime Analysis CS > 70ms > PS > 300ms > PE > 70ms > CE
+443ms ║Runtime (44266 bytes) successfully initialized in 300ms (v0.3.10c.20190522) (441ms)
+445ms ║╔Execution stage started
+477ms ║║Executed physical command [Book Lamp].setLevel([5]) (7ms)
+497ms ║║Fast executing schedules, waiting for 187ms to sync up
+978ms ║║Executed physical command [Lily Princess Lamp].setLevel([5]) (10ms)
+1016ms ║║Executed physical command [Lily’s Rainbow].setLevel([5]) (10ms)
+1067ms ║╚Execution stage complete. (623ms)
+1111ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:23:45 AM EDT (in 14.544s), with 168 more jobs pending
+1119ms ╚Event processed successfully (1119ms)
3/27/2020, 11:23:15 AM +159ms
+1ms ╔Received event [Home].time = 1585322595866 with a delay of -708ms
+331ms ║RunTime Analysis CS > 29ms > PS > 253ms > PE > 49ms > CE
+334ms ║Runtime (44266 bytes) successfully initialized in 253ms (v0.3.10c.20190522) (331ms)
+335ms ║╔Execution stage started
+369ms ║║Executed physical command [Book Lamp].setLevel([4]) (8ms)
+392ms ║║Fast executing schedules, waiting for 344ms to sync up
+754ms ║║Executed physical command [Lily Princess Lamp].setLevel([4]) (8ms)
+787ms ║║Executed physical command [Lily’s Rainbow].setLevel([4]) (9ms)
+822ms ║╚Execution stage complete. (488ms)
+859ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:23:30 AM EDT (in 14.849s), with 171 more jobs pending
+941ms ╚Event processed successfully (940ms)
3/27/2020, 11:23:03 AM +938ms
+2ms ╔Received event [Home].time = 1585322580866 with a delay of 3070ms
+449ms ║RunTime Analysis CS > 49ms > PS > 318ms > PE > 84ms > CE
+452ms ║Runtime (44266 bytes) successfully initialized in 318ms (v0.3.10c.20190522) (446ms)
+453ms ║╔Execution stage started
+545ms ║║Executed physical command [Book Lamp].setLevel([3]) (10ms)
+576ms ║║Executed physical command [Lily Princess Lamp].setLevel([3]) (8ms)
+605ms ║║Executed physical command [Lily’s Rainbow].setLevel([3]) (7ms)
+636ms ║╚Execution stage complete. (182ms)
+738ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:23:15 AM EDT (in 11.192s), with 174 more jobs pending
+748ms ╚Event processed successfully (748ms)
3/27/2020, 11:22:45 AM +198ms
+1ms ╔Received event [Home].time = 1585322565865 with a delay of -667ms
+371ms ║RunTime Analysis CS > 53ms > PS > 266ms > PE > 52ms > CE
+374ms ║Runtime (44266 bytes) successfully initialized in 266ms (v0.3.10c.20190522) (372ms)
+375ms ║╔Execution stage started
+407ms ║║Executed physical command [Book Lamp].setLevel([2]) (8ms)
+428ms ║║Fast executing schedules, waiting for 270ms to sync up
+710ms ║║Executed physical command [Lily Princess Lamp].setLevel([2]) (9ms)
+749ms ║║Executed physical command [Lily’s Rainbow].setLevel([2]) (8ms)
+783ms ║╚Execution stage complete. (408ms)
+969ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:23:00 AM EDT (in 14.7s), with 177 more jobs pending
+978ms ╚Event processed successfully (978ms)
3/27/2020, 11:22:32 AM +742ms
+9ms ╔Received event [Home].time = 1585322550865 with a delay of 1874ms
+458ms ║RunTime Analysis CS > 58ms > PS > 269ms > PE > 133ms > CE
+462ms ║Runtime (44267 bytes) successfully initialized in 269ms (v0.3.10c.20190522) (446ms)
+463ms ║╔Execution stage started
+570ms ║║Executed physical command [Book Lamp].setLevel([1]) (9ms)
+615ms ║║Executed physical command [Lily Princess Lamp].setLevel([1]) (9ms)
+660ms ║║Executed physical command [Lily’s Rainbow].setLevel([1]) (7ms)
+703ms ║╚Execution stage complete. (240ms)
+923ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:22:45 AM EDT (in 12.202s), with 180 more jobs pending
+933ms ╚Event processed successfully (933ms)
3/27/2020, 11:22:15 AM +177ms
+0ms ╔Received event [Home].time = 1585322516129 with a delay of 19047ms
+454ms ║RunTime Analysis CS > 86ms > PS > 322ms > PE > 47ms > CE
+457ms ║Runtime (44267 bytes) successfully initialized in 322ms (v0.3.10c.20190522) (455ms)
+458ms ║╔Execution stage started
+486ms ║║Executed physical command [Book Lamp].setLevel([10]) (8ms)
+514ms ║║Executed physical command [Lily Princess Lamp].setLevel([10]) (7ms)
+542ms ║║Executed physical command [Lily’s Rainbow].setLevel([10]) (7ms)
+568ms ║║Cancelling statement #5’s schedules…
+603ms ║║Error while executing physical command Book Lamp.setColor([[hue:13, saturation:80, level:0]]): java.lang.IllegalArgumentException: Command ‘setColor’ is not supported by device ac9ae745-8110-418e-a3ff-0d58d6ae4e70 of type ‘Z-Wave Metering Dimmer’. Supported commands: [on, off, poll, configure, setLevel, refresh, ping, off, on, reset]
+605ms ║║Executed virtual command [Book Lamp].setHSLColor (25ms)
+608ms ║║Error while executing physical command Lily Princess Lamp.setColor([[hue:13, saturation:80, level:0]]): java.lang.IllegalArgumentException: Command ‘setColor’ is not supported by device 9c657d48-4fa0-46c7-989f-18b1337f2e0f of type ‘Z-Wave Dimmer Switch Generic’. Supported commands: [on, off, poll, setLevel, refresh, ping, off, on]
+609ms ║║Executed virtual command [Lily Princess Lamp].setHSLColor (3ms)
+618ms ║║Executed physical command [Lily’s Rainbow].setColor([[hue:13, saturation:80, level:0]]) (7ms)
+619ms ║║Executed virtual command [Lily’s Rainbow].setHSLColor (9ms)
+641ms ║║Executed physical command [Book Lamp].setLevel([0]) (10ms)
+705ms ║║Executed virtual command [Book Lamp].fadeLevel (80ms)
+717ms ║║Executed physical command [Lily Princess Lamp].setLevel([0]) (9ms)
+732ms ║║Executed virtual command [Lily Princess Lamp].fadeLevel (26ms)
+742ms ║║Executed physical command [Lily’s Rainbow].setLevel([0]) (8ms)
+758ms ║║Executed virtual command [Lily’s Rainbow].fadeLevel (25ms)
+760ms ║║Requesting a wake up for Fri, Mar 27 2020 @ 11:37:16 AM EDT (in 900.1s)
+913ms ║╚Execution stage complete. (455ms)
+969ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:22:30 AM EDT (in 14.72s), with 183 more jobs pending
+979ms ╚Event processed successfully (980ms)
3/27/2020, 11:22:02 AM +554ms
+10ms ╔Received event [Home].time = 1585322516129 with a delay of 6424ms
+9136ms ║RunTime Analysis CS > 8196ms > PS > 625ms > PE > 316ms > CE
+9139ms ║Runtime (44271 bytes) successfully initialized in 625ms (v0.3.10c.20190522) (9115ms)
+9140ms ║╔Execution stage started
+9166ms ║╚Execution stage complete. (27ms)
+9513ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:21:56 AM EDT (in 1s), with 157 more jobs pending
+9524ms ╚Event processed successfully (9524ms)
3/27/2020, 11:21:41 AM +145ms
+1ms ╔Received event [Home].time = 1585322456128 with a delay of 45017ms
+8413ms ║RunTime Analysis CS > 59ms > PS > 8248ms > PE > 105ms > CE
+8414ms ║Piston waited at a semaphore for 7940ms
+8416ms ║Runtime (44340 bytes) successfully initialized in 8248ms (v0.3.10c.20190522) (8415ms)
+8417ms ║╔Execution stage started
+8418ms ║╚Execution stage complete. (1ms)
+8458ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:21:56 AM EDT (in 6.532s), with 157 more jobs pending
+8468ms ╚Event processed successfully (8468ms)
3/27/2020, 11:21:40 AM +754ms
+1ms ╔Received event [Home].wc_async_reply = httpRequest with a delay of 0ms
+8576ms ║RunTime Analysis CS > 29ms > PS > 8481ms > PE > 66ms > CE
+8577ms ║Piston waited at a semaphore for 8267ms
+8580ms ║Runtime (44343 bytes) successfully initialized in 8481ms (v0.3.10c.20190522) (8578ms)
+8580ms ║╔Execution stage started
+8581ms ║╚Execution stage complete. (1ms)
+8610ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:21:56 AM EDT (in 6.766s), with 157 more jobs pending
+8621ms ╚Event processed successfully (8620ms)
3/27/2020, 11:21:38 AM +992ms
+0ms ╔Received event [Home].execute = recovery with a delay of 96ms
+312ms ║RunTime Analysis CS > 24ms > PS > 226ms > PE > 62ms > CE
+315ms ║Runtime (44262 bytes) successfully initialized in 226ms (v0.3.10c.20190522) (313ms)
+316ms ║╔Execution stage started
+320ms ║║Cancelling statement #10’s schedules…
+325ms ║║Calculating (string) 10.0.0.239:5005 + (string) /preset/Lily_Wake_Up >> (string) 10.0.0.239:5005/preset/Lily_Wake_Up
+332ms ║║Sending internal web request to: 10.0.0.239:5005/preset/Lily_Wake_Up
+335ms ║║Executed virtual command httpRequest (3ms)
+336ms ║║Requesting a wake up for Fri, Mar 27 2020 @ 11:21:59 AM EDT (in 20.0s)
+396ms ║║Executed physical command [Book Lamp].setLevel([6]) (8ms)
+428ms ║║Executed physical command [Lily Princess Lamp].setLevel([6]) (8ms)
+460ms ║║Executed physical command [Lily’s Rainbow].setLevel([6]) (8ms)
+490ms ║║Executed physical command [Book Lamp].setLevel([7]) (6ms)
+519ms ║║Executed physical command [Lily Princess Lamp].setLevel([7]) (6ms)
+546ms ║║Executed physical command [Lily’s Rainbow].setLevel([7]) (5ms)
+574ms ║║Executed physical command [Book Lamp].setLevel([8]) (6ms)
+601ms ║║Executed physical command [Lily Princess Lamp].setLevel([8]) (5ms)
+629ms ║║Executed physical command [Lily’s Rainbow].setLevel([8]) (6ms)
+649ms ║║Fast executing schedules, waiting for 1487ms to sync up
+2146ms ║║Executed physical command [Book Lamp].setLevel([9]) (6ms)
+2174ms ║║Executed physical command [Lily Princess Lamp].setLevel([9]) (5ms)
+2201ms ║║Executed physical command [Lily’s Rainbow].setLevel([9]) (5ms)
+2230ms ║╚Execution stage complete. (1915ms)
+2262ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:21:56 AM EDT (in 14.876s), with 157 more jobs pending
+2271ms ╚Event processed successfully (2271ms)
3/27/2020, 11:21:32 AM +136ms
+0ms ╔Received event [Home].time/recovery = 1585322492131 with a delay of 4ms
+6513ms ║RunTime Analysis CS > 121ms > PS > 6344ms > PE > 48ms > CE
+6514ms ║Piston waited at a semaphore for 6027ms
+6517ms ║Runtime (44345 bytes) successfully initialized in 6344ms (v0.3.10c.20190522) (6516ms)
+6518ms ║╔Execution stage started
+6519ms ║╚Execution stage complete. (1ms)
+6560ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:20:56 AM EDT (in 1s), with 168 more jobs pending
+6568ms ╚Event processed successfully (6569ms)
3/27/2020, 11:20:40 AM +181ms
+0ms ╔Received event [Home].time = 1585322441128 with a delay of -948ms
+633ms ║RunTime Analysis CS > 219ms > PS > 365ms > PE > 49ms > CE
+635ms ║Runtime (44267 bytes) successfully initialized in 365ms (v0.3.10c.20190522) (634ms)
+636ms ║╔Execution stage started
+669ms ║║Executed physical command [Book Lamp].setLevel([5]) (8ms)
+690ms ║║Fast executing schedules, waiting for 278ms to sync up
+1111ms ║║Executed physical command [Lily Princess Lamp].setLevel([5]) (9ms)
+1146ms ║║Executed physical command [Lily’s Rainbow].setLevel([5]) (9ms)
+1184ms ║╚Execution stage complete. (547ms)
+1220ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:20:56 AM EDT (in 14.727s), with 168 more jobs pending
+1229ms ╚Event processed successfully (1229ms)
3/27/2020, 11:20:25 AM +190ms
+1ms ╔Received event [Home].time = 1585322426127 with a delay of -937ms
+555ms ║RunTime Analysis CS > 133ms > PS > 364ms > PE > 57ms > CE
+557ms ║Runtime (44267 bytes) successfully initialized in 364ms (v0.3.10c.20190522) (555ms)
+558ms ║╔Execution stage started
+597ms ║║Executed physical command [Book Lamp].setLevel([4]) (8ms)
+621ms ║║Fast executing schedules, waiting for 339ms to sync up
+972ms ║║Executed physical command [Lily Princess Lamp].setLevel([4]) (8ms)
+1021ms ║║Executed physical command [Lily’s Rainbow].setLevel([4]) (11ms)
+1216ms ║╚Execution stage complete. (511ms)
+1259ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:20:41 AM EDT (in 14.68s), with 171 more jobs pending
+1268ms ╚Event processed successfully (1268ms)
3/27/2020, 11:20:10 AM +234ms
+0ms ╔Received event [Home].time = 1585322411127 with a delay of -894ms
+622ms ║RunTime Analysis CS > 77ms > PS > 498ms > PE > 48ms > CE
+625ms ║Runtime (44266 bytes) successfully initialized in 498ms (v0.3.10c.20190522) (624ms)
+626ms ║╔Execution stage started
+660ms ║║Executed physical command [Book Lamp].setLevel([3]) (11ms)
+681ms ║║Fast executing schedules, waiting for 234ms to sync up
+932ms ║║Executed physical command [Lily Princess Lamp].setLevel([3]) (9ms)
+976ms ║║Executed physical command [Lily’s Rainbow].setLevel([3]) (8ms)
+1010ms ║╚Execution stage complete. (384ms)
+1056ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:20:26 AM EDT (in 14.838s), with 174 more jobs pending
+1065ms ╚Event processed successfully (1066ms)
3/27/2020, 11:19:55 AM +128ms
+1ms ╔Received event [Home].time = 1585322396127 with a delay of -1001ms
+514ms ║RunTime Analysis CS > 65ms > PS > 363ms > PE > 87ms > CE
+516ms ║Runtime (44267 bytes) successfully initialized in 363ms (v0.3.10c.20190522) (514ms)
+518ms ║╔Execution stage started
+553ms ║║Executed physical command [Book Lamp].setLevel([2]) (9ms)
+576ms ║║Fast executing schedules, waiting for 445ms to sync up
+1033ms ║║Executed physical command [Lily Princess Lamp].setLevel([2]) (8ms)
+1065ms ║║Executed physical command [Lily’s Rainbow].setLevel([2]) (7ms)
+1100ms ║╚Execution stage complete. (583ms)
+1141ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:20:11 AM EDT (in 14.86s), with 177 more jobs pending
+1151ms ╚Event processed successfully (1150ms)
3/27/2020, 11:19:40 AM +219ms
+1ms ╔Received event [Home].time = 1585322381127 with a delay of -909ms
+449ms ║RunTime Analysis CS > 62ms > PS > 335ms > PE > 53ms > CE
+453ms ║Runtime (44266 bytes) successfully initialized in 335ms (v0.3.10c.20190522) (450ms)
+454ms ║╔Execution stage started
+491ms ║║Executed physical command [Book Lamp].setLevel([1]) (11ms)
+525ms ║║Fast executing schedules, waiting for 406ms to sync up
+947ms ║║Executed physical command [Lily Princess Lamp].setLevel([1]) (10ms)
+1004ms ║║Executed physical command [Lily’s Rainbow].setLevel([1]) (12ms)
+1056ms ║╚Execution stage complete. (603ms)
+1114ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:19:56 AM EDT (in 14.796s), with 180 more jobs pending
+1126ms ╚Event processed successfully (1126ms)
3/27/2020, 11:19:25 AM +775ms
+1ms ╔Received event [Home].wc_async_reply = httpRequest with a delay of 0ms
+297ms ║RunTime Analysis CS > 35ms > PS > 211ms > PE > 50ms > CE
+299ms ║Runtime (44271 bytes) successfully initialized in 211ms (v0.3.10c.20190522) (298ms)
+300ms ║╔Execution stage started
+316ms ║║Cancelling statement #5’s schedules…
+322ms ║║Error while executing physical command Book Lamp.setColor([[hue:13, saturation:80, level:0]]): java.lang.IllegalArgumentException: Command ‘setColor’ is not supported by device ac9ae745-8110-418e-a3ff-0d58d6ae4e70 of type ‘Z-Wave Metering Dimmer’. Supported commands: [on, off, poll, configure, setLevel, refresh, ping, off, on, reset]
+324ms ║║Executed virtual command [Book Lamp].setHSLColor (3ms)
+327ms ║║Error while executing physical command Lily Princess Lamp.setColor([[hue:13, saturation:80, level:0]]): java.lang.IllegalArgumentException: Command ‘setColor’ is not supported by device 9c657d48-4fa0-46c7-989f-18b1337f2e0f of type ‘Z-Wave Dimmer Switch Generic’. Supported commands: [on, off, poll, setLevel, refresh, ping, off, on]
+329ms ║║Executed virtual command [Lily Princess Lamp].setHSLColor (2ms)
+338ms ║║Executed physical command [Lily’s Rainbow].setColor([[hue:13, saturation:80, level:0]]) (7ms)
+339ms ║║Executed virtual command [Lily’s Rainbow].setHSLColor (9ms)
+351ms ║║Executed physical command [Book Lamp].setLevel([0]) (7ms)
+364ms ║║Executed virtual command [Book Lamp].fadeLevel (20ms)
+373ms ║║Executed physical command [Lily Princess Lamp].setLevel([0]) (8ms)
+386ms ║║Executed virtual command [Lily Princess Lamp].fadeLevel (22ms)
+394ms ║║Executed physical command [Lily’s Rainbow].setLevel([0]) (6ms)
+408ms ║║Executed virtual command [Lily’s Rainbow].fadeLevel (21ms)
+409ms ║║Requesting a wake up for Fri, Mar 27 2020 @ 11:34:26 AM EDT (in 900.1s)
+482ms ║╚Execution stage complete. (182ms)
+514ms ║Setting up scheduled job for Fri, Mar 27 2020 @ 11:19:41 AM EDT (in 14.838s), with 183 more jobs pending
+526ms ╚Event processed successfully (526ms)
3/27/2020, 11:19:23 AM +414ms
+1ms ╔Received event [Home].execute = recovery with a delay of 96ms
+316ms ║RunTime Analysis CS > 24ms > PS > 230ms > PE > 62ms > CE
+319ms ║Runtime (44262 bytes) successfully initialized in 230ms (v0.3.10c.20190522) (318ms)
+320ms ║╔Execution stage started
+324ms ║║Cancelling statement #10’s schedules…
+329ms ║║Calculating (string) 10.0.0.239:5005 + (string) /preset/Lily_Wake_Up >> (string) 10.0.0.239:5005/preset/Lily_Wake_Up
+336ms ║║Sending internal web request to: 10.0.0.239:5005/preset/Lily_Wake_Up
+338ms ║║Executed virtual command httpRequest (3ms)
+340ms ║║Requesting a wake up for Fri, Mar 27 2020 @ 11:19:43 AM EDT (in 20.0s)

Clear

Full


#2

If you look at the logs, each run is incrementing the level by 1. That is how it will accomplish your fade from 0 to 60 over 15 minutes.


#3

Holy smokes, Batman!

Personally, I would avoid this logic like the plague…


#4

I don’t see the piston being fired every fifteen minutes.

What I see is a routine implementing a fade over 180 steps at fifteen seconds intervals. That in itself isn’t necessarily a problem, though it is a bit frightening.

What is a problem is that at least twice the piston has been fired as a ‘recovery’. That means a scheduled run of the piston has either not happened or has been significantly delayed (such as by thirty seconds or more). As pistons look after their own scheduling this would mean a piston that should be run at a certain time wouldn’t be, and so the piston is fired so it can sort things out.

Unfortunately your piston doesn’t realise that is is running a recovery and just starts the web request and fade sequence all over again.

The way to go is to have the piston set its own schedule instead of being executed from elsewhere.

The webCoRE dashboard has a ‘Test’ button for each piston. That is useful for finding out how your piston reacts to unexpected events.


#5

Ok, here’s what I changed this piston to…any potential concerns anybody can see?