@Robin nice catch I was looking at the logs like this all looks right lol
Making web request to IFTTT
Tested- No announcements at all. SMS received at 4 mins
12/6/2017, 8:43:54 PM +190ms
+2ms ╔Received event [Refrigerator].contact = closed with a delay of 297ms
+106ms ║RunTime Analysis CS > 16ms > PS > 51ms > PE > 38ms > CE
+124ms ║Runtime (41938 bytes) successfully initialized in 51ms (v0.2.0ff.20171129) (122ms)
+125ms ║╔Execution stage started
+135ms ║║Comparison (enum) closed is (string) open = false (2ms)
+136ms ║║Cancelling condition #32’s schedules…
+137ms ║║Condition #32 evaluated false (6ms)
+138ms ║║Cancelling condition #8’s schedules…
+139ms ║║Condition group #8 evaluated false (state changed) (9ms)
+141ms ║║Cancelling statement #9’s schedules…
+143ms ║║Executed virtual command cancelTasks (0ms)
+149ms ║╚Execution stage complete. (23ms)
+158ms ╚Event processed successfully (158ms)
12/6/2017, 8:43:05 PM +334ms
+1ms ╔Received event [Home].time = 1512610986491 with a delay of -1157ms
+114ms ║RunTime Analysis CS > 17ms > PS > 58ms > PE > 39ms > CE
+133ms ║Runtime (41936 bytes) successfully initialized in 58ms (v0.2.0ff.20171129) (131ms)
+134ms ║╔Execution stage started
+148ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:2.0, vt:string]]]
+152ms ║║Comparison (decimal) 2.0 is (integer) 0 = false (2ms)
+162ms ║║Comparison (decimal) 2.0 is (integer) 1 = false (2ms)
+171ms ║║Comparison (decimal) 2.0 is (integer) 2 = true (1ms)
+183ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:3.0, vt:string]]]
+186ms ║║Comparison (decimal) 3.0 is (integer) 0 = false (2ms)
+190ms ║║Comparison (decimal) 3.0 is (integer) 1 = false (1ms)
+193ms ║║Comparison (decimal) 3.0 is (integer) 2 = false (2ms)
+197ms ║║Comparison (decimal) 3.0 is (integer) 3 = true (2ms)
+199ms ║║Cancelling statement #41’s schedules…
+236ms ║║Executed physical command [Kitchen Sonos].playText([Attention. Attention. The refrigerator door has been open for 8 minutes and Merrick has been notified. This is the final announcement., 3]) (29ms)
+237ms ║║Executed [Kitchen Sonos].playText (31ms)
+240ms ║║Cancelling statement #28’s schedules…
+243ms ║║Executed virtual command wait (0ms)
+244ms ║║Requesting a wake up for Wed, Dec 6 2017 @ 8:45:05 PM EST (in 120.0s)
+250ms ║╚Execution stage complete. (116ms)
+252ms ║Setting up scheduled job for Wed, Dec 6 2017 @ 8:45:05 PM EST (in 119.994s)
+280ms ╚Event processed successfully (280ms)
12/6/2017, 8:41:06 PM +300ms
+0ms ╔Received event [Home].time = 1512610867627 with a delay of -1328ms
+79ms ║RunTime Analysis CS > 13ms > PS > 35ms > PE > 32ms > CE
+95ms ║Runtime (41935 bytes) successfully initialized in 35ms (v0.2.0ff.20171129) (94ms)
+96ms ║╔Execution stage started
+110ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:1.0, vt:string]]]
+114ms ║║Comparison (decimal) 1.0 is (integer) 0 = false (2ms)
+128ms ║║Comparison (decimal) 1.0 is (integer) 1 = true (2ms)
+140ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:2.0, vt:string]]]
+143ms ║║Comparison (decimal) 2.0 is (integer) 0 = false (2ms)
+147ms ║║Comparison (decimal) 2.0 is (integer) 1 = false (1ms)
+150ms ║║Comparison (decimal) 2.0 is (integer) 2 = true (2ms)
+152ms ║║Cancelling statement #39’s schedules…
+183ms ║║Executed physical command [Kitchen Sonos].playText([Attention. Attention. The refrigerator door has been open for 6 minutes and Merrick has been notified., 3]) (23ms)
+183ms ║║Executed [Kitchen Sonos].playText (25ms)
+186ms ║║Cancelling statement #24’s schedules…
+190ms ║║Executed virtual command wait (0ms)
+191ms ║║Requesting a wake up for Wed, Dec 6 2017 @ 8:43:06 PM EST (in 120.0s)
+197ms ║╚Execution stage complete. (101ms)
+199ms ║Setting up scheduled job for Wed, Dec 6 2017 @ 8:43:06 PM EST (in 119.993s)
+223ms ╚Event processed successfully (223ms)
12/6/2017, 8:39:07 PM +285ms
+0ms ╔Received event [Home].time = 1512610748426 with a delay of -1142ms
+191ms ║RunTime Analysis CS > 83ms > PS > 56ms > PE > 52ms > CE
+207ms ║Runtime (41936 bytes) successfully initialized in 56ms (v0.2.0ff.20171129) (205ms)
+208ms ║╔Execution stage started
+224ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:0.0, vt:string]]]
+228ms ║║Comparison (decimal) 0.0 is (integer) 0 = true (2ms)
+247ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:1.0, vt:string]]]
+251ms ║║Comparison (decimal) 1.0 is (integer) 0 = false (1ms)
+255ms ║║Comparison (decimal) 1.0 is (integer) 1 = true (2ms)
+258ms ║║Cancelling statement #37’s schedules…
+297ms ║║Executed physical command [Kitchen Sonos].playText([Attention. Attention. It appears that the refrigerator door has been open for 4 minutes. I’m contacting Merrick, 3]) (32ms)
+298ms ║║Executed [Kitchen Sonos].playText (33ms)
+303ms ║║Cancelling statement #20’s schedules…
+335ms ║║Executed virtual command sendSMSNotification (24ms)
+340ms ║║Executed virtual command wait (0ms)
+341ms ║║Requesting a wake up for Wed, Dec 6 2017 @ 8:41:07 PM EST (in 120.0s)
+349ms ║╚Execution stage complete. (140ms)
+351ms ║Setting up scheduled job for Wed, Dec 6 2017 @ 8:41:07 PM EST (in 119.992s)
+373ms ╚Event processed successfully (374ms)
12/6/2017, 8:37:08 PM +254ms
+0ms ╔Received event [Home].time = 1512610629299 with a delay of -1046ms
+84ms ║RunTime Analysis CS > 14ms > PS > 42ms > PE > 28ms > CE
+103ms ║Runtime (41935 bytes) successfully initialized in 42ms (v0.2.0ff.20171129) (101ms)
+104ms ║╔Execution stage started
+118ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:0.0, vt:string]]]
+122ms ║║Comparison (decimal) 0.0 is (integer) 0 = true (2ms)
+128ms ║║Cancelling statement #33’s schedules…
+161ms ║║Executed physical command [Kitchen Sonos].playText([Attention. Attention. It appears that the refrigerator door has been open for 2 minutes. Please check on it., 3]) (26ms)
+162ms ║║Executed [Kitchen Sonos].playText (27ms)
+166ms ║║Cancelling statement #35’s schedules…
+170ms ║║Executed virtual command wait (0ms)
+171ms ║║Requesting a wake up for Wed, Dec 6 2017 @ 8:39:08 PM EST (in 120.0s)
+178ms ║╚Execution stage complete. (75ms)
+180ms ║Setting up scheduled job for Wed, Dec 6 2017 @ 8:39:08 PM EST (in 119.993s)
+205ms ╚Event processed successfully (204ms)
12/6/2017, 8:35:09 PM +170ms
+1ms ╔Received event [Refrigerator].contact = open with a delay of 439ms
+84ms ║RunTime Analysis CS > 13ms > PS > 39ms > PE > 32ms > CE
+96ms ║Runtime (41942 bytes) successfully initialized in 39ms (v0.2.0ff.20171129) (94ms)
+97ms ║╔Execution stage started
+106ms ║║Comparison (enum) open is (string) open = true (2ms)
+107ms ║║Cancelling condition #32’s schedules…
+108ms ║║Condition #32 evaluated true (6ms)
+109ms ║║Cancelling condition #8’s schedules…
+110ms ║║Condition group #8 evaluated true (state changed) (9ms)
+116ms ║║Evaluating switch with values [[i:12:null:0, v:[t:decimal, v:0.0, vt:string]]]
+119ms ║║Comparison (decimal) 0.0 is (integer) 0 = true (1ms)
+122ms ║║Cancelling statement #17’s schedules…
+128ms ║║Executed virtual command wait (0ms)
+129ms ║║Requesting a wake up for Wed, Dec 6 2017 @ 8:37:09 PM EST (in 120.0s)
+134ms ║╚Execution stage complete. (38ms)
+136ms ║Setting up scheduled job for Wed, Dec 6 2017 @ 8:37:09 PM EST (in 119.994s)
+160ms ╚Event processed successfully (160ms)
Clear Full
Again everything looks good in the logs. You can see it stepping through each section just fine.
And the logs seem to say that the announcements were made… but they weren’t. Weird, no?
This is possibly something unrelated to webcore… Sonos can sometimes react erratically with regard to ‘Groups’. For example: If I have all of my downstairs Sonos speakers grouped together to all play simultaneously, than what would happen if a piston gave a command to just one specific speaker (part of the group)? Sometimes it works and sometimes not. I’ll have to check and play around in the morning. Cannot tell you and Robin how much I appreciate your assistance!
WOWWWW!! You guys won’t believe what I figured out, through a ton of testing and scratching my head. Unfortunately you won’t be able to duplicate the problem unless you have speakers- but here it is:
Recall a few posts ago when I curiously asked why entering a value in the optional field for ‘Volume’ does not result in an entry actually showing in the piston, right? Ok, so I’ve now tested and learned that IF you enter a value in that field, regardless of what value you enter, the speaker will not play, however the logs will show nothing abnormal.
I hope I’m explaining in a way that you can understand… worded differently:
You CAN NOT use the ‘Volume (optional)’ field… If you do this:
NOTHING will play, but if you leave it like this:
You’ll be fine (the speaker will play). But either way (whether you enter a value or not), the piston will look like this (not displaying a volume value):
And the logs will look perfectly fine, like this:
@c1arkbar and @Robin,
Thanks so much for previous help on this piston- but I seem to get a bunch of misfires… I often hear Sonos announcements (and receive SMS) even when the refrigerator door (contact sensor) is closed. Today I happened to be looking at the SmartThings app at the very moment that a Sonos announcement said that the fridge was open… but ST showed the contact sensor was closed. Any ideas what would cause?