Portion of piston doesn't run consistently


#1

1) Give a description of the problem
Outlet 4 doesn’t always turn off by itself. Line 44-51.

2) What is the expected behavior?
No matter how outlet 4 is turned on (by a piston, through Smartthings app, etc) I ALWAYS want it to turn off by itself one second later.

3) What is happening/not happening?
If I turn it on from the Things page in the Smartthings app, it doesn’t consistently turn off by itself. Sometimes it does, sometimes it doesn’t. There is no pattern that I have been able to discern.

I’ve played around with and tried various settings for the task cancellation policy. I haven’t gotten it to work consistently. I’ve done this on both lines 44 and 48.

**4) Post a Green Snapshot of the piston!


#2

logs would help better to understand whats happening (at least for pros, I can’t read them well yet, st’ll learning)
but on top of my head, have you tried at least 5 seconds?
a - One second is very limited/fast in my opinion (signal issues and delays etc)
b - Again as a test, I would put line 45-53 as a seperate piston, instead of in the same piston.


#3

I tried two seconds instead, that didn’t help. I’ll try five in a minute.
I tried it with two seconds and in it’s own piston and that didn’t help either.

I took some logs from the Smartthings IDE of me manually turned the outlet on a few times in a row until it stayed on. I’ll paste them into the next post.


#4
ae2c8b54-39eb-49d6-b1f9-0348ca4dbc79  7:46:58 PM: debug Parse returned [name:fanMode, value:00, isStateChange:false, displayed:false, linkText:Ceiling Fan, descriptionText:Ceiling Fan fan mode is 00]
ae2c8b54-39eb-49d6-b1f9-0348ca4dbc79  7:46:58 PM: info Fan event detected on controller
ae2c8b54-39eb-49d6-b1f9-0348ca4dbc79  7:46:58 PM: info Fan event detected on controller
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:40 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:39 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:37 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:36 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:34 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:38 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:37 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:33 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:36 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:36 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:33 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:35 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:33 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:32 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:35 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:34 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:31 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:34 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:31 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:30 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:33 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:32 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:32 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 parse returned: [name:illuminance, value:39, unit:lux, isStateChange:true, descriptionText:Motion Office 2 illuminance was 39 lux]
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 parsing: illuminance: 39
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 parsing: illuminance: 39
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 parse returned: [name:motion, value:active, descriptionText:Motion Office 2 detected motion]
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 detected motion
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 parsing: read attr - raw: 78AE0104060800001801, dni: 78AE, endpoint: 01, cluster: 0406, size: 08, attrId: 0000, encoding: 18, value: 01
b3052adf-15c8-42dc-8636-4b02b6b1ee8e  7:46:40 PM: debug Motion Office 2 parsing: read attr - raw: 78AE0104060800001801, dni: 78AE, endpoint: 01, cluster: 0406, size: 08, attrId: 0000, encoding: 18, value: 01
62c591a9-3e98-478a-9db0-d2ba370b9885  7:46:36 PM: debug Parse returned [name:temperature, value:73, unit:F, descriptionText:{{ device.displayName }} was {{ value }}°F, translatable:true]
62c591a9-3e98-478a-9db0-d2ba370b9885  7:46:36 PM: debug description: temperature: 22.66
62c591a9-3e98-478a-9db0-d2ba370b9885  7:46:36 PM: debug Parse returned [name:temperature, value:73, unit:F, descriptionText:{{ device.displayName }} was {{ value }}°F, translatable:true]
62c591a9-3e98-478a-9db0-d2ba370b9885  7:46:36 PM: debug description: temperature: 22.66
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:26 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:26 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:25 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:25 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:24 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:21 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:18 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:16 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
a8580f3d-e276-45f3-ab77-a0c0429634ab  7:46:23 PM: info smartapp_pause called by smartAppName=webCoRE Piston, value=2000, smartAppVersionId=537466ff-c635-4f98-8d98-546bf934be1f
a56f0e65-0a09-4ca9-b2c5-7940635ea455  7:46:24 PM: warn No response from Weather Underground API
a56f0e65-0a09-4ca9-b2c5-7940635ea455  7:46:24 PM: debug WUSTATION: Executing 'poll', location: Home
a56f0e65-0a09-4ca9-b2c5-7940635ea455  7:46:24 PM: debug WUSTATION: Executing 'poll', location: Home
a8580f3d-e276-45f3-ab77-a0c0429634ab  7:46:16 PM: info smartapp_pause called by smartAppName=webCoRE Piston, value=2000, smartAppVersionId=537466ff-c635-4f98-8d98-546bf934be1f
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:06 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:04 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:03 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:04 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:03 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
bc185e0e-2027-4982-a04c-a41a6d2d3832  7:46:03 PM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=a24ea7ee-e3f0-4f1a-af9d-d7bf325b7fac
a8580f3d-e276-45f3-ab77-a0c0429634ab  7:46:11 PM: info smartapp_pause called by smartAppName=webCoRE Piston, value=2000, smartAppVersionId=537466ff-c635-4f98-8d98-546bf934be1f
a8580f3d-e276-45f3-ab77-a0c0429634ab  7:46:00 PM: info smartapp_pause called by smartAppName=webCoRE Piston, value=2000, smartAppVersionId=537466ff-c635-4f98-8d98-546bf934be1f

#5

himmm not this one…
Logs are under the pistons you will see different kinds of logs


#6

Still might be too fast… Atleast five secons - 10 even better


#7

I took some logs from Webcore. I’ll put them in the next post.

Am I wrong to think that I should be able to test this by turning the outlet on, waiting for it to turn off, then turning it right back on again over and over again? To me, that seems like an easy way to get days worth of reliability testing done quickly. Maybe it doesn’t work that way? That said, it has failed to turn itself off, even when it was only executed once.

On both sets of these logs, I copied them after the switch failed to turn off. So, the “failure” should be the newest event.

I’ll post this, paste the logs, and then try upping the time again. I’m trying to keep it as low as possible, when this is on, it’s basically holding down the button on my garage door opener. I’m not sure if that will have any strange effects to the opener itself.


#8
2/26/2019, 7:55:09 PM +688ms
+1ms ╔Received event [Door Garage Controller].switch = on with a delay of 496ms
+10218ms ║RunTime Analysis CS > 19ms > PS > 10180ms > PE > 20ms > CE
+10219ms ║Piston waited at a semaphore for 10027ms
+10222ms ║Runtime (37626 bytes) successfully initialized in 10180ms (v0.3.10a.20190223) (10219ms)
+10223ms ║╔Execution stage started
+10233ms ║║Comparison (enum) on changes_to (string) on = false (1ms)
+10235ms ║║Condition #11 evaluated false (6ms)
+10236ms ║║Condition group #10 evaluated false (state did not change) (8ms)
+10239ms ║╚Execution stage complete. (16ms)
+10240ms ╚Event processed successfully (10240ms)
2/26/2019, 7:55:09 PM +683ms
+2ms ╔Received event [Door Garage Controller].switch = on with a delay of 550ms
+146ms ║RunTime Analysis CS > 14ms > PS > 115ms > PE > 16ms > CE
+148ms ║Runtime (37543 bytes) successfully initialized in 115ms (v0.3.10a.20190223) (145ms)
+149ms ║╔Execution stage started
+160ms ║║Comparison (enum) on changes_to (string) on = false (1ms)
+162ms ║║Cancelling condition #11's schedules...
+163ms ║║Condition #11 evaluated false (7ms)
+165ms ║║Cancelling condition #10's schedules...
+166ms ║║Condition group #10 evaluated false (state changed) (10ms)
+168ms ║╚Execution stage complete. (19ms)
+170ms ╚Event processed successfully (170ms)
2/26/2019, 7:55:08 PM +239ms
+0ms ╔Received event [Home].time = 1551236107323 with a delay of 915ms
+568ms ║RunTime Analysis CS > 129ms > PS > 413ms > PE > 26ms > CE
+572ms ║Runtime (37535 bytes) successfully initialized in 413ms (v0.3.10a.20190223) (570ms)
+574ms ║╔Execution stage started
+591ms ║║Cancelling statement #14's schedules...
+601ms ║║Skipped execution of physical command [Door Garage Controller].off([]) because it would make no change to the device. (4ms)
+602ms ║║Executed [Door Garage Controller].off (7ms)
+607ms ║╚Execution stage complete. (33ms)
+609ms ╚Event processed successfully (609ms)
2/26/2019, 7:54:54 PM +972ms
+2ms ╔Received event [Door Garage Controller].switch = on with a delay of 553ms
+10320ms ║RunTime Analysis CS > 27ms > PS > 10273ms > PE > 20ms > CE
+10321ms ║Piston waited at a semaphore for 10090ms
+10323ms ║Runtime (37627 bytes) successfully initialized in 10273ms (v0.3.10a.20190223) (10320ms)
+10325ms ║╔Execution stage started
+10335ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+10337ms ║║Cancelling condition #11's schedules...
+10338ms ║║Condition #11 evaluated true (7ms)
+10340ms ║║Cancelling condition #10's schedules...
+10341ms ║║Condition group #10 evaluated true (state changed) (11ms)
+10344ms ║║Cancelling statement #12's schedules...
+10348ms ║║Executed virtual command wait (1ms)
+10350ms ║║Requesting a wake up for Tue, Feb 26 2019 @ 7:55:07 PM MST (in 2.0s)
+10355ms ║╚Execution stage complete. (31ms)
+10357ms ║Setting up scheduled job for Tue, Feb 26 2019 @ 7:55:07 PM MST (in 1.995s)
+10367ms ╚Event processed successfully (10367ms)
2/26/2019, 7:54:57 PM +522ms
+1ms ╔Received event [Door Garage Controller].switch = off with a delay of 491ms
+192ms ║RunTime Analysis CS > 18ms > PS > 156ms > PE > 18ms > CE
+194ms ║Runtime (37544 bytes) successfully initialized in 156ms (v0.3.10a.20190223) (192ms)
+195ms ║╔Execution stage started
+206ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+208ms ║║Cancelling condition #11's schedules...
+209ms ║║Condition #11 evaluated false (7ms)
+211ms ║║Cancelling condition #10's schedules...
+212ms ║║Condition group #10 evaluated false (state changed) (11ms)
+215ms ║╚Execution stage complete. (20ms)
+216ms ╚Event processed successfully (216ms)
2/26/2019, 7:54:54 PM +956ms
+1ms ╔Received event [Door Garage Controller].switch = on with a delay of 552ms
+151ms ║RunTime Analysis CS > 13ms > PS > 124ms > PE > 14ms > CE
+153ms ║Runtime (37549 bytes) successfully initialized in 124ms (v0.3.10a.20190223) (151ms)
+154ms ║╔Execution stage started
+165ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+168ms ║║Cancelling condition #11's schedules...
+169ms ║║Condition #11 evaluated true (8ms)
+170ms ║║Cancelling condition #10's schedules...
+172ms ║║Condition group #10 evaluated true (state changed) (11ms)
+175ms ║║Cancelling statement #12's schedules...
+179ms ║║Executed virtual command wait (1ms)
+181ms ║║Waiting for 2000ms
+2185ms ║║Cancelling statement #14's schedules...
+2219ms ║║Executed physical command [Door Garage Controller].off() (29ms)
+2220ms ║║Executed [Door Garage Controller].off (32ms)
+2224ms ║╚Execution stage complete. (2070ms)
+2225ms ╚Event processed successfully (2225ms)
2/26/2019, 7:54:33 PM +293ms
+1ms ╔Received event [Door Garage Controller].switch = off with a delay of 490ms
+142ms ║RunTime Analysis CS > 12ms > PS > 116ms > PE > 13ms > CE
+144ms ║Runtime (37544 bytes) successfully initialized in 116ms (v0.3.10a.20190223) (142ms)
+146ms ║╔Execution stage started
+156ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+158ms ║║Cancelling condition #11's schedules...
+159ms ║║Condition #11 evaluated false (7ms)
+161ms ║║Cancelling condition #10's schedules...
+162ms ║║Condition group #10 evaluated false (state changed) (10ms)
+164ms ║╚Execution stage complete. (19ms)
+166ms ╚Event processed successfully (165ms)
2/26/2019, 7:54:31 PM +202ms
+1ms ╔Received event [Home].time = 1551236070235 with a delay of 966ms
+246ms ║RunTime Analysis CS > 22ms > PS > 208ms > PE > 17ms > CE
+249ms ║Runtime (37534 bytes) successfully initialized in 208ms (v0.3.10a.20190223) (246ms)
+250ms ║╔Execution stage started
+267ms ║║Cancelling statement #14's schedules...
+308ms ║║Executed physical command [Door Garage Controller].off() (38ms)
+309ms ║║Executed [Door Garage Controller].off (40ms)
+313ms ║╚Execution stage complete. (64ms)
+315ms ╚Event processed successfully (315ms)
2/26/2019, 7:54:18 PM +4ms
+1ms ╔Received event [Door Garage Controller].switch = on with a delay of 563ms
+10202ms ║RunTime Analysis CS > 19ms > PS > 10169ms > PE > 14ms > CE
+10203ms ║Piston waited at a semaphore for 10019ms
+10205ms ║Runtime (37627 bytes) successfully initialized in 10169ms (v0.3.10a.20190223) (10203ms)
+10206ms ║╔Execution stage started
+10216ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+10218ms ║║Cancelling condition #11's schedules...
+10219ms ║║Condition #11 evaluated true (7ms)
+10220ms ║║Cancelling condition #10's schedules...
+10221ms ║║Condition group #10 evaluated true (state changed) (10ms)
+10224ms ║║Cancelling statement #12's schedules...
+10228ms ║║Executed virtual command wait (1ms)
+10230ms ║║Requesting a wake up for Tue, Feb 26 2019 @ 7:54:30 PM MST (in 2.0s)
+10235ms ║╚Execution stage complete. (28ms)
+10236ms ║Setting up scheduled job for Tue, Feb 26 2019 @ 7:54:30 PM MST (in 1.995s)
+10249ms ╚Event processed successfully (10248ms)
2/26/2019, 7:54:17 PM +993ms
+2ms ╔Received event [Door Garage Controller].switch = on with a delay of 571ms
+10206ms ║RunTime Analysis CS > 22ms > PS > 10169ms > PE > 15ms > CE
+10208ms ║Piston waited at a semaphore for 10018ms
+10210ms ║Runtime (37627 bytes) successfully initialized in 10169ms (v0.3.10a.20190223) (10206ms)
+10211ms ║╔Execution stage started
+10221ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+10223ms ║║Cancelling condition #11's schedules...
+10224ms ║║Condition #11 evaluated true (7ms)
+10226ms ║║Cancelling condition #10's schedules...
+10227ms ║║Condition group #10 evaluated true (state changed) (10ms)
+10230ms ║║Cancelling statement #12's schedules...
+10234ms ║║Executed virtual command wait (1ms)
+10235ms ║║Requesting a wake up for Tue, Feb 26 2019 @ 7:54:30 PM MST (in 2.0s)
+10240ms ║╚Execution stage complete. (30ms)
+10242ms ║Setting up scheduled job for Tue, Feb 26 2019 @ 7:54:30 PM MST (in 1.995s)
+10250ms ╚Event processed successfully (10250ms)
2/26/2019, 7:54:07 PM +923ms
+2ms ╔Received event [Door Garage Controller].switch = off with a delay of 541ms
+10254ms ║RunTime Analysis CS > 21ms > PS > 10216ms > PE > 17ms > CE
+10255ms ║Piston waited at a semaphore for 10025ms
+10257ms ║Runtime (37628 bytes) successfully initialized in 10216ms (v0.3.10a.20190223) (10254ms)
+10258ms ║╔Execution stage started
+10269ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+10271ms ║║Condition #11 evaluated false (6ms)
+10273ms ║║Condition group #10 evaluated false (state did not change) (8ms)
+10275ms ║╚Execution stage complete. (17ms)
+10277ms ╚Event processed successfully (10277ms)
2/26/2019, 7:54:14 PM +650ms
+2ms ╔Received event [Door Garage Controller].switch = off with a delay of 532ms
+229ms ║RunTime Analysis CS > 25ms > PS > 188ms > PE > 16ms > CE
+231ms ║Runtime (37544 bytes) successfully initialized in 188ms (v0.3.10a.20190223) (228ms)
+233ms ║╔Execution stage started
+243ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+245ms ║║Cancelling condition #11's schedules...
+246ms ║║Condition #11 evaluated false (8ms)
+247ms ║║Cancelling condition #10's schedules...
+248ms ║║Condition group #10 evaluated false (state changed) (10ms)
+251ms ║╚Execution stage complete. (19ms)
+252ms ╚Event processed successfully (252ms)
2/26/2019, 7:54:12 PM +175ms
+1ms ╔Received event [Door Garage Controller].switch = on with a delay of 470ms
+137ms ║RunTime Analysis CS > 13ms > PS > 114ms > PE > 10ms > CE
+139ms ║Runtime (37549 bytes) successfully initialized in 114ms (v0.3.10a.20190223) (138ms)
+140ms ║╔Execution stage started
+151ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+153ms ║║Cancelling condition #11's schedules...
+154ms ║║Condition #11 evaluated true (7ms)
+155ms ║║Cancelling condition #10's schedules...
+156ms ║║Condition group #10 evaluated true (state changed) (10ms)
+159ms ║║Cancelling statement #12's schedules...
+163ms ║║Executed virtual command wait (1ms)
+164ms ║║Waiting for 2000ms
+2168ms ║║Cancelling statement #14's schedules...
+2187ms ║║Executed physical command [Door Garage Controller].off() (15ms)
+2189ms ║║Executed [Door Garage Controller].off (18ms)
+2193ms ║╚Execution stage complete. (2053ms)
+2195ms ╚Event processed successfully (2195ms)
2/26/2019, 7:54:07 PM +913ms
+1ms ╔Received event [Door Garage Controller].switch = off with a delay of 556ms
+192ms ║RunTime Analysis CS > 23ms > PS > 156ms > PE > 13ms > CE
+194ms ║Runtime (37544 bytes) successfully initialized in 156ms (v0.3.10a.20190223) (192ms)
+195ms ║╔Execution stage started
+206ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+208ms ║║Cancelling condition #11's schedules...
+209ms ║║Condition #11 evaluated false (8ms)
+210ms ║║Cancelling condition #10's schedules...
+212ms ║║Condition group #10 evaluated false (state changed) (10ms)
+214ms ║╚Execution stage complete. (19ms)
+216ms ╚Event processed successfully (216ms)
2/26/2019, 7:54:05 PM +387ms
+2ms ╔Received event [Door Garage Controller].switch = on with a delay of 495ms
+187ms ║RunTime Analysis CS > 18ms > PS > 157ms > PE > 12ms > CE
+190ms ║Runtime (37549 bytes) successfully initialized in 157ms (v0.3.10a.20190223) (186ms)
+191ms ║╔Execution stage started
+201ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+203ms ║║Cancelling condition #11's schedules...
+204ms ║║Condition #11 evaluated true (7ms)
+205ms ║║Cancelling condition #10's schedules...
+207ms ║║Condition group #10 evaluated true (state changed) (10ms)
+209ms ║║Cancelling statement #12's schedules...
+214ms ║║Executed virtual command wait (0ms)
+215ms ║║Waiting for 2000ms
+2218ms ║║Cancelling statement #14's schedules...
+2240ms ║║Executed physical command [Door Garage Controller].off() (16ms)
+2241ms ║║Executed [Door Garage Controller].off (19ms)
+2244ms ║╚Execution stage complete. (2054ms)
+2245ms ╚Event processed successfully (2245ms)
2/26/2019, 7:53:55 PM +11ms
+1ms ╔Received event [Door Garage Controller].switch = off with a delay of 498ms
+284ms ║RunTime Analysis CS > 17ms > PS > 255ms > PE > 12ms > CE
+286ms ║Runtime (37544 bytes) successfully initialized in 255ms (v0.3.10a.20190223) (284ms)
+287ms ║╔Execution stage started
+297ms ║║Comparison (enum) off changes_to (string) on = false (1ms)
+299ms ║║Cancelling condition #11's schedules...
+300ms ║║Condition #11 evaluated false (7ms)
+301ms ║║Cancelling condition #10's schedules...
+302ms ║║Condition group #10 evaluated false (state changed) (10ms)
+305ms ║╚Execution stage complete. (18ms)
+307ms ╚Event processed successfully (307ms)
2/26/2019, 7:53:52 PM +498ms
+1ms ╔Received event [Door Garage Controller].switch = on with a delay of 472ms
+140ms ║RunTime Analysis CS > 14ms > PS > 118ms > PE > 9ms > CE
+143ms ║Runtime (37548 bytes) successfully initialized in 118ms (v0.3.10a.20190223) (140ms)
+144ms ║╔Execution stage started
+155ms ║║Comparison (enum) on changes_to (string) on = true (1ms)
+157ms ║║Cancelling condition #11's schedules...
+158ms ║║Condition #11 evaluated true (8ms)
+159ms ║║Cancelling condition #10's schedules...
+160ms ║║Condition group #10 evaluated true (state changed) (10ms)
+163ms ║║Cancelling statement #12's schedules...
+168ms ║║Executed virtual command wait (0ms)
+169ms ║║Waiting for 2000ms
+2173ms ║║Cancelling statement #14's schedules...
+2193ms ║║Executed physical command [Door Garage Controller].off() (15ms)
+2194ms ║║Executed [Door Garage Controller].off (18ms)
+2197ms ║╚Execution stage complete. (2053ms)
+2199ms ╚Event processed successfully (2199ms)

Clear

Full

#9

can you just change the outlet device type in the Smartthings IDE to a momentary switch? I copied the momentary switch device type and created one with a one second on time for my garage door opener. It was fairly simple but that was a z-wave device. Not sure what type of device you are using.


#10

Based on this block, it looks like the piston ran correctly after your wait, and saw that the Door Controller was already off, so it exited without doing anything. I’d guess that your hub hadn’t gotten the updated status yet that it was on. A longer delay should help, or you can change the piston to always execute the command even if it looks like it isn’t needed. This will send the off command even if the switch is already off. I’ll see if I can find where that is set unless someone beats me to it.


#11

Use options in the top right hand corner and then click, show piston settings.

On the piston you will now see lines for:

settings
end settings

Click settings and then click the little cog to the bottom right.

Set command optimizations to “disabled”

This will send the off command even if the switch is already off.


#12

Sorry, for the slow response, I had surgery on both hands twelve days ago and haven’t been up to messing with this.

I can’t find any momentary switch device handlers that will work with Zigbee devices.


#13

Sorry, for the slow response, I had surgery on both hands twelve days ago and haven’t been up to messing with this.

I did this and it seemed to help. I tested it by turning the switch on and waiting for it to turn itself off over and over again. It worked perfectly while I was doing this, but then a couple of days later my wife turned it on just once and it never turned off again. This was after disabling command optimizations and setting the wait period to ten seconds.


#14

Move your ‘Wait’

IF
-Outlet 4’s switch changes to ‘ON’
–Then
–With (N)
—Outlet 4
–Do
—Wait 1 seconds
—Turn Off
–end with
end if

The (N) Never Cancel does not include the Wait command.

Also, you might want to move this out into it’s own Piston.


#15

I’ll try this, this seems like a better way to do it.

I have had it in it’s own piston since shortly after posting this.

Thanks for the tip. This is really hard to diagnose when it only fails occasionally.