Lock piston not working all the time


#1

1) Give a description of the problem
(PUT YOUR INFO HERE)
Piston works sometimes and other times not working

2) What is the expected behaviour?
(PUT YOUR INFO HERE)

Whenever a code is used to unlock lock it will turn on a light after sunset and turn off the light 2 minutes after

3) What is happening/not happening?
(PUT YOUR INFO HERE)
Light is not turning off after 2 minutes

4) Post a Green Snapshot of the pistonimage
(UPLOAD YOUR IMAGE HERE)

5) Attach logs after turning logging level to Full
(PASTE YOUR LOGS HERE THEN HIGHLIGHT ALL OF THE LOGS AND CLICK ON THE </> ICON TO FORMAT THEM CORRECTLY)

2022-02-13, 9:34:55 AM +168ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 49ms
+65ms ║RunTime Analysis CS > 19ms > PS > 5ms > PE > 40ms > CE
+67ms ║Runtime (36909 bytes) successfully initialized in 5ms (v0.3.113.20210203) (65ms)
+68ms ║╔Execution stage started
+79ms ║║Comparison (enum) unlocked gets (string) unlocked = false (4ms)
+80ms ║║Condition #2 evaluated false (8ms)
+81ms ║║Condition group #1 evaluated false (state did not change) (9ms)
+83ms ║╚Execution stage complete. (15ms)
+84ms ╚Event processed successfully (84ms)
2022-02-13, 12:06:03 AM +190ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 45ms
+61ms ║RunTime Analysis CS > 17ms > PS > 5ms > PE > 39ms > CE
+63ms ║Runtime (36908 bytes) successfully initialized in 5ms (v0.3.113.20210203) (61ms)
+64ms ║╔Execution stage started
+71ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+72ms ║║Cancelling condition #2’s schedules…
+73ms ║║Condition #2 evaluated false (5ms)
+74ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+75ms ║╚Execution stage complete. (11ms)
+76ms ╚Event processed successfully (76ms)
2022-02-13, 12:05:51 AM +874ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 49ms
+62ms ║RunTime Analysis CS > 19ms > PS > 5ms > PE > 39ms > CE
+65ms ║Runtime (36910 bytes) successfully initialized in 5ms (v0.3.113.20210203) (62ms)
+65ms ║╔Execution stage started
+76ms ║║Comparison (enum) unlocked gets (string) unlocked = true (4ms)
+77ms ║║Cancelling condition #2’s schedules…
+78ms ║║Condition #2 evaluated true (8ms)
+107ms ║║Comparison (time) 351953 is_after (time) 1644792300000 = false (6ms)
+109ms ║║Cancelling condition #6’s schedules…
+110ms ║║Condition #6 evaluated false (30ms)
+111ms ║║Condition group #1 evaluated false (state did not change) (41ms)
+112ms ║╚Execution stage complete. (47ms)
+114ms ╚Event processed successfully (113ms)
2022-02-12, 9:20:19 PM +475ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 45ms
+64ms ║RunTime Analysis CS > 18ms > PS > 5ms > PE > 41ms > CE
+67ms ║Runtime (36905 bytes) successfully initialized in 5ms (v0.3.113.20210203) (64ms)
+67ms ║╔Execution stage started
+74ms ║║Comparison (enum) locked gets (string) unlocked = false (0ms)
+76ms ║║Cancelling condition #2’s schedules…
+77ms ║║Condition #2 evaluated false (5ms)
+78ms ║║Cancelling condition #1’s schedules…
+79ms ║║Condition group #1 evaluated false (state changed) (8ms)
+81ms ║╚Execution stage complete. (14ms)
+88ms ╚Event processed successfully (87ms)
2022-02-12, 9:20:14 PM +781ms
+2ms ╔Received event [Front Door].lock = unlocked with a delay of 51ms
+77ms ║RunTime Analysis CS > 32ms > PS > 6ms > PE > 39ms > CE
+80ms ║Runtime (36911 bytes) successfully initialized in 6ms (v0.3.113.20210203) (77ms)
+81ms ║╔Execution stage started
+92ms ║║Comparison (enum) unlocked gets (string) unlocked = true (5ms)
+93ms ║║Cancelling condition #2’s schedules…
+94ms ║║Condition #2 evaluated true (9ms)
+126ms ║║Comparison (time) 76814876 is_after (time) 1644705840000 = true (6ms)
+127ms ║║Time restriction check passed
+128ms ║║Cancelling condition #6’s schedules…
+129ms ║║Condition #6 evaluated true (34ms)
+130ms ║║Cancelling condition #1’s schedules…
+131ms ║║Condition group #1 evaluated true (state changed) (46ms)
+133ms ║║Cancelling statement #3’s schedules…
+155ms ║║Executed physical command [Foyer].on() (18ms)
+156ms ║║Executed [Foyer].on (20ms)
+161ms ║║Executed virtual command [Foyer].wait (0ms)
+162ms ║║Requesting a wake up for Sat, Feb 12 2022 @ 9:22:14 PM EST (in 120.0s)
+166ms ║╚Execution stage complete. (86ms)
+168ms ║Setting up scheduled job for Sat, Feb 12 2022 @ 9:22:14 PM EST (in 119s)
+175ms ╚Event processed successfully (174ms)
2022-02-12, 8:57:13 PM +923ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 60ms
+67ms ║RunTime Analysis CS > 21ms > PS > 4ms > PE > 41ms > CE
+69ms ║Runtime (36911 bytes) successfully initialized in 4ms (v0.3.113.20210203) (67ms)
+70ms ║╔Execution stage started
+79ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+81ms ║║Condition #2 evaluated false (5ms)
+82ms ║║Condition group #1 evaluated false (state did not change) (7ms)
+84ms ║╚Execution stage complete. (14ms)
+86ms ╚Event processed successfully (85ms)
2022-02-12, 8:55:58 PM +153ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 46ms
+67ms ║RunTime Analysis CS > 25ms > PS > 6ms > PE > 36ms > CE
+69ms ║Runtime (36911 bytes) successfully initialized in 6ms (v0.3.113.20210203) (67ms)
+70ms ║╔Execution stage started
+78ms ║║Comparison (enum) unlocked gets (string) unlocked = false (2ms)
+80ms ║║Condition #2 evaluated false (6ms)
+81ms ║║Condition group #1 evaluated false (state did not change) (7ms)
+82ms ║╚Execution stage complete. (13ms)
+83ms ╚Event processed successfully (83ms)
2022-02-12, 4:23:31 PM +51ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 78ms
+66ms ║RunTime Analysis CS > 22ms > PS > 4ms > PE > 40ms > CE
+68ms ║Runtime (36911 bytes) successfully initialized in 4ms (v0.3.113.20210203) (67ms)
+69ms ║╔Execution stage started
+77ms ║║Comparison (enum) locked gets (string) unlocked = false (0ms)
+78ms ║║Condition #2 evaluated false (4ms)
+79ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+81ms ║╚Execution stage complete. (12ms)
+82ms ╚Event processed successfully (82ms)
2022-02-12, 4:23:19 PM +741ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 43ms
+64ms ║RunTime Analysis CS > 21ms > PS > 6ms > PE > 36ms > CE
+66ms ║Runtime (36911 bytes) successfully initialized in 6ms (v0.3.113.20210203) (63ms)
+67ms ║╔Execution stage started
+76ms ║║Comparison (enum) unlocked gets (string) unlocked = false (2ms)
+77ms ║║Condition #2 evaluated false (6ms)
+78ms ║║Condition group #1 evaluated false (state did not change) (8ms)
+80ms ║╚Execution stage complete. (14ms)
+81ms ╚Event processed successfully (81ms)
2022-02-12, 2:53:23 PM +221ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 49ms
+64ms ║RunTime Analysis CS > 22ms > PS > 5ms > PE > 36ms > CE
+66ms ║Runtime (36910 bytes) successfully initialized in 5ms (v0.3.113.20210203) (63ms)
+67ms ║╔Execution stage started
+75ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+76ms ║║Cancelling condition #2’s schedules…
+77ms ║║Condition #2 evaluated false (5ms)
+78ms ║║Condition group #1 evaluated false (state did not change) (7ms)
+80ms ║╚Execution stage complete. (13ms)
+81ms ╚Event processed successfully (81ms)
2022-02-12, 2:53:17 PM +872ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 96ms
+67ms ║RunTime Analysis CS > 24ms > PS > 7ms > PE > 36ms > CE
+69ms ║Runtime (36910 bytes) successfully initialized in 7ms (v0.3.113.20210203) (67ms)
+70ms ║╔Execution stage started
+81ms ║║Comparison (enum) unlocked gets (string) unlocked = true (3ms)
+83ms ║║Cancelling condition #2’s schedules…
+84ms ║║Condition #2 evaluated true (8ms)
+118ms ║║Comparison (time) 53597957 is_after (time) 1644705840000 = false (7ms)
+119ms ║║Cancelling condition #6’s schedules…
+121ms ║║Condition #6 evaluated false (35ms)
+122ms ║║Condition group #1 evaluated false (state did not change) (46ms)
+124ms ║╚Execution stage complete. (54ms)
+125ms ╚Event processed successfully (126ms)
2022-02-12, 12:05:12 PM +565ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 54ms
+65ms ║RunTime Analysis CS > 21ms > PS > 7ms > PE > 36ms > CE
+67ms ║Runtime (36910 bytes) successfully initialized in 7ms (v0.3.113.20210203) (64ms)
+68ms ║╔Execution stage started
+76ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+78ms ║║Condition #2 evaluated false (5ms)
+79ms ║║Condition group #1 evaluated false (state did not change) (7ms)
+81ms ║╚Execution stage complete. (13ms)
+82ms ╚Event processed successfully (82ms)
2022-02-12, 12:05:02 PM +672ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 54ms
+70ms ║RunTime Analysis CS > 28ms > PS > 4ms > PE > 37ms > CE
+72ms ║Runtime (36910 bytes) successfully initialized in 4ms (v0.3.113.20210203) (70ms)
+73ms ║╔Execution stage started
+84ms ║║Comparison (enum) unlocked gets (string) unlocked = false (4ms)
+86ms ║║Condition #2 evaluated false (8ms)
+87ms ║║Condition group #1 evaluated false (state did not change) (9ms)
+88ms ║╚Execution stage complete. (16ms)
+89ms ╚Event processed successfully (89ms)
2022-02-12, 11:23:50 AM +253ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 48ms
+64ms ║RunTime Analysis CS > 18ms > PS > 5ms > PE > 40ms > CE
+66ms ║Runtime (36910 bytes) successfully initialized in 5ms (v0.3.113.20210203) (64ms)
+67ms ║╔Execution stage started
+74ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+76ms ║║Condition #2 evaluated false (5ms)
+77ms ║║Condition group #1 evaluated false (state did not change) (5ms)
+78ms ║╚Execution stage complete. (11ms)
+79ms ╚Event processed successfully (79ms)
2022-02-12, 11:23:41 AM +160ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 45ms
+69ms ║RunTime Analysis CS > 31ms > PS > 7ms > PE > 32ms > CE
+72ms ║Runtime (36910 bytes) successfully initialized in 7ms (v0.3.113.20210203) (70ms)
+73ms ║╔Execution stage started
+84ms ║║Comparison (enum) unlocked gets (string) unlocked = false (4ms)
+86ms ║║Condition #2 evaluated false (8ms)
+87ms ║║Condition group #1 evaluated false (state did not change) (10ms)
+89ms ║╚Execution stage complete. (15ms)
+90ms ╚Event processed successfully (90ms)
2022-02-12, 9:34:33 AM +132ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 50ms
+58ms ║RunTime Analysis CS > 20ms > PS > 6ms > PE > 32ms > CE
+60ms ║Runtime (36910 bytes) successfully initialized in 6ms (v0.3.113.20210203) (57ms)
+61ms ║╔Execution stage started
+68ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+69ms ║║Condition #2 evaluated false (4ms)
+70ms ║║Condition group #1 evaluated false (state did not change) (5ms)
+72ms ║╚Execution stage complete. (11ms)
+73ms ╚Event processed successfully (73ms)
2022-02-12, 9:34:21 AM +686ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 59ms
+69ms ║RunTime Analysis CS > 21ms > PS > 4ms > PE > 44ms > CE
+72ms ║Runtime (36910 bytes) successfully initialized in 4ms (v0.3.113.20210203) (70ms)
+73ms ║╔Execution stage started
+85ms ║║Comparison (enum) unlocked gets (string) unlocked = false (4ms)
+86ms ║║Condition #2 evaluated false (9ms)
+87ms ║║Condition group #1 evaluated false (state did not change) (10ms)
+89ms ║╚Execution stage complete. (17ms)
+91ms ╚Event processed successfully (91ms)
2022-02-11, 10:23:38 PM +160ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 48ms
+60ms ║RunTime Analysis CS > 22ms > PS > 6ms > PE > 31ms > CE
+62ms ║Runtime (36905 bytes) successfully initialized in 6ms (v0.3.113.20210203) (59ms)
+63ms ║╔Execution stage started
+70ms ║║Comparison (enum) locked gets (string) unlocked = false (0ms)
+72ms ║║Cancelling condition #2’s schedules…
+73ms ║║Condition #2 evaluated false (6ms)
+74ms ║║Cancelling condition #1’s schedules…
+75ms ║║Condition group #1 evaluated false (state changed) (8ms)
+77ms ║╚Execution stage complete. (15ms)
+83ms ╚Event processed successfully (82ms)
2022-02-11, 10:23:24 PM +96ms
+2ms ╔Received event [Front Door].lock = unlocked with a delay of 50ms
+59ms ║RunTime Analysis CS > 22ms > PS > 5ms > PE > 32ms > CE
+62ms ║Runtime (36910 bytes) successfully initialized in 5ms (v0.3.113.20210203) (58ms)
+63ms ║╔Execution stage started
+71ms ║║Comparison (enum) unlocked gets (string) unlocked = true (2ms)
+73ms ║║Cancelling condition #2’s schedules…
+74ms ║║Condition #2 evaluated true (7ms)
+105ms ║║Comparison (time) 80604171 is_after (time) 1644619320000 = true (7ms)
+106ms ║║Time restriction check passed
+108ms ║║Condition #6 evaluated true (32ms)
+109ms ║║Cancelling condition #1’s schedules…
+110ms ║║Condition group #1 evaluated true (state changed) (42ms)
+112ms ║║Cancelling statement #3’s schedules…
+127ms ║║Executed physical command [Foyer].on() (12ms)
+128ms ║║Executed [Foyer].on (13ms)
+133ms ║║Executed virtual command [Foyer].wait (1ms)
+134ms ║║Requesting a wake up for Fri, Feb 11 2022 @ 10:25:24 PM EST (in 120.0s)
+138ms ║╚Execution stage complete. (76ms)
+139ms ║Setting up scheduled job for Fri, Feb 11 2022 @ 10:25:24 PM EST (in 119s)
+148ms ╚Event processed successfully (148ms)
2022-02-11, 10:12:24 PM +996ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 55ms
+59ms ║RunTime Analysis CS > 18ms > PS > 5ms > PE > 35ms > CE
+61ms ║Runtime (36905 bytes) successfully initialized in 5ms (v0.3.113.20210203) (59ms)
+62ms ║╔Execution stage started
+69ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+70ms ║║Cancelling condition #2’s schedules…
+71ms ║║Condition #2 evaluated false (5ms)
+72ms ║║Cancelling condition #1’s schedules…
+73ms ║║Condition group #1 evaluated false (state changed) (7ms)
+75ms ║╚Execution stage complete. (13ms)
+81ms ╚Event processed successfully (81ms)
2022-02-11, 10:12:19 PM +352ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 45ms
+63ms ║RunTime Analysis CS > 27ms > PS > 5ms > PE > 31ms > CE
+65ms ║Runtime (36911 bytes) successfully initialized in 5ms (v0.3.113.20210203) (63ms)
+66ms ║╔Execution stage started
+76ms ║║Comparison (enum) unlocked gets (string) unlocked = true (3ms)
+78ms ║║Cancelling condition #2’s schedules…
+78ms ║║Condition #2 evaluated true (7ms)
+107ms ║║Comparison (time) 79939431 is_after (time) 1644619320000 = true (7ms)
+108ms ║║Time restriction check passed
+110ms ║║Cancelling condition #6’s schedules…
+111ms ║║Condition #6 evaluated true (32ms)
+113ms ║║Cancelling condition #1’s schedules…
+114ms ║║Condition group #1 evaluated true (state changed) (43ms)
+116ms ║║Cancelling statement #3’s schedules…
+132ms ║║Executed physical command [Foyer].on() (11ms)
+132ms ║║Executed [Foyer].on (13ms)
+136ms ║║Executed virtual command [Foyer].wait (0ms)
+137ms ║║Requesting a wake up for Fri, Feb 11 2022 @ 10:14:19 PM EST (in 120.0s)
+142ms ║╚Execution stage complete. (75ms)
+143ms ║Setting up scheduled job for Fri, Feb 11 2022 @ 10:14:19 PM EST (in 119s)
+150ms ╚Event processed successfully (150ms)
2022-02-11, 6:52:18 PM +13ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 50ms
+64ms ║RunTime Analysis CS > 23ms > PS > 6ms > PE > 35ms > CE
+67ms ║Runtime (36911 bytes) successfully initialized in 6ms (v0.3.113.20210203) (63ms)
+68ms ║╔Execution stage started
+75ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+76ms ║║Condition #2 evaluated false (4ms)
+77ms ║║Condition group #1 evaluated false (state did not change) (5ms)
+79ms ║╚Execution stage complete. (12ms)
+80ms ╚Event processed successfully (80ms)
2022-02-11, 6:52:10 PM +188ms
+2ms ╔Received event [Front Door].lock = unlocked with a delay of 49ms
+71ms ║RunTime Analysis CS > 31ms > PS > 6ms > PE > 34ms > CE
+74ms ║Runtime (36911 bytes) successfully initialized in 6ms (v0.3.113.20210203) (71ms)
+75ms ║╔Execution stage started
+86ms ║║Comparison (enum) unlocked gets (string) unlocked = false (4ms)
+88ms ║║Condition #2 evaluated false (9ms)
+89ms ║║Condition group #1 evaluated false (state did not change) (10ms)
+91ms ║╚Execution stage complete. (17ms)
+92ms ╚Event processed successfully (92ms)
2022-02-11, 6:30:28 PM +760ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 93ms
+57ms ║RunTime Analysis CS > 19ms > PS > 4ms > PE > 34ms > CE
+59ms ║Runtime (36911 bytes) successfully initialized in 4ms (v0.3.113.20210203) (57ms)
+60ms ║╔Execution stage started
+67ms ║║Comparison (enum) locked gets (string) unlocked = false (0ms)
+69ms ║║Condition #2 evaluated false (5ms)
+70ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+72ms ║╚Execution stage complete. (11ms)
+73ms ╚Event processed successfully (72ms)
2022-02-11, 6:30:18 PM +274ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 70ms
+65ms ║RunTime Analysis CS > 22ms > PS > 4ms > PE > 39ms > CE
+67ms ║Runtime (36911 bytes) successfully initialized in 4ms (v0.3.113.20210203) (65ms)
+68ms ║╔Execution stage started
+79ms ║║Comparison (enum) unlocked gets (string) unlocked = false (4ms)
+81ms ║║Condition #2 evaluated false (8ms)
+82ms ║║Condition group #1 evaluated false (state did not change) (10ms)
+84ms ║╚Execution stage complete. (16ms)
+85ms ╚Event processed successfully (85ms)
2022-02-11, 2:51:03 PM +333ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 52ms
+55ms ║RunTime Analysis CS > 21ms > PS > 6ms > PE > 27ms > CE
+57ms ║Runtime (36910 bytes) successfully initialized in 6ms (v0.3.113.20210203) (55ms)
+58ms ║╔Execution stage started
+65ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+66ms ║║Cancelling condition #2’s schedules…
+67ms ║║Condition #2 evaluated false (5ms)
+68ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+70ms ║╚Execution stage complete. (11ms)
+71ms ╚Event processed successfully (70ms)
2022-02-11, 2:45:56 PM +327ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 107ms
+55ms ║RunTime Analysis CS > 20ms > PS > 3ms > PE > 32ms > CE
+57ms ║Runtime (36912 bytes) successfully initialized in 3ms (v0.3.113.20210203) (55ms)
+58ms ║╔Execution stage started
+68ms ║║Comparison (enum) unlocked gets (string) unlocked = true (4ms)
+70ms ║║Cancelling condition #2’s schedules…
+71ms ║║Condition #2 evaluated true (8ms)
+99ms ║║Comparison (time) 53156399 is_after (time) 1644619320000 = false (6ms)
+100ms ║║Condition #6 evaluated false (29ms)
+101ms ║║Condition group #1 evaluated false (state did not change) (39ms)
+103ms ║╚Execution stage complete. (45ms)
+104ms ╚Event processed successfully (104ms)
2022-02-11, 1:11:10 PM +293ms
+1ms ╔Received event [Front Door].lock = locked with a delay of 42ms
+51ms ║RunTime Analysis CS > 19ms > PS > 5ms > PE > 28ms > CE
+53ms ║Runtime (36911 bytes) successfully initialized in 5ms (v0.3.113.20210203) (51ms)
+54ms ║╔Execution stage started
+61ms ║║Comparison (enum) locked gets (string) unlocked = false (0ms)
+63ms ║║Condition #2 evaluated false (5ms)
+64ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+66ms ║╚Execution stage complete. (11ms)
+67ms ╚Event processed successfully (67ms)
2022-02-11, 1:11:02 PM +612ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 48ms
+64ms ║RunTime Analysis CS > 28ms > PS > 5ms > PE > 31ms > CE
+66ms ║Runtime (36911 bytes) successfully initialized in 5ms (v0.3.113.20210203) (64ms)
+67ms ║╔Execution stage started
+76ms ║║Comparison (enum) unlocked gets (string) unlocked = false (2ms)
+77ms ║║Condition #2 evaluated false (6ms)
+78ms ║║Condition group #1 evaluated false (state did not change) (8ms)
+80ms ║╚Execution stage complete. (12ms)
+81ms ╚Event processed successfully (80ms)
2022-02-11, 12:03:29 PM +443ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 48ms
+52ms ║RunTime Analysis CS > 19ms > PS > 5ms > PE > 27ms > CE
+54ms ║Runtime (36910 bytes) successfully initialized in 5ms (v0.3.113.20210203) (52ms)
+55ms ║╔Execution stage started
+62ms ║║Comparison (enum) locked gets (string) unlocked = false (1ms)
+64ms ║║Cancelling condition #2’s schedules…
+64ms ║║Condition #2 evaluated false (5ms)
+65ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+67ms ║╚Execution stage complete. (12ms)
+68ms ╚Event processed successfully (68ms)
2022-02-11, 12:03:23 PM +661ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 42ms
+58ms ║RunTime Analysis CS > 24ms > PS > 5ms > PE > 29ms > CE
+60ms ║Runtime (36910 bytes) successfully initialized in 5ms (v0.3.113.20210203) (58ms)
+61ms ║╔Execution stage started
+72ms ║║Comparison (enum) unlocked gets (string) unlocked = true (4ms)
+74ms ║║Cancelling condition #2’s schedules…
+75ms ║║Condition #2 evaluated true (8ms)
+101ms ║║Comparison (time) 43403737 is_after (time) 1644619320000 = false (5ms)
+102ms ║║Cancelling condition #6’s schedules…
+103ms ║║Condition #6 evaluated false (28ms)
+104ms ║║Condition group #1 evaluated false (state did not change) (39ms)
+106ms ║╚Execution stage complete. (45ms)
+107ms ╚Event processed successfully (107ms)
2022-02-11, 9:37:32 AM +155ms
+2ms ╔Received event [Front Door].lock = locked with a delay of 48ms
+51ms ║RunTime Analysis CS > 17ms > PS > 7ms > PE > 27ms > CE
+54ms ║Runtime (36910 bytes) successfully initialized in 7ms (v0.3.113.20210203) (51ms)
+55ms ║╔Execution stage started
+63ms ║║Comparison (enum) locked gets (string) unlocked = false (0ms)
+64ms ║║Condition #2 evaluated false (5ms)
+66ms ║║Condition group #1 evaluated false (state did not change) (6ms)
+68ms ║╚Execution stage complete. (12ms)
+69ms ╚Event processed successfully (69ms)
2022-02-11, 9:37:13 AM +140ms
+1ms ╔Received event [Front Door].lock = unlocked with a delay of 45ms
+50ms ║RunTime Analysis CS > 19ms > PS > 5ms > PE > 26ms > CE
indent preformatted text by 4 spaces

REMOVE BELOW AFTER READING
If a solution is found for your question then please mark the post as the solution.


#2

I’m guessing the lock command cancels your 2 minute timer. Click the ‘with’ and go to the task cancelation policy, set it to Never.


#3

Looks like @eibyer has identified the issue.

There are a few tricks in webCoRE to try and make it do the most useful thing in certain circumstances.

The Task Cancellation Policy (TCP) is one of them. In your case the piston ran because the door was unlocked and it then encountered a long wait (over 5 seconds). Pistons can only execute for 20 seconds so that instance has exited and a timer event has been scheduled so it can start up afresh and continue where it left off. Meanwhile the piston has run again because the door is locked and wonders whether you really do want that to happen now things have changed. The default TCP says no you don’t.

The ‘task’ that is the subject of the TCP is the wait. You can edit the TCP for that wait specifically, or edit the entire with action so it would apply to any wait in that action.

You may wonder what happens if you change the TCP to Never so the timer keeps running and then the door is unlocked again. In that case a new instance of the piston will turn the light on again and encounter the same wait. So what does it do? It can either cancel the existing timer and start a new one, or it can create an extra one. The Task Scheduling Policy (TSP) defaults to the former.


#4

Thanks. Not sure I understand how that works. But I will try it and see if that has fixed issue


#5

I was looking the TCP. But don’t see it anywhere. Not sure if it’s a setting


#6

Click on the WITH in line 22, click the cog icon to show the options then you’ll see the Task Cancelation Policy setting.


#7

Thank you found it.