Timer Piston not changing location mode


#1

1) Give a description of the problem
Simple timer based piston doesnt always change the location mode
2) What is the expected behaviour?
based on a time of day the piston should change the location mode between, Day, Evening, Night
3) What is happening/not happening?
Often, the piston executes but does not change the location mode
**4) Post a Green Snapshot of the piston!

5) Attach logs after turning logging level to Full
19/04/2020, 20:24:59 +46ms
+1ms ╔Received event [116].time = 1587324300000 with a delay of -954ms
+99ms ║RunTime Analysis CS > 19ms > PS > 39ms > PE > 41ms > CE
+102ms ║Runtime (37853 bytes) successfully initialized in 39ms (v0.3.110.20191009) (101ms)
+103ms ║╔Execution stage started
+143ms ║║Cancelling statement #15’s schedules…
+271ms ║║Executed virtual command setLocationMode (125ms)
+275ms ║╚Execution stage complete. (172ms)
+277ms ║Setting up scheduled job for Sun, Apr 19 2020 @ 11:55:00 PM BST (in 12600.678s), with 2 more jobs pending
+285ms ╚Event processed successfully (285ms)
19/04/2020, 06:16:59 +45ms
+0ms ╔Received event [116].time = 1587273420000 with a delay of -956ms
+82ms ║RunTime Analysis CS > 17ms > PS > 36ms > PE > 29ms > CE
+84ms ║Runtime (37853 bytes) successfully initialized in 36ms (v0.3.110.20191009) (83ms)
+85ms ║╔Execution stage started
+116ms ║║Cancelling statement #12’s schedules…
+183ms ║║Executed virtual command setLocationMode (65ms)
+187ms ║╚Execution stage complete. (102ms)
+189ms ║Setting up scheduled job for Sun, Apr 19 2020 @ 8:25:00 PM BST (in 50880.767s), with 2 more jobs pending
+196ms ╚Event processed successfully (195ms)
18/04/2020, 23:54:59 +69ms
+1ms ╔Received event [116].time = 1587250500000 with a delay of -931ms
+115ms ║RunTime Analysis CS > 28ms > PS > 50ms > PE > 36ms > CE
+117ms ║Runtime (37854 bytes) successfully initialized in 50ms (v0.3.110.20191009) (115ms)
+118ms ║╔Execution stage started
+129ms ║║Cancelling statement #9’s schedules…
+312ms ║║Executed virtual command setLocationMode (181ms)
+315ms ║╚Execution stage complete. (197ms)
+317ms ║Setting up scheduled job for Sun, Apr 19 2020 @ 6:17:00 AM BST (in 22920.614s), with 2 more jobs pending
+325ms ╚Event processed successfully (325ms)
18/04/2020, 20:22:59 +77ms
+0ms ╔Received event [116].time = 1587237780000 with a delay of -924ms
+114ms ║RunTime Analysis CS > 20ms > PS > 45ms > PE > 50ms > CE
+117ms ║Runtime (37854 bytes) successfully initialized in 45ms (v0.3.110.20191009) (116ms)
+118ms ║╔Execution stage started
+159ms ║║Cancelling statement #15’s schedules…
+289ms ║║Executed virtual command setLocationMode (126ms)
+292ms ║╚Execution stage complete. (174ms)
+294ms ║Setting up scheduled job for Sat, Apr 18 2020 @ 11:55:00 PM BST (in 12720.63s), with 2 more jobs pending
+302ms ╚Event processed successfully (302ms)
18/04/2020, 06:18:59 +71ms
+0ms ╔Received event [116].time = 1587187140000 with a delay of -930ms
+115ms ║RunTime Analysis CS > 25ms > PS > 51ms > PE > 40ms > CE
+117ms ║Runtime (37854 bytes) successfully initialized in 51ms (v0.3.110.20191009) (116ms)
+118ms ║╔Execution stage started
+156ms ║║Cancelling statement #12’s schedules…
+246ms ║║Executed virtual command setLocationMode (88ms)
+250ms ║╚Execution stage complete. (132ms)
+252ms ║Setting up scheduled job for Sat, Apr 18 2020 @ 8:23:00 PM BST (in 50640.678s), with 2 more jobs pending
+259ms ╚Event processed successfully (259ms)
17/04/2020, 23:54:59 +68ms
+1ms ╔Received event [116].time = 1587164100000 with a delay of -932ms
+109ms ║RunTime Analysis CS > 21ms > PS > 46ms > PE > 41ms > CE
+112ms ║Runtime (37854 bytes) successfully initialized in 46ms (v0.3.110.20191009) (109ms)
+113ms ║╔Execution stage started
+125ms ║║Cancelling statement #9’s schedules…
+259ms ║║Executed virtual command setLocationMode (132ms)
+263ms ║╚Execution stage complete. (151ms)
+265ms ║Setting up scheduled job for Sat, Apr 18 2020 @ 6:19:00 AM BST (in 23040.668s), with 2 more jobs pending
+273ms ╚Event processed successfully (273ms)
17/04/2020, 20:21:59 +73ms
+0ms ╔Received event [116].time = 1587151320000 with a delay of -928ms
+126ms ║RunTime Analysis CS > 23ms > PS > 46ms > PE > 57ms > CE
+129ms ║Runtime (37854 bytes) successfully initialized in 46ms (v0.3.110.20191009) (128ms)
+130ms ║╔Execution stage started
+170ms ║║Cancelling statement #15’s schedules…
+449ms ║║Executed virtual command setLocationMode (276ms)
+452ms ║╚Execution stage complete. (322ms)
+454ms ║Setting up scheduled job for Fri, Apr 17 2020 @ 11:55:00 PM BST (in 12780.473s), with 2 more jobs pending
+463ms ╚Event processed successfully (463ms)
17/04/2020, 06:20:59 +52ms
+1ms ╔Received event [116].time = 1587100860000 with a delay of -948ms
+108ms ║RunTime Analysis CS > 21ms > PS > 48ms > PE > 39ms > CE
+112ms ║Runtime (37854 bytes) successfully initialized in 48ms (v0.3.110.20191009) (109ms)
+113ms ║╔Execution stage started
+160ms ║║Cancelling statement #12’s schedules…
+265ms ║║Executed virtual command setLocationMode (101ms)
+270ms ║╚Execution stage complete. (157ms)
+273ms ║Setting up scheduled job for Fri, Apr 17 2020 @ 8:22:00 PM BST (in 50460.676s), with 2 more jobs pending
+281ms ╚Event processed successfully (280ms)
16/04/2020, 23:54:59 +323ms
+1ms ╔Received event [116].time = 1587077700000 with a delay of -677ms
+111ms ║RunTime Analysis CS > 31ms > PS > 49ms > PE > 31ms > CE
+113ms ║Runtime (37854 bytes) successfully initialized in 49ms (v0.3.110.20191009) (112ms)
+114ms ║╔Execution stage started
+125ms ║║Cancelling statement #9’s schedules…
+355ms ║║Executed virtual command setLocationMode (228ms)
+359ms ║╚Execution stage complete. (244ms)
+361ms ║Setting up scheduled job for Fri, Apr 17 2020 @ 6:21:00 AM BST (in 23160.317s), with 2 more jobs pending
+368ms ╚Event processed successfully (368ms)
16/04/2020, 20:19:59 +71ms
+0ms ╔Received event [116].time = 1587064800000 with a delay of -929ms
+104ms ║RunTime Analysis CS > 20ms > PS > 47ms > PE > 37ms > CE
+106ms ║Runtime (37854 bytes) successfully initialized in 47ms (v0.3.110.20191009) (105ms)
+107ms ║╔Execution stage started
+145ms ║║Cancelling statement #15’s schedules…
+295ms ║║Executed virtual command setLocationMode (148ms)
+299ms ║╚Execution stage complete. (192ms)
+301ms ║Setting up scheduled job for Thu, Apr 16 2020 @ 11:55:00 PM BST (in 12900.629s), with 2 more jobs pending
+309ms ╚Event processed successfully (309ms)
16/04/2020, 06:23:59 +51ms
+0ms ╔Received event [116].time = 1587014640000 with a delay of -950ms
+100ms ║RunTime Analysis CS > 26ms > PS > 45ms > PE > 29ms > CE
+102ms ║Runtime (37854 bytes) successfully initialized in 45ms (v0.3.110.20191009) (101ms)
+103ms ║╔Execution stage started
+137ms ║║Cancelling statement #12’s schedules…
+157ms ║║Executed virtual command setLocationMode (18ms)
+160ms ║╚Execution stage complete. (57ms)
+162ms ║Setting up scheduled job for Thu, Apr 16 2020 @ 8:20:00 PM BST (in 50160.787s), with 2 more jobs pending
+170ms ╚Event processed successfully (170ms)
15/04/2020, 23:54:59 +59ms
+1ms ╔Received event [116].time = 1586991300000 with a delay of -941ms
+100ms ║RunTime Analysis CS > 20ms > PS > 45ms > PE > 34ms > CE
+102ms ║Runtime (37854 bytes) successfully initialized in 45ms (v0.3.110.20191009) (101ms)
+103ms ║╔Execution stage started
+114ms ║║Cancelling statement #9’s schedules…
+270ms ║║Executed virtual command setLocationMode (154ms)
+273ms ║╚Execution stage complete. (171ms)
+275ms ║Setting up scheduled job for Thu, Apr 16 2020 @ 6:24:00 AM BST (in 23340.666s), with 2 more jobs pending
+284ms ╚Event processed successfully (283ms)
15/04/2020, 20:17:59 +67ms
+1ms ╔Received event [116].time = 1586978280000 with a delay of -933ms
+92ms ║RunTime Analysis CS > 22ms > PS > 46ms > PE > 24ms > CE
+94ms ║Runtime (37853 bytes) successfully initialized in 46ms (v0.3.110.20191009) (92ms)
+95ms ║╔Execution stage started
+131ms ║║Cancelling statement #15’s schedules…
+646ms ║║Executed virtual command setLocationMode (513ms)
+649ms ║╚Execution stage complete. (554ms)
+651ms ║Setting up scheduled job for Wed, Apr 15 2020 @ 11:55:00 PM BST (in 13020.282s), with 2 more jobs pending
+658ms ╚Event processed successfully (658ms)
15/04/2020, 06:25:59 +53ms
+0ms ╔Received event [116].time = 1586928360000 with a delay of -948ms
+96ms ║RunTime Analysis CS > 27ms > PS > 42ms > PE > 28ms > CE
+99ms ║Runtime (37853 bytes) successfully initialized in 42ms (v0.3.110.20191009) (97ms)
+99ms ║╔Execution stage started
+129ms ║║Cancelling statement #12’s schedules…
+150ms ║║Executed virtual command setLocationMode (18ms)
+153ms ║╚Execution stage complete. (54ms)
+155ms ║Setting up scheduled job for Wed, Apr 15 2020 @ 8:18:00 PM BST (in 49920.793s), with 2 more jobs pending
+162ms ╚Event processed successfully (162ms)
14/04/2020, 23:54:59 +76ms
+0ms ╔Received event [116].time = 1586904900000 with a delay of -924ms
+98ms ║RunTime Analysis CS > 19ms > PS > 44ms > PE > 34ms > CE
+100ms ║Runtime (37853 bytes) successfully initialized in 44ms (v0.3.110.20191009) (99ms)
+101ms ║╔Execution stage started
+112ms ║║Cancelling statement #9’s schedules…
+222ms ║║Executed virtual command setLocationMode (108ms)
+226ms ║╚Execution stage complete. (125ms)
+228ms ║Setting up scheduled job for Wed, Apr 15 2020 @ 6:26:00 AM BST (in 23460.697s), with 2 more jobs pending
+235ms ╚Event processed successfully (235ms)
14/04/2020, 20:15:59 +61ms
+0ms ╔Received event [116].time = 1586891760000 with a delay of -939ms
+101ms ║RunTime Analysis CS > 20ms > PS > 44ms > PE > 37ms > CE
+104ms ║Runtime (37854 bytes) successfully initialized in 44ms (v0.3.110.20191009) (102ms)
+105ms ║╔Execution stage started
+142ms ║║Cancelling statement #15’s schedules…
+297ms ║║Executed virtual command setLocationMode (152ms)
+300ms ║╚Execution stage complete. (196ms)
+302ms ║Setting up scheduled job for Tue, Apr 14 2020 @ 11:55:00 PM BST (in 13140.637s), with 2 more jobs pending
+309ms ╚Event processed successfully (309ms)
14/04/2020, 06:27:59 +56ms
+1ms ╔Received event [116].time = 1586842080000 with a delay of -944ms
+241ms ║RunTime Analysis CS > 30ms > PS > 47ms > PE > 164ms > CE
+244ms ║Runtime (37855 bytes) successfully initialized in 47ms (v0.3.110.20191009) (243ms)
+245ms ║╔Execution stage started
+291ms ║║Cancelling statement #12’s schedules…
+320ms ║║Executed virtual command setLocationMode (26ms)
+324ms ║╚Execution stage complete. (78ms)
+326ms ║Setting up scheduled job for Tue, Apr 14 2020 @ 8:16:00 PM BST (in 49680.619s), with 2 more jobs pending
+332ms ╚Event processed successfully (332ms)

REMOVE BELOW AFTER READING


#2

Your piston looks solid…

Every single event above shows this:
║Executed virtual command setLocationMode

Are you sure that no other automation changes locationMode?