Momentary Button Tile hitting 10s semaphore wait and then timeout


#1

1) Give a description of the problem
My Momentary Button Tile based pistons keep hitting a 10s semaphore wait, and will often do nothing as a result

2) What is the expected behavior?
No semaphore wait (or a smaller one) - 10s seems to be some sort of timeout?

3) What is happening/not happening?
Odd delays keep happening, but only with momentary buttons - I think it’s because events are coming to fast? It might need a new device handler, but i’m unsure.

**4) Post a Green Snapshot of the piston

5) Attach any logs (From ST IDE and by turning logging level to Full)

ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:58 AM: trace ║╔ Execution stage complete. (24ms)
ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:58 AM: debug ║║ Cancelling condition #4's schedules...
ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:58 AM: warn ║ Piston waited at a semaphore for 10087ms
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:55 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:57 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:56 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:58 AM: info ╔ Event processed successfully (10213ms)
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:57 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:57 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:53 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:56 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:53 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:55 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:57 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:52 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:54 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:53 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:56 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:54 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:54 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:51 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:55 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:52 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:53 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:51 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:52 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:51 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:50 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:50 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:50 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:49 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:49 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:49 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:49 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:48 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
df9dc888-b954-42f3-a2f7-a0553a4e4b53  11:00:48 AM: info smartapp_pause called by smartAppName=webCoRE, value=250, smartAppVersionId=983fb77a-9572-445b-bfb9-3c1911b98a42
ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:48 AM: info ╚ Received event [Home Mode].switch = off with a delay of 74ms
ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:48 AM: debug ║ RunTime Analysis CS > 17ms > PS > 16ms > PE > 53ms > CE
ef9b4ca5-3916-421e-8706-1fa071bcca45  11:00:48 AM: info ╚ Received event [Home Mode].switch = on with a delay of 66ms`

#2

I tried your example and I’m getting almost the same thing except I changed the Task Cancellation Policy (TCP) to never so that the piston doesn’t cancel the task when the button goes to off status.

Click the with, then the cog, select Never cancel tasks for TCP. It works but not consistently.

Edit: I looked at what I was using for my manual mode toggle, I have Simulated Button instead of Momentary Button Tile. It worked better for my setup.


#3

I too have had intermittent success using momentary button tiles. I switched those Pistons to use simulated switches and they work every time now.


#4

Interesting. I have rearranged the way my mode momentaries work with my pistons/routines and noticed I was getting a 10s timeout sometimes. When I activate my night mode from a button sometimes the house goes to sleep immediately and other times it takes forever for the piston to run. I will try changing to simulated switches and see if things improve.

Edit. Rats, Simulated Button doesn’t have the switch capability so it disappears from selection in my ST Routine. Everything is based on this as we use the cell widget to perform tasks. Back to the Momentary Button for now as it has this capability.


#5

I toyed more about this today and tried enabling parallelism in the advanced settings. I got more consistent result with it.

12/28/2017, 11:27:08 AM +692ms
+100ms	║Mode set to Vacation.
12/28/2017, 11:27:05 AM +292ms
+110ms	║Mode set to Home.
12/28/2017, 11:27:02 AM +249ms
+117ms	║Mode set to Vacation.
12/28/2017, 11:26:56 AM +302ms
+90ms	║Mode set to Home.
12/28/2017, 11:26:52 AM +162ms
+98ms	║Mode set to Vacation.
12/28/2017, 11:26:46 AM +215ms
+94ms	║Mode set to Home.