Triggering Simulated Contact Sensor for Alexa Annoucement

mikecoscia · 2018-09-19 21:01:29 UTC

1) Give a description of the problem
Created a piston to have alexa make an announcement when I arrive home. However, it is not completing it’s actions.

2) What is the expected behavior?
I arrive home and if I open my front door within 5 minutes it triggers a simulated contact sensor to open. This triggers Alexa to make an announcement. The contact sensor should then close within 5 seconds of being opened.

3) What is happening/not happening?
The contact sensor is not closing. It remains open preventing the piston from firing again.

4) Post a Green Snapshot of the piston

5) Attach any logs (From ST IDE and by turning logging level to Full)
I swapped out the presencePreformatted text detector for an ordinary switch to make creating the logs easier…

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:59:05 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:59:05 PM: debug Property Change Event temperature: 72 (source: DEVICE)

[aecfaf4e-86e9-4dc6-bc16-97f45b2e402c](https://graph.api.smartthings.com/ide/logs#aecfaf4e-86e9-4dc6-bc16-97f45b2e402c) 4:59:05 PM: trace | Nest Location Home Watchdog | TEMPERATURE Event | Device: MB Thermostat | Value: (72) with a delay of 43ms

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:04 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:04 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:04 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:04 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:59:04 PM: info ╔ Event processed successfully (123ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:59:03 PM: info ╚ Received event [Front Porch Light].switch = off with a delay of 540ms

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:59:03 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:59:03 PM: debug Property Change Event switch: off (source: DEVICE)

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:59:03 PM: trace Harmony - Sending data to Harmony Hub - Basement

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:59:03 PM: trace Harmony - Sending data to Kitchen

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:59:03 PM: trace Harmony - Sending data to Master Bedroom

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:59:03 PM: trace Harmony - Sending data to Living Room

[b73e5c1a-6449-40f9-b8fe-4515b268a6db](https://graph.api.smartthings.com/ide/logs#b73e5c1a-6449-40f9-b8fe-4515b268a6db) 4:59:03 PM: debug Parse returned Switch button was pressed

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:03 PM: trace MEDIARENDER RESPONSES

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:03 PM: debug getChildDevices(false), children=5

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:00 PM: trace MEDIARENDER RESPONSES

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:00 PM: debug getChildDevices(false), children=5

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:00 PM: debug getChildDevices(false), children=5

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:59:00 PM: debug getChildDevices(false), children=5

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:58 PM: info ╔ Event processed successfully (107ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:58 PM: info ╚ Received event [Home].time = 1537390739417 with a delay of -1337ms

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:58 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:57 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:57 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:57 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:57 PM: info ╔ Event processed successfully (169ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:57 PM: info ╚ Received event [Front Door].contact = closed with a delay of 549ms

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:57 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:57 PM: debug Property Change Event contact: closed (source: DEVICE)

[3765a981-6bff-4179-8e8c-2b2add03ef8e](https://graph.api.smartthings.com/ide/logs#3765a981-6bff-4179-8e8c-2b2add03ef8e) 4:58:57 PM: debug motionOrContactHandler(Front Door contact:closed)

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:57 PM: trace Harmony - Sending data to Harmony Hub - Basement

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:57 PM: trace Harmony - Sending data to Kitchen

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:57 PM: trace Harmony - Sending data to Master Bedroom

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:57 PM: trace Harmony - Sending data to Living Room

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:54 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:54 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:54 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:54 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:54 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:54 PM: debug Property Change Event contact: open (source: DEVICE)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:54 PM: info ╔ Event processed successfully (219ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:54 PM: info ║ Setting up scheduled job for Wed, Sep 19 2018 @ 4:58:59 PM EDT (in 4.994s)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:54 PM: info ╚ Received event [Front Door].contact = open with a delay of 542ms

[8cee5138-2b0d-4036-99c6-69a91a3ccf00](https://graph.api.smartthings.com/ide/logs#8cee5138-2b0d-4036-99c6-69a91a3ccf00) 4:58:54 PM: trace open()

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:54 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:54 PM: debug Property Change Event contact: open (source: DEVICE)

[3765a981-6bff-4179-8e8c-2b2add03ef8e](https://graph.api.smartthings.com/ide/logs#3765a981-6bff-4179-8e8c-2b2add03ef8e) 4:58:54 PM: info SHM isActiveSensor(contact:open, app: off, location: off) = false, locationId: b349b9b0-999c-4111-98a1-29a03220dc24, ISA: 3765a981-6bff-4179-8e8c-2b2add03ef8e

[3765a981-6bff-4179-8e8c-2b2add03ef8e](https://graph.api.smartthings.com/ide/logs#3765a981-6bff-4179-8e8c-2b2add03ef8e) 4:58:54 PM: debug motionOrContactHandler(Front Door contact:open)

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:54 PM: trace Harmony - Sending data to Harmony Hub - Basement

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:54 PM: trace Harmony - Sending data to Kitchen

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:54 PM: trace Harmony - Sending data to Master Bedroom

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:54 PM: trace Harmony - Sending data to Living Room

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:51 PM: debug Harmony - Current Activities: []

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:51 PM: debug getChildDevices(false), children=17

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:51 PM: debug Harmony - response body: {"hubs":{"5607758":{"status":200,"message":"OK","response":{"code":"200","msg":"OK","data":{"accountId":"4478191","configVersion":512,"sleepTimerId":-1,"activityStatus":0,"version":635,"contentVersion":61,"currentAvActivity":"-1","syncStatus":0,"currentActivities":[]}}},"6062362":{"status":200,"message":"OK","response":{"code":"200","msg":"OK","data":{"accountId":"6027615","configVersion":349,"sleepTimerId":-1,"activityStatus":0,"version":644,"contentVersion":112,"currentAvActivity":"-1","syncStatus":0,"currentActivities":[]}}},"9947231":{"status":200,"message":"OK","response":{"code":"200","msg":"OK","data":{"accountId":"9147122","configVersion":180,"sleepTimerId":-1,"activityStatus":0,"version":1061,"contentVersion":94,"currentAvActivity":"-1","syncStatus":0,"currentActivities":[]}}},"13213628":{"status":200,"message":"OK","response":{"code":"200","msg":"OK","data":{"accountId":"11619507","configVersion":163,"sleepTimerId":-1,"activityStatus":0,"version":100,"contentVersion":108,"currentAvActivity":"-1","syncStatus":0,"currentActivities":[]}}}}}

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:51 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:51 PM: debug Property Change Event DeviceWatch-DeviceStatus: online (source: DEVICE)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:50 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:50 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:50 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:50 PM: info ╔ Event processed successfully (283ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:50 PM: info ║ Setting up scheduled job for Wed, Sep 19 2018 @ 5:03:41 PM EDT (in 290.683s)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:50 PM: info ╚ Received event [Front Door].contact = closed with a delay of 632ms

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:50 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:50 PM: debug Property Change Event contact: closed (source: DEVICE)

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:50 PM: trace Harmony - Sending data to Harmony Hub - Basement

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:50 PM: trace Harmony - Sending data to Kitchen

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:50 PM: trace Harmony - Sending data to Master Bedroom

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:50 PM: trace Harmony - Sending data to Living Room

[3765a981-6bff-4179-8e8c-2b2add03ef8e](https://graph.api.smartthings.com/ide/logs#3765a981-6bff-4179-8e8c-2b2add03ef8e) 4:58:50 PM: debug motionOrContactHandler(Front Door contact:closed)

[d816545e-dc92-4f3a-afd6-6e28fdd86315](https://graph.api.smartthings.com/ide/logs#d816545e-dc92-4f3a-afd6-6e28fdd86315) 4:58:48 PM: info ||| NST Manager (v5.5.1) | API Device Data HAS Changed (POLL(ASYNC))

[d816545e-dc92-4f3a-afd6-6e28fdd86315](https://graph.api.smartthings.com/ide/logs#d816545e-dc92-4f3a-afd6-6e28fdd86315) 4:58:48 PM: info ||| NST Manager (v5.5.1) | Updating Structure Data (Last Updated: 180 seconds ago) | Updating Device Data (Last Updated: 180 seconds ago) (async)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:46 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:46 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:45 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:45 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:45 PM: info ╔ Event processed successfully (121ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:45 PM: info ║ Setting up scheduled job for Wed, Sep 19 2018 @ 5:03:41 PM EDT (in 295.645s)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:45 PM: info ╚ Received event [Front Door].contact = open with a delay of 529ms

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:45 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:45 PM: debug Property Change Event contact: open (source: DEVICE)

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:45 PM: trace Harmony - Sending data to Harmony Hub - Basement

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:45 PM: trace Harmony - Sending data to Kitchen

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:45 PM: trace Harmony - Sending data to Master Bedroom

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:45 PM: trace Harmony - Sending data to Living Room

[3765a981-6bff-4179-8e8c-2b2add03ef8e](https://graph.api.smartthings.com/ide/logs#3765a981-6bff-4179-8e8c-2b2add03ef8e) 4:58:45 PM: info SHM isActiveSensor(contact:open, app: off, location: off) = false, locationId: b349b9b0-999c-4111-98a1-29a03220dc24, ISA: 3765a981-6bff-4179-8e8c-2b2add03ef8e

[3765a981-6bff-4179-8e8c-2b2add03ef8e](https://graph.api.smartthings.com/ide/logs#3765a981-6bff-4179-8e8c-2b2add03ef8e) 4:58:45 PM: debug motionOrContactHandler(Front Door contact:open)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:41 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:41 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:41 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[bdf97140-5ae9-441c-b05d-d2fc9b84fb4f](https://graph.api.smartthings.com/ide/logs#bdf97140-5ae9-441c-b05d-d2fc9b84fb4f) 4:58:41 PM: error java.lang.StringIndexOutOfBoundsException: String index out of range: 184 @line 286 (locationHandler)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:41 PM: info ╔ Event processed successfully (137ms)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:41 PM: info ║ Setting up scheduled job for Wed, Sep 19 2018 @ 5:03:41 PM EDT (in 299.995s)

[5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1](https://graph.api.smartthings.com/ide/logs#5a42cc95-a5a4-4f3f-b3c9-951de6da6ad1) 4:58:41 PM: info ╚ Received event [Front Porch Light].switch = on with a delay of 572ms

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:41 PM: info postEventToEndpoint: event successfully posted.

[56986c0f-3f9f-438d-946d-b36987be33f4](https://graph.api.smartthings.com/ide/logs#56986c0f-3f9f-438d-946d-b36987be33f4) 4:58:41 PM: debug Property Change Event switch: on (source: DEVICE)

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:41 PM: trace Harmony - Sending data to Harmony Hub - Basement

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:41 PM: trace Harmony - Sending data to Kitchen

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:41 PM: trace Harmony - Sending data to Master Bedroom

[4f28ac3d-9382-41c9-aeae-14bce9becb16](https://graph.api.smartthings.com/ide/logs#4f28ac3d-9382-41c9-aeae-14bce9becb16) 4:58:41 PM: trace Harmony - Sending data to Living Room

[b73e5c1a-6449-40f9-b8fe-4515b268a6db](https://graph.api.smartthings.com/ide/logs#b73e5c1a-6449-40f9-b8fe-4515b268a6db) 4:58:40 PM: debug Parse returned Switch button was pressed

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

mikecoscia · 2018-09-24 20:16:55 UTC

So, I had the piston working for a day or two and now it stopped. Not sure what is going on. Here are the logs from when I arrived home today. The piston should have opened the virtual contact sensor and Alexa should have stated her welcome home message. Unfortunately, the virtual contact sensor never opened when the conditions were met. It worked Sat no problem, yesterday and today…no luck.

9/24/2018, 4:06:40 PM +729ms
+1ms	╔Received event [Front Door].contact = closed with a delay of 926ms
+107ms	║RunTime Analysis CS > 17ms > PS > 44ms > PE > 46ms > CE
+110ms	║Runtime (38670 bytes) successfully initialized in 44ms (v0.3.108.20180906) (108ms)
+111ms	║╔Execution stage started
+121ms	║║Comparison (string) :544a9b992c238a89c379073fefaa7fe1: is_any_of (string) :544a9b992c238a89c379073fefaa7fe1:,:aa4b274e22d2f4c0001b65e07d3b32bd:,:2fce84ee632722b31122dc1a90d826c3: = true (2ms)
+122ms	║║Condition #1 evaluated true (6ms)
+124ms	║║Condition group #null evaluated true (state did not change) (8ms)
+134ms	║║Cancelling condition #8's schedules...
+135ms	║║Condition #8 evaluated false (8ms)
+137ms	║║Cancelling statement #2's schedules...
+138ms	║║Condition group #2 evaluated false (state did not change) (12ms)
+141ms	║╚Execution stage complete. (29ms)
+142ms	╚Event processed successfully (142ms)
9/24/2018, 4:06:38 PM +531ms
+1ms	╔Received event [Front Door].contact = open with a delay of 928ms
+127ms	║RunTime Analysis CS > 23ms > PS > 50ms > PE > 54ms > CE
+129ms	║Runtime (38680 bytes) successfully initialized in 50ms (v0.3.108.20180906) (127ms)
+131ms	║╔Execution stage started
+140ms	║║Comparison (string) :544a9b992c238a89c379073fefaa7fe1: is_any_of (string) :544a9b992c238a89c379073fefaa7fe1:,:aa4b274e22d2f4c0001b65e07d3b32bd:,:2fce84ee632722b31122dc1a90d826c3: = true (2ms)
+142ms	║║Condition #1 evaluated true (6ms)
+143ms	║║Condition group #null evaluated true (state did not change) (8ms)
+148ms	║║Conditional ladder step failed due to a timeout
+150ms	║║Cancelling statement #2's schedules...
+151ms	║║Condition group #2 evaluated false (state did not change) (5ms)
+153ms	║╚Execution stage complete. (23ms)
+155ms	╚Event processed successfully (155ms)
9/24/2018, 4:05:33 PM +107ms
+1ms	╔Received event [Home].time = 1537819534789 with a delay of -1682ms
+120ms	║RunTime Analysis CS > 17ms > PS > 51ms > PE > 51ms > CE
+122ms	║Runtime (38682 bytes) successfully initialized in 51ms (v0.3.108.20180906) (120ms)
+124ms	║╔Execution stage started
+136ms	║╚Execution stage complete. (13ms)
+138ms	╚Event processed successfully (137ms)
9/24/2018, 4:02:28 PM +601ms
+1ms	╔Received event [Front Door].contact = closed with a delay of 924ms
+169ms	║RunTime Analysis CS > 23ms > PS > 75ms > PE > 71ms > CE
+172ms	║Runtime (38680 bytes) successfully initialized in 75ms (v0.3.108.20180906) (170ms)
+174ms	║╔Execution stage started
+188ms	║║Comparison (string) :544a9b992c238a89c379073fefaa7fe1: is_any_of (string) :544a9b992c238a89c379073fefaa7fe1:,:aa4b274e22d2f4c0001b65e07d3b32bd:,:2fce84ee632722b31122dc1a90d826c3: = true (3ms)
+191ms	║║Condition #1 evaluated true (10ms)
+193ms	║║Condition group #null evaluated true (state did not change) (12ms)
+206ms	║║Comparison (enum) closed changes_to (string) open = false (1ms)
+209ms	║║Condition #3 evaluated false (9ms)
+213ms	║╚Execution stage complete. (40ms)
+216ms	║Setting up scheduled job for Mon, Sep 24 2018 @ 4:05:34 PM EDT (in 185.973s)
+229ms	╚Event processed successfully (228ms)
9/24/2018, 4:02:24 PM +840ms
+1ms	╔Received event [Front Door].contact = open with a delay of 917ms
+139ms	║RunTime Analysis CS > 25ms > PS > 65ms > PE > 49ms > CE
+141ms	║Runtime (38677 bytes) successfully initialized in 65ms (v0.3.108.20180906) (139ms)
+143ms	║╔Execution stage started
+152ms	║║Comparison (string) :544a9b992c238a89c379073fefaa7fe1: is_any_of (string) :544a9b992c238a89c379073fefaa7fe1:,:aa4b274e22d2f4c0001b65e07d3b32bd:,:2fce84ee632722b31122dc1a90d826c3: = true (2ms)
+154ms	║║Condition #1 evaluated true (6ms)
+155ms	║║Condition group #null evaluated true (state did not change) (8ms)
+164ms	║║Comparison (enum) open changes_to (string) open = false (1ms)
+166ms	║║Cancelling condition #3's schedules...
+167ms	║║Condition #3 evaluated false (7ms)
+170ms	║╚Execution stage complete. (28ms)
+172ms	║Setting up scheduled job for Mon, Sep 24 2018 @ 4:05:34 PM EDT (in 189.778s)
+212ms	╚Event processed successfully (211ms)
9/24/2018, 4:00:34 PM +626ms
+1ms	╔Received event [Mike's iPhone].presence = present with a delay of 85ms
+130ms	║RunTime Analysis CS > 21ms > PS > 63ms > PE > 46ms > CE
+132ms	║Runtime (38676 bytes) successfully initialized in 63ms (v0.3.108.20180906) (130ms)
+134ms	║╔Execution stage started
+145ms	║║Comparison (string) :2fce84ee632722b31122dc1a90d826c3: is_any_of (string) :544a9b992c238a89c379073fefaa7fe1:,:aa4b274e22d2f4c0001b65e07d3b32bd:,:2fce84ee632722b31122dc1a90d826c3: = true (3ms)
+146ms	║║Condition #1 evaluated true (8ms)
+148ms	║║Condition group #null evaluated true (state did not change) (9ms)
+155ms	║║Comparison (enum) present changes_to (string) present = true (1ms)
+157ms	║║Cancelling condition #8's schedules...
+159ms	║║Condition #8 evaluated true (7ms)
+160ms	║║Cancelling statement #2's schedules...
+161ms	║║Condition group #2 made progress up the ladder, currently at step 1 of 2
+167ms	║╚Execution stage complete. (34ms)
+169ms	║Setting up scheduled job for Mon, Sep 24 2018 @ 4:05:34 PM EDT (in 299.995s)
+178ms	╚Event processed successfully (178ms)

Nezmo · 2018-09-24 20:55:29 UTC

Try setting command optimization for the piston to off. webCoRE only sends transactions it thinks are needed. If for some reason ST is not updating the status of the sensor promptly the webCoRE will think it’s still closed and not send the close command that is after the wait. Setting optimization will force all commands to be sent.

EDIT: Actually, my reply was based on your first problem noted in the opening post. I am not sure why the virtual contact is not opening as stated in your second post. But try what I said anyway in case it’s the same problem in reverse.

mikecoscia · 2018-09-24 21:08:54 UTC

Thanks for the help, yeah I don’t get it. I used a regular light switch for testing purposes and wit worked great. When I was finished I switched it over to my phone presence sensor. It fired a few times without an issues on Saturday when I was running errands during the day. Then that was it, the piston stopped triggering the virtual contact sensor to open and close. Works fine triggering Alexa if I manually open/close the contact. I made the change that you suggested, we will see what happens tomorrow when I return home from work.

mikecoscia · 2018-09-26 00:06:42 UTC

No dice, didn’t fire again when I came home. I don’t get it at all. The logs show me arriving home at 3:59, but there is nothing noting the front door open. If I look in smarthings the door sensor shows being opened and closed at 4:01. That should have triggered the piston to open and close the virtual contact sensor. I am at a complete loss. Here are the logs.

9/25/2018, 4:04:24 PM +69ms
+1ms ╔Received event [Home].time = 1537905865894 with a delay of -1825ms
+133ms ║RunTime Analysis CS &gt; 21ms &gt; PS &gt; 63ms &gt; PE &gt; 48ms &gt; CE
+136ms ║Runtime (38675 bytes) successfully initialized in 63ms (v0.3.108.20180906) (133ms)
+137ms ║╔Execution stage started
+149ms ║╚Execution stage complete. (13ms)
+151ms ╚Event processed successfully (150ms)
9/25/2018, 3:59:25 PM +720ms
+2ms ╔Received event [Mike's iPhone].presence = present with a delay of 105ms
+138ms ║RunTime Analysis CS &gt; 22ms &gt; PS &gt; 61ms &gt; PE &gt; 54ms &gt; CE
+141ms ║Runtime (38673 bytes) successfully initialized in 61ms (v0.3.108.20180906) (137ms)
+142ms ║╔Execution stage started
+153ms ║║Comparison (string) :2fce84ee632722b31122dc1a90d826c3: is_any_of (string) :544a9b992c238a89c379073fefaa7fe1:,:aa4b274e22d2f4c0001b65e07d3b32bd:,:2fce84ee632722b31122dc1a90d826c3: = true (3ms)
+155ms ║║Condition #1 evaluated true (8ms)
+156ms ║║Condition group #null evaluated true (state did not change) (10ms)
+164ms ║║Comparison (enum) present changes_to (string) present = true (1ms)
+166ms ║║Cancelling condition #8's schedules...
+167ms ║║Condition #8 evaluated true (8ms)
+168ms ║║Cancelling statement #2's schedules...
+171ms ║║Condition group #2 made progress up the ladder, currently at step 1 of 2
+178ms ║╚Execution stage complete. (37ms)
+180ms ║Setting up scheduled job for Tue, Sep 25 2018 @ 4:04:25 PM EDT (in 299.995s)
+189ms ╚Event processed successfully (189ms)

mikecoscia · 2018-10-09 23:17:39 UTC

So I am pretty sure I resolved the issue by changing the piston around a bit. I had it running on my presence sensor and my wife’s and it’s been activating pretty much without fail. Minus it repeating if i open the door again a second time within the 5 minutes. I added the cancel all pending tasks command to take care of that, but have’t tested it out yet. I’ll know tomrrow for sure and can mark this one solved.

mikecoscia · 2018-10-10 21:36:12 UTC

Well the cancel all pending tasks did not work, need to try something else. Anyone have suggestions?

mikecoscia · 2018-11-02 20:54:34 UTC

Still messing around with this piston. Someone recommended this particular one. It should fix the piston repeating, but I can’t get it to fire at all. Logs are below.

11/2/2018, 4:08:56 PM +658ms
+1ms ╔Received event [Front Door].contact = closed with a delay of 634ms
+137ms ║RunTime Analysis CS &gt; 24ms &gt; PS &gt; 67ms &gt; PE &gt; 47ms &gt; CE
+140ms ║Runtime (40199 bytes) successfully initialized in 67ms (v0.3.108.20180906) (138ms)
+141ms ║╔Execution stage started
+157ms ║║Condition #11 evaluated false (9ms)
+159ms ║║Condition group #1 evaluated false (state did not change) (10ms)
+167ms ║║Comparison (enum) closed changes_to (string) open = false (1ms)
+169ms ║║Cancelling condition #9's schedules...
+170ms ║║Condition #9 evaluated false (7ms)
+172ms ║║Condition group #5 evaluated false (state did not change) (9ms)
+175ms ║╚Execution stage complete. (33ms)
+176ms ╚Event processed successfully (176ms)
11/2/2018, 4:08:53 PM +433ms
+2ms ╔Received event [Front Door].contact = open with a delay of 651ms
+139ms ║RunTime Analysis CS &gt; 22ms &gt; PS &gt; 64ms &gt; PE &gt; 52ms &gt; CE
+142ms ║Runtime (40199 bytes) successfully initialized in 64ms (v0.3.108.20180906) (139ms)
+143ms ║╔Execution stage started
+160ms ║║Cancelling condition #11's schedules...
+161ms ║║Condition #11 evaluated false (9ms)
+162ms ║║Condition group #1 evaluated false (state did not change) (12ms)
+171ms ║║Comparison (enum) open changes_to (string) open = true (0ms)
+173ms ║║Cancelling condition #9's schedules...
+174ms ║║Condition #9 evaluated true (8ms)
+180ms ║║Comparison (boolean) false is (boolean) true = false (2ms)
+182ms ║║Condition #10 evaluated false (7ms)
+184ms ║║Condition group #5 evaluated false (state did not change) (18ms)
+187ms ║╚Execution stage complete. (43ms)
+188ms ╚Event processed successfully (188ms)
11/2/2018, 4:07:31 PM +404ms
+1ms ╔Received event [Mike's iPhone].presence = present with a delay of 95ms
+138ms ║RunTime Analysis CS &gt; 23ms &gt; PS &gt; 69ms &gt; PE &gt; 46ms &gt; CE
+140ms ║Runtime (40210 bytes) successfully initialized in 69ms (v0.3.108.20180906) (138ms)
+142ms ║╔Execution stage started
+154ms ║║Comparison (enum) present changes_to (string) present = true (1ms)
+157ms ║║Cancelling condition #11's schedules...
+158ms ║║Condition #11 evaluated true (9ms)
+190ms ║║Comparison (enum) present was_not (string) not present = false (25ms)
+192ms ║║Condition #12 evaluated false (33ms)
+193ms ║║Condition group #1 evaluated false (state did not change) (45ms)
+206ms ║║Condition #9 evaluated false (9ms)
+207ms ║║Condition group #5 evaluated false (state did not change) (10ms)
+210ms ║╚Execution stage complete. (69ms)
+212ms ╚Event processed successfully (213ms)

eibyer · 2018-11-02 21:05:02 UTC

Click the with on line 34 and then set the Task Cancellation Policy to Never so that the door closing event will not cancel the timers/tasks. See if that takes care of the issue.