False alarm with piston


#1

1) Give a description of the problem
I have a Dakota Alert motion driveway monitor. It has contacts on the receiver that I wired to a Schlage Door contact. When there is motion in the driveway, the receiver beeps 8 times. It also sends a momentary “close” signal to the door contact.
2) What is the expected behaviour?
(PUT YOUR INFO HERE)
I have a piston that, when the door contact is closed, turns on some lights during a certain time period, for 5 minutes), speaks on some google speakers, sends a push notification, and plays an mp3 on an aerotek doorbell speaker.

3) What is happening/not happening?
False alarms! The driveway receiver does not beep, but the piston fires.

4) Post a Green Snapshot of the pistonimage

5) Attach logs after turning logging level to Full

|+1ms|╔Received event [Driveway].contact = closed with a delay of 68ms|
| --- | --- |
|+111ms|║RunTime Analysis CS > 22ms > PS > 33ms > PE > 56ms > CE|
|+114ms|║Runtime (40246 bytes) successfully initialized in 33ms (v0.3.110.20191009) (111ms)|
|+115ms|║╔Execution stage started|
|+123ms|║║Comparison (enum) closed changes_to (string) closed = true (0ms)|
|+124ms|║║Cancelling condition #2's schedules...|
|+125ms|║║Condition #2 evaluated true (6ms)|
|+126ms|║║Cancelling condition #1's schedules...|
|+127ms|║║Condition group #1 evaluated true (state changed) (8ms)|
|+129ms|║║Cancelling statement #3's schedules...|
|+311ms|║║Executed physical command [Small Speaker].playTrack([5]) (175ms)|
|+312ms|║║Executed [Small Speaker].playTrack (178ms)|
|+314ms|║║Cancelling statement #15's schedules...|
|+334ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (14ms)|
|+381ms|║║Comparison (time) 25701316 is_not_between (time) 1593767640000 .. (time) 1593822720000 = false (10ms)|
|+383ms|║║Cancelling condition #14's schedules...|
|+384ms|║║Condition #14 evaluated false (47ms)|
|+385ms|║║Cancelling condition #7's schedules...|
|+386ms|║║Condition group #7 evaluated false (state changed) (50ms)|
|+398ms|║║Comparison (time) 25701368 is_between (time) 28800000 .. (time) 79200000 = false (8ms)|
|+400ms|║║Condition #18 evaluated false (11ms)|
|+401ms|║║Condition group #17 evaluated false (state did not change) (12ms)|
|+403ms|║╚Execution stage complete. (289ms)|
|+404ms|╚Event processed successfully (404ms)|
|7/3/2020, 1:04:46 AM +347ms|
|+0ms|╔Received event [Home].execute = recovery with a delay of 93ms|
|+115ms|║RunTime Analysis CS > 21ms > PS > 39ms > PE > 55ms > CE|
|+117ms|║Runtime (40244 bytes) successfully initialized in 39ms (v0.3.110.20191009) (116ms)|
|+118ms|║╔Execution stage started|
|+128ms|║║Condition #2 evaluated false (5ms)|
|+128ms|║║Condition group #1 evaluated false (state did not change) (6ms)|
|+130ms|║╚Execution stage complete. (12ms)|
|+131ms|╚Event processed successfully (131ms)|
|7/3/2020, 1:04:45 AM +742ms|
|+1ms|╔Received event [Home].time = 1593752627858 with a delay of 57884ms|
|+159ms|║RunTime Analysis CS > 48ms > PS > 60ms > PE > 50ms > CE|
|+161ms|║Runtime (40248 bytes) successfully initialized in 60ms (v0.3.110.20191009) (159ms)|
|+162ms|║╔Execution stage started|
|+197ms|║║Executed physical command [Basement Door Light].off() (16ms)|
|+199ms|║║Executed [Basement Door Light].off (19ms)|
|+351ms|║║Executed physical command [Driveway Light].off() (147ms)|
|+352ms|║║Executed [Driveway Light].off (151ms)|
|+371ms|║║Executed physical command [Garage Driveway Light].off() (16ms)|
|+372ms|║║Executed [Garage Driveway Light].off (18ms)|
|+389ms|║║Comparison (time) 3886119 is_between (time) 28800000 .. (time) 79200000 = false (9ms)|
|+393ms|║║Cancelling condition #18's schedules...|
|+394ms|║║Condition #18 evaluated false (17ms)|
|+395ms|║║Cancelling condition #17's schedules...|
|+396ms|║║Condition group #17 evaluated false (state changed) (20ms)|
|+399ms|║╚Execution stage complete. (237ms)|
|+401ms|╚Event processed successfully (400ms)|
|7/3/2020, 12:58:48 AM +334ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 58ms|
|+95ms|║RunTime Analysis CS > 17ms > PS > 30ms > PE > 48ms > CE|
|+98ms|║Runtime (40239 bytes) successfully initialized in 30ms (v0.3.110.20191009) (96ms)|
|+98ms|║╔Execution stage started|
|+105ms|║║Comparison (enum) open changes_to (string) closed = false (0ms)|
|+107ms|║║Cancelling condition #2's schedules...|
|+107ms|║║Condition #2 evaluated false (5ms)|
|+108ms|║║Cancelling condition #1's schedules...|
|+109ms|║║Condition group #1 evaluated false (state changed) (7ms)|
|+112ms|║╚Execution stage complete. (13ms)|
|+113ms|║Setting up scheduled job for Fri, Jul 3 2020 @ 1:03:47 AM EDT (in 299.412s)|
|+126ms|╚Event processed successfully (125ms)|
|7/3/2020, 12:58:47 AM +350ms|
|+1ms|╔Received event [Driveway].contact = closed with a delay of 96ms|
|+117ms|║RunTime Analysis CS > 24ms > PS > 40ms > PE > 54ms > CE|
|+120ms|║Runtime (40246 bytes) successfully initialized in 40ms (v0.3.110.20191009) (117ms)|
|+121ms|║╔Execution stage started|
|+128ms|║║Comparison (enum) closed changes_to (string) closed = true (1ms)|
|+129ms|║║Cancelling condition #2's schedules...|
|+130ms|║║Condition #2 evaluated true (5ms)|
|+131ms|║║Cancelling condition #1's schedules...|
|+132ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+134ms|║║Cancelling statement #3's schedules...|
|+367ms|║║Executed physical command [Small Speaker].playTrack([5]) (227ms)|
|+368ms|║║Executed [Small Speaker].playTrack (229ms)|
|+370ms|║║Cancelling statement #15's schedules...|
|+393ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (16ms)|
|+446ms|║║Comparison (time) 3527746 is_not_between (time) 1593767640000 .. (time) 1593822720000 = true (10ms)|
|+448ms|║║Time restriction check passed|
|+449ms|║║Condition #14 evaluated true (54ms)|
|+450ms|║║Condition group #7 evaluated true (state did not change) (55ms)|
|+453ms|║║Cancelling statement #10's schedules...|
|+469ms|║║Executed physical command [Basement Door Light].on() (12ms)|
|+470ms|║║Executed [Basement Door Light].on (14ms)|
|+485ms|║║Executed physical command [Driveway Light].on() (11ms)|
|+486ms|║║Executed [Driveway Light].on (13ms)|
|+502ms|║║Executed physical command [Garage Driveway Light].on() (12ms)|
|+503ms|║║Executed [Garage Driveway Light].on (14ms)|
|+506ms|║║Executed virtual command [Basement Door Light, Driveway Light, Garage Driveway Light].wait (1ms)|
|+507ms|║║Requesting a wake up for Fri, Jul 3 2020 @ 1:03:47 AM EDT (in 300.0s)|
|+511ms|║╚Execution stage complete. (391ms)|
|+513ms|║Setting up scheduled job for Fri, Jul 3 2020 @ 1:03:47 AM EDT (in 299.996s)|
|+528ms|╚Event processed successfully (528ms)|
|7/2/2020, 9:02:03 PM +103ms|
|+0ms|╔Received event [Home].time = 1593738123938 with a delay of -836ms|
|+146ms|║RunTime Analysis CS > 30ms > PS > 68ms > PE > 48ms > CE|
|+148ms|║Runtime (40248 bytes) successfully initialized in 68ms (v0.3.110.20191009) (147ms)|
|+149ms|║╔Execution stage started|
|+179ms|║║Executed physical command [Basement Door Light].off() (13ms)|
|+180ms|║║Executed [Basement Door Light].off (15ms)|
|+194ms|║║Executed physical command [Driveway Light].off() (11ms)|
|+195ms|║║Executed [Driveway Light].off (12ms)|
|+208ms|║║Executed physical command [Garage Driveway Light].off() (11ms)|
|+209ms|║║Executed [Garage Driveway Light].off (13ms)|
|+221ms|║║Comparison (time) 75723315 is_between (time) 28800000 .. (time) 79200000 = true (7ms)|
|+222ms|║║Time restriction check passed|
|+224ms|║║Condition #18 evaluated true (12ms)|
|+225ms|║║Condition group #17 evaluated true (state did not change) (14ms)|
|+227ms|║║Cancelling statement #19's schedules...|
|+1009ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (773ms)|
|+1010ms|║║Executed [Google Entryway].speak (775ms)|
|+1198ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (185ms)|
|+1199ms|║║Executed [Google Garage].speak (187ms)|
|+1380ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (170ms)|
|+1381ms|║║Executed [Google Kitchen].speak (172ms)|
|+1565ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (179ms)|
|+1566ms|║║Executed [Google Living Room].speak (181ms)|
|+1786ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (217ms)|
|+1787ms|║║Executed [Google Mudroom].speak (219ms)|
|+1958ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (166ms)|
|+1959ms|║║Executed [Google Office].speak (168ms)|
|+2180ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (216ms)|
|+2182ms|║║Executed [Google Studio].speak (219ms)|
|+2184ms|║╚Execution stage complete. (2035ms)|
|+2186ms|╚Event processed successfully (2185ms)|
|7/2/2020, 8:57:03 PM +948ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 100ms|
|+9727ms|║RunTime Analysis CS > 25ms > PS > 9634ms > PE > 68ms > CE|
|+9728ms|║Piston waited at a semaphore for 9594ms|
|+9730ms|║Runtime (40316 bytes) successfully initialized in 9634ms (v0.3.110.20191009) (9728ms)|
|+9731ms|║╔Execution stage started|
|+9738ms|║║Comparison (enum) open changes_to (string) closed = false (0ms)|
|+9739ms|║║Cancelling condition #2's schedules...|
|+9740ms|║║Condition #2 evaluated false (5ms)|
|+9741ms|║║Cancelling condition #1's schedules...|
|+9742ms|║║Condition group #1 evaluated false (state changed) (7ms)|
|+9744ms|║╚Execution stage complete. (13ms)|
|+9745ms|║Setting up scheduled job for Thu, Jul 2 2020 @ 9:02:03 PM EDT (in 290.246s)|
|+9755ms|╚Event processed successfully (9755ms)|
|7/2/2020, 8:57:03 PM +488ms|
|+2ms|╔Received event [Driveway].contact = closed with a delay of 109ms|
|+141ms|║RunTime Analysis CS > 37ms > PS > 45ms > PE > 59ms > CE|
|+143ms|║Runtime (40246 bytes) successfully initialized in 45ms (v0.3.110.20191009) (141ms)|
|+144ms|║╔Execution stage started|
|+151ms|║║Comparison (enum) closed changes_to (string) closed = true (1ms)|
|+152ms|║║Cancelling condition #2's schedules...|
|+153ms|║║Condition #2 evaluated true (5ms)|
|+154ms|║║Cancelling condition #1's schedules...|
|+155ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+157ms|║║Cancelling statement #3's schedules...|
|+313ms|║║Executed physical command [Small Speaker].playTrack([5]) (150ms)|
|+314ms|║║Executed [Small Speaker].playTrack (152ms)|
|+317ms|║║Cancelling statement #15's schedules...|
|+335ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (13ms)|
|+389ms|║║Comparison (time) 75423826 is_not_between (time) 1593681180000 .. (time) 1593736320000 = true (9ms)|
|+391ms|║║Time restriction check passed|
|+392ms|║║Cancelling condition #14's schedules...|
|+393ms|║║Condition #14 evaluated true (55ms)|
|+394ms|║║Cancelling condition #7's schedules...|
|+395ms|║║Condition group #7 evaluated true (state changed) (58ms)|
|+397ms|║║Cancelling statement #10's schedules...|
|+413ms|║║Executed physical command [Basement Door Light].on() (12ms)|
|+414ms|║║Executed [Basement Door Light].on (14ms)|
|+429ms|║║Executed physical command [Driveway Light].on() (12ms)|
|+430ms|║║Executed [Driveway Light].on (13ms)|
|+445ms|║║Executed physical command [Garage Driveway Light].on() (11ms)|
|+445ms|║║Executed [Garage Driveway Light].on (12ms)|
|+448ms|║║Executed virtual command [Basement Door Light, Driveway Light, Garage Driveway Light].wait (0ms)|
|+449ms|║║Requesting a wake up for Thu, Jul 2 2020 @ 9:02:03 PM EDT (in 300.0s)|
|+453ms|║╚Execution stage complete. (309ms)|
|+455ms|║Setting up scheduled job for Thu, Jul 2 2020 @ 9:02:03 PM EDT (in 299.996s)|
|+463ms|╚Event processed successfully (463ms)|
|7/2/2020, 4:15:39 PM +995ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 61ms|
|+9128ms|║RunTime Analysis CS > 21ms > PS > 9043ms > PE > 65ms > CE|
|+9129ms|║Piston waited at a semaphore for 9014ms|
|+9131ms|║Runtime (40317 bytes) successfully initialized in 9043ms (v0.3.110.20191009) (9130ms)|
|+9132ms|║╔Execution stage started|
|+9139ms|║║Comparison (enum) open changes_to (string) closed = false (0ms)|
|+9141ms|║║Cancelling condition #2's schedules...|
|+9142ms|║║Condition #2 evaluated false (5ms)|
|+9143ms|║║Cancelling condition #1's schedules...|
|+9144ms|║║Condition group #1 evaluated false (state changed) (7ms)|
|+9145ms|║╚Execution stage complete. (13ms)|
|+9146ms|╚Event processed successfully (9146ms)|
|7/2/2020, 4:15:38 PM +808ms|
|+1ms|╔Received event [Driveway].contact = closed with a delay of 254ms|
|+119ms|║RunTime Analysis CS > 23ms > PS > 43ms > PE > 52ms > CE|
|+121ms|║Runtime (40249 bytes) successfully initialized in 43ms (v0.3.110.20191009) (118ms)|
|+122ms|║╔Execution stage started|
|+128ms|║║Comparison (enum) closed changes_to (string) closed = true (1ms)|
|+130ms|║║Cancelling condition #2's schedules...|
|+131ms|║║Condition #2 evaluated true (5ms)|
|+132ms|║║Cancelling condition #1's schedules...|
|+133ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+135ms|║║Cancelling statement #3's schedules...|
|+287ms|║║Executed physical command [Small Speaker].playTrack([5]) (146ms)|
|+288ms|║║Executed [Small Speaker].playTrack (147ms)|
|+290ms|║║Cancelling statement #15's schedules...|
|+314ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (19ms)|
|+369ms|║║Comparison (time) 58539125 is_not_between (time) 1593681180000 .. (time) 1593736320000 = false (9ms)|
|+371ms|║║Condition #14 evaluated false (54ms)|
|+372ms|║║Condition group #7 evaluated false (state did not change) (56ms)|
|+383ms|║║Comparison (time) 58539183 is_between (time) 28800000 .. (time) 79200000 = true (7ms)|
|+384ms|║║Time restriction check passed|
|+386ms|║║Condition #18 evaluated true (11ms)|
|+387ms|║║Condition group #17 evaluated true (state did not change) (12ms)|
|+389ms|║║Cancelling statement #19's schedules...|
|+692ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (296ms)|
|+693ms|║║Executed [Google Entryway].speak (297ms)|
|+935ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (238ms)|
|+936ms|║║Executed [Google Garage].speak (239ms)|
|+1114ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (174ms)|
|+1115ms|║║Executed [Google Kitchen].speak (175ms)|
|+1291ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (172ms)|
|+1292ms|║║Executed [Google Living Room].speak (173ms)|
|+1471ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (174ms)|
|+1472ms|║║Executed [Google Mudroom].speak (176ms)|
|+1633ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (157ms)|
|+1634ms|║║Executed [Google Office].speak (159ms)|
|+1793ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (154ms)|
|+1794ms|║║Executed [Google Studio].speak (156ms)|
|+1797ms|║╚Execution stage complete. (1676ms)|
|+1798ms|╚Event processed successfully (1798ms)|
|7/2/2020, 4:03:42 PM +871ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 62ms|
|+9364ms|║RunTime Analysis CS > 18ms > PS > 9292ms > PE > 54ms > CE|
|+9365ms|║Piston waited at a semaphore for 9263ms|
|+9367ms|║Runtime (40317 bytes) successfully initialized in 9292ms (v0.3.110.20191009) (9366ms)|
|+9368ms|║╔Execution stage started|
|+9375ms|║║Comparison (enum) open changes_to (string) closed = false (1ms)|
|+9376ms|║║Cancelling condition #2's schedules...|
|+9377ms|║║Condition #2 evaluated false (5ms)|
|+9378ms|║║Cancelling condition #1's schedules...|
|+9379ms|║║Condition group #1 evaluated false (state changed) (6ms)|
|+9380ms|║╚Execution stage complete. (12ms)|
|+9381ms|╚Event processed successfully (9381ms)|
|7/2/2020, 4:03:41 PM +923ms|
|+2ms|╔Received event [Driveway].contact = closed with a delay of 94ms|
|+122ms|║RunTime Analysis CS > 24ms > PS > 44ms > PE > 53ms > CE|
|+124ms|║Runtime (40245 bytes) successfully initialized in 44ms (v0.3.110.20191009) (121ms)|
|+125ms|║╔Execution stage started|
|+132ms|║║Comparison (enum) closed changes_to (string) closed = true (1ms)|
|+134ms|║║Cancelling condition #2's schedules...|
|+134ms|║║Condition #2 evaluated true (5ms)|
|+135ms|║║Cancelling condition #1's schedules...|
|+136ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+138ms|║║Cancelling statement #3's schedules...|
|+341ms|║║Executed physical command [Small Speaker].playTrack([5]) (197ms)|
|+342ms|║║Executed [Small Speaker].playTrack (199ms)|
|+345ms|║║Cancelling statement #15's schedules...|
|+377ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (25ms)|
|+432ms|║║Comparison (time) 57822303 is_not_between (time) 1593681180000 .. (time) 1593736320000 = false (11ms)|
|+434ms|║║Condition #14 evaluated false (55ms)|
|+435ms|║║Condition group #7 evaluated false (state did not change) (56ms)|
|+448ms|║║Comparison (time) 57822362 is_between (time) 28800000 .. (time) 79200000 = true (8ms)|
|+450ms|║║Time restriction check passed|
|+451ms|║║Condition #18 evaluated true (13ms)|
|+452ms|║║Condition group #17 evaluated true (state did not change) (14ms)|
|+455ms|║║Cancelling statement #19's schedules...|
|+674ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (211ms)|
|+675ms|║║Executed [Google Entryway].speak (213ms)|
|+854ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (175ms)|
|+855ms|║║Executed [Google Garage].speak (177ms)|
|+1020ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (160ms)|
|+1021ms|║║Executed [Google Kitchen].speak (162ms)|
|+1181ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (155ms)|
|+1182ms|║║Executed [Google Living Room].speak (157ms)|
|+1363ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (176ms)|
|+1364ms|║║Executed [Google Mudroom].speak (178ms)|
|+1538ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (169ms)|
|+1539ms|║║Executed [Google Office].speak (171ms)|
|+1710ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (165ms)|
|+1711ms|║║Executed [Google Studio].speak (167ms)|
|+1713ms|║╚Execution stage complete. (1588ms)|
|+1714ms|╚Event processed successfully (1714ms)|
|7/2/2020, 2:11:20 PM +544ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 61ms|
|+9117ms|║RunTime Analysis CS > 26ms > PS > 9039ms > PE > 51ms > CE|
|+9118ms|║Piston waited at a semaphore for 9011ms|
|+9120ms|║Runtime (40317 bytes) successfully initialized in 9039ms (v0.3.110.20191009) (9117ms)|
|+9120ms|║╔Execution stage started|
|+9127ms|║║Comparison (enum) open changes_to (string) closed = false (1ms)|
|+9128ms|║║Cancelling condition #2's schedules...|
|+9129ms|║║Condition #2 evaluated false (5ms)|
|+9130ms|║║Cancelling condition #1's schedules...|
|+9131ms|║║Condition group #1 evaluated false (state changed) (6ms)|
|+9132ms|║╚Execution stage complete. (12ms)|
|+9133ms|╚Event processed successfully (9133ms)|
|7/2/2020, 2:11:19 PM +481ms|
|+1ms|╔Received event [Driveway].contact = closed with a delay of 62ms|
|+100ms|║RunTime Analysis CS > 19ms > PS > 31ms > PE > 50ms > CE|
|+103ms|║Runtime (40248 bytes) successfully initialized in 31ms (v0.3.110.20191009) (101ms)|
|+104ms|║╔Execution stage started|
|+110ms|║║Comparison (enum) closed changes_to (string) closed = true (0ms)|
|+112ms|║║Cancelling condition #2's schedules...|
|+113ms|║║Condition #2 evaluated true (4ms)|
|+114ms|║║Cancelling condition #1's schedules...|
|+114ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+116ms|║║Cancelling statement #3's schedules...|
|+252ms|║║Executed physical command [Small Speaker].playTrack([5]) (131ms)|
|+253ms|║║Executed [Small Speaker].playTrack (133ms)|
|+256ms|║║Cancelling statement #15's schedules...|
|+272ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (11ms)|
|+315ms|║║Comparison (time) 51079756 is_not_between (time) 1593681180000 .. (time) 1593736320000 = false (9ms)|
|+316ms|║║Condition #14 evaluated false (42ms)|
|+317ms|║║Condition group #7 evaluated false (state did not change) (43ms)|
|+329ms|║║Comparison (time) 51079801 is_between (time) 28800000 .. (time) 79200000 = true (7ms)|
|+330ms|║║Time restriction check passed|
|+332ms|║║Condition #18 evaluated true (11ms)|
|+333ms|║║Condition group #17 evaluated true (state did not change) (13ms)|
|+335ms|║║Cancelling statement #19's schedules...|
|+805ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (464ms)|
|+806ms|║║Executed [Google Entryway].speak (466ms)|
|+995ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (186ms)|
|+996ms|║║Executed [Google Garage].speak (188ms)|
|+1175ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (175ms)|
|+1176ms|║║Executed [Google Kitchen].speak (177ms)|
|+1341ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (162ms)|
|+1342ms|║║Executed [Google Living Room].speak (164ms)|
|+1525ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (179ms)|
|+1526ms|║║Executed [Google Mudroom].speak (182ms)|
|+1739ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (210ms)|
|+1740ms|║║Executed [Google Office].speak (212ms)|
|+1911ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (166ms)|
|+1912ms|║║Executed [Google Studio].speak (168ms)|
|+1914ms|║╚Execution stage complete. (1810ms)|
|+1916ms|╚Event processed successfully (1915ms)|
|7/2/2020, 2:10:18 PM +612ms|
|+2ms|╔Received event [Driveway].contact = open with a delay of 110ms|
|+126ms|║RunTime Analysis CS > 28ms > PS > 43ms > PE > 55ms > CE|
|+129ms|║Runtime (40244 bytes) successfully initialized in 43ms (v0.3.110.20191009) (126ms)|
|+130ms|║╔Execution stage started|
|+138ms|║║Comparison (enum) open changes_to (string) closed = false (1ms)|
|+140ms|║║Cancelling condition #2's schedules...|
|+141ms|║║Condition #2 evaluated false (6ms)|
|+142ms|║║Cancelling condition #1's schedules...|
|+143ms|║║Condition group #1 evaluated false (state changed) (9ms)|
|+145ms|║╚Execution stage complete. (15ms)|
|+146ms|╚Event processed successfully (146ms)|
|7/2/2020, 9:25:03 AM +774ms|
|+1ms|╔Received event [Driveway].contact = closed with a delay of 92ms|
|+113ms|║RunTime Analysis CS > 22ms > PS > 38ms > PE > 53ms > CE|
|+115ms|║Runtime (40245 bytes) successfully initialized in 38ms (v0.3.110.20191009) (113ms)|
|+116ms|║╔Execution stage started|
|+123ms|║║Comparison (enum) closed changes_to (string) closed = true (1ms)|
|+124ms|║║Cancelling condition #2's schedules...|
|+125ms|║║Condition #2 evaluated true (5ms)|
|+126ms|║║Cancelling condition #1's schedules...|
|+127ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+129ms|║║Cancelling statement #3's schedules...|
|+191ms|║║Executed physical command [Small Speaker].playTrack([5]) (57ms)|
|+192ms|║║Executed [Small Speaker].playTrack (59ms)|
|+194ms|║║Cancelling statement #15's schedules...|
|+234ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (29ms)|
|+299ms|║║Comparison (time) 33904011 is_not_between (time) 1593681180000 .. (time) 1593736320000 = false (9ms)|
|+301ms|║║Condition #14 evaluated false (64ms)|
|+302ms|║║Condition group #7 evaluated false (state did not change) (66ms)|
|+314ms|║║Comparison (time) 33904079 is_between (time) 28800000 .. (time) 79200000 = true (7ms)|
|+315ms|║║Time restriction check passed|
|+317ms|║║Condition #18 evaluated true (12ms)|
|+318ms|║║Condition group #17 evaluated true (state did not change) (13ms)|
|+320ms|║║Cancelling statement #19's schedules...|
|+919ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (590ms)|
|+920ms|║║Executed [Google Entryway].speak (592ms)|
|+1108ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (183ms)|
|+1109ms|║║Executed [Google Garage].speak (185ms)|
|+1299ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (186ms)|
|+1300ms|║║Executed [Google Kitchen].speak (188ms)|
|+1519ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (215ms)|
|+1520ms|║║Executed [Google Living Room].speak (217ms)|
|+1789ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (265ms)|
|+1790ms|║║Executed [Google Mudroom].speak (267ms)|
|+1979ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (184ms)|
|+1980ms|║║Executed [Google Office].speak (186ms)|
|+2204ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (220ms)|
|+2205ms|║║Executed [Google Studio].speak (222ms)|
|+2207ms|║╚Execution stage complete. (2091ms)|
|+2208ms|╚Event processed successfully (2208ms)|
|7/1/2020, 4:58:04 PM +42ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 98ms|
|+9651ms|║RunTime Analysis CS > 31ms > PS > 9558ms > PE > 62ms > CE|
|+9652ms|║Piston waited at a semaphore for 9515ms|
|+9654ms|║Runtime (40317 bytes) successfully initialized in 9558ms (v0.3.110.20191009) (9652ms)|
|+9655ms|║╔Execution stage started|
|+9661ms|║║Comparison (enum) open changes_to (string) closed = false (0ms)|
|+9663ms|║║Cancelling condition #2's schedules...|
|+9664ms|║║Condition #2 evaluated false (4ms)|
|+9665ms|║║Cancelling condition #1's schedules...|
|+9665ms|║║Condition group #1 evaluated false (state changed) (7ms)|
|+9667ms|║╚Execution stage complete. (12ms)|
|+9668ms|╚Event processed successfully (9669ms)|
|7/1/2020, 4:58:03 PM +353ms|
|+1ms|╔Received event [Driveway].contact = closed with a delay of 92ms|
|+115ms|║RunTime Analysis CS > 23ms > PS > 37ms > PE > 55ms > CE|
|+118ms|║Runtime (40248 bytes) successfully initialized in 37ms (v0.3.110.20191009) (116ms)|
|+119ms|║╔Execution stage started|
|+127ms|║║Comparison (enum) closed changes_to (string) closed = true (0ms)|
|+128ms|║║Cancelling condition #2's schedules...|
|+129ms|║║Condition #2 evaluated true (6ms)|
|+130ms|║║Cancelling condition #1's schedules...|
|+131ms|║║Condition group #1 evaluated true (state changed) (8ms)|
|+133ms|║║Cancelling statement #3's schedules...|
|+286ms|║║Executed physical command [Small Speaker].playTrack([5]) (147ms)|
|+287ms|║║Executed [Small Speaker].playTrack (149ms)|
|+289ms|║║Cancelling statement #15's schedules...|
|+310ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (15ms)|
|+363ms|║║Comparison (time) 61083666 is_not_between (time) 1593594720000 .. (time) 1593649920000 = false (9ms)|
|+365ms|║║Condition #14 evaluated false (52ms)|
|+366ms|║║Condition group #7 evaluated false (state did not change) (53ms)|
|+378ms|║║Comparison (time) 61083722 is_between (time) 28800000 .. (time) 79200000 = true (7ms)|
|+379ms|║║Time restriction check passed|
|+381ms|║║Condition #18 evaluated true (11ms)|
|+382ms|║║Condition group #17 evaluated true (state did not change) (13ms)|
|+384ms|║║Cancelling statement #19's schedules...|
|+845ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (454ms)|
|+846ms|║║Executed [Google Entryway].speak (456ms)|
|+1018ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (167ms)|
|+1019ms|║║Executed [Google Garage].speak (169ms)|
|+1195ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (171ms)|
|+1196ms|║║Executed [Google Kitchen].speak (174ms)|
|+1375ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (174ms)|
|+1376ms|║║Executed [Google Living Room].speak (177ms)|
|+1575ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (195ms)|
|+1576ms|║║Executed [Google Mudroom].speak (197ms)|
|+1737ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (157ms)|
|+1738ms|║║Executed [Google Office].speak (159ms)|
|+1924ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (182ms)|
|+1925ms|║║Executed [Google Studio].speak (184ms)|
|+1928ms|║╚Execution stage complete. (1809ms)|
|+1929ms|╚Event processed successfully (1929ms)|
|7/1/2020, 4:56:47 PM +761ms|
|+2ms|╔Received event [Driveway].contact = open with a delay of 105ms|
|+9143ms|║RunTime Analysis CS > 25ms > PS > 9056ms > PE > 61ms > CE|
|+9143ms|║Piston waited at a semaphore for 9014ms|
|+9146ms|║Runtime (40318 bytes) successfully initialized in 9056ms (v0.3.110.20191009) (9143ms)|
|+9146ms|║╔Execution stage started|
|+9153ms|║║Comparison (enum) open changes_to (string) closed = false (1ms)|
|+9154ms|║║Cancelling condition #2's schedules...|
|+9155ms|║║Condition #2 evaluated false (5ms)|
|+9156ms|║║Cancelling condition #1's schedules...|
|+9157ms|║║Condition group #1 evaluated false (state changed) (6ms)|
|+9158ms|║╚Execution stage complete. (12ms)|
|+9159ms|╚Event processed successfully (9159ms)|
|7/1/2020, 4:56:46 PM +682ms|
|+2ms|╔Received event [Driveway].contact = closed with a delay of 109ms|
|+143ms|║RunTime Analysis CS > 44ms > PS > 47ms > PE > 51ms > CE|
|+145ms|║Runtime (40246 bytes) successfully initialized in 47ms (v0.3.110.20191009) (142ms)|
|+146ms|║╔Execution stage started|
|+152ms|║║Comparison (enum) closed changes_to (string) closed = true (1ms)|
|+154ms|║║Cancelling condition #2's schedules...|
|+154ms|║║Condition #2 evaluated true (4ms)|
|+155ms|║║Cancelling condition #1's schedules...|
|+156ms|║║Condition group #1 evaluated true (state changed) (7ms)|
|+158ms|║║Cancelling statement #3's schedules...|
|+379ms|║║Executed physical command [Small Speaker].playTrack([5]) (216ms)|
|+380ms|║║Executed [Small Speaker].playTrack (217ms)|
|+382ms|║║Cancelling statement #15's schedules...|
|+399ms|║║Executed virtual command [Eryc Courmac's Pixel 4 XL, JAYSEN COURMAC's Pixel 3].sendPushNotification (12ms)|
|+454ms|║║Comparison (time) 61007084 is_not_between (time) 1593594720000 .. (time) 1593649920000 = false (9ms)|
|+455ms|║║Condition #14 evaluated false (54ms)|
|+456ms|║║Condition group #7 evaluated false (state did not change) (55ms)|
|+467ms|║║Comparison (time) 61007141 is_between (time) 28800000 .. (time) 79200000 = true (7ms)|
|+468ms|║║Time restriction check passed|
|+470ms|║║Condition #18 evaluated true (12ms)|
|+471ms|║║Condition group #17 evaluated true (state did not change) (13ms)|
|+473ms|║║Cancelling statement #19's schedules...|
|+862ms|║║Executed physical command [Google Entryway].speak([There is someone in the driveway.]) (382ms)|
|+863ms|║║Executed [Google Entryway].speak (383ms)|
|+1050ms|║║Executed physical command [Google Garage].speak([There is someone in the driveway.]) (182ms)|
|+1051ms|║║Executed [Google Garage].speak (183ms)|
|+1236ms|║║Executed physical command [Google Kitchen].speak([There is someone in the driveway.]) (181ms)|
|+1237ms|║║Executed [Google Kitchen].speak (182ms)|
|+1418ms|║║Executed physical command [Google Living Room].speak([There is someone in the driveway.]) (176ms)|
|+1419ms|║║Executed [Google Living Room].speak (178ms)|
|+1676ms|║║Executed physical command [Google Mudroom].speak([There is someone in the driveway.]) (253ms)|
|+1677ms|║║Executed [Google Mudroom].speak (255ms)|
|+1842ms|║║Executed physical command [Google Office].speak([There is someone in the driveway.]) (160ms)|
|+1843ms|║║Executed [Google Office].speak (162ms)|
|+2012ms|║║Executed physical command [Google Studio].speak([There is someone in the driveway.]) (165ms)|
|+2013ms|║║Executed [Google Studio].speak (167ms)|
|+2016ms|║╚Execution stage complete. (1869ms)|
|+2016ms|╚Event processed successfully (2016ms)|
|7/1/2020, 12:09:22 PM +89ms|
|+1ms|╔Received event [Driveway].contact = open with a delay of 86ms|
|+9628ms|║RunTime Analysis CS > 22ms > PS > 9554ms > PE > 53ms > CE|
|+9629ms|║Piston waited at a semaphore for 9519ms|
|+9631ms|║Runtime (40317 bytes) successfully initialized in 9554ms (v0.3.110.20191009) (9629ms)|
|+9632ms|║╔Execution stage started|
|+9639ms|║║Comparison (enum) open changes_to (string) closed = false (1ms)|
|+9640ms|║║Cancelling condition #2's schedules...|
|+9641ms|║║Condition #2 evaluated false (5ms)|
|+9642ms|║║Cancelling condition #1's schedules...|
|+9749ms|║║Condition group #1 evaluated false (state changed) (6ms)|
|+9753ms|║╚Execution stage complete. (121ms)|
|+9754ms|╚Event processed successfully (9754ms)|

#2

Won’t this fire every time the door is opened and closed in addition to the driveway signal? Was no-one going through the door?


#3

It looks to me like the piston you posted, and the log did exactly what you programmed.

If Contact sensor 2 closed for unknown reasons, I would focus on that automation.
(maybe an old experimental piston you forgot to delete or pause?)


You can usually see what controlled a device by going to the:
IDE > My Devices > Contact Sensor 2 > List Events > all

This shows recent events at the top. Scrolling down should reveal what automation (piston) closed that sensor. I believe that is where you should troubleshoot.


#4

Heh, sorry, I wasn’t clear. I’m using a door sensor, but it’s not attached to a door. It’s just the sensor part of it (not the magnet) so it’s just wired to the driveway receiver. The receiver, when it get a “motion” signal, electrically connects the two wires so the sensor has a “closed” signal.

I’ll do that - thank you!


#5

Just a lengthy list of opens and closed. I’m not sure why though - if the driveway receiver got a “motion” signal, it would beep, and the false ones… no beep. I think I’ll replace the batteries in the door sensor, juuuust for fun - maybe it’s low. The “current state” reads 99%, but after over six months, with at least two detections per day if not more… It’s got to be lower than that.


#6

Often, if you look up or down a line near the event, then look in the far right column…
(It usually displays which piston opened or closed it)

IE: PistonNameX sent open command to Contact Sensor 2


Battery is a good move as well… :+1:
(I have many that die while still displaying 89%)