search

ady624 / CoRE

CoRE - Community's own Rule Engine
GNU General Public License v3.0
222 stars 663 forks source link

RGB/W Capability - Capture state to local store (null) #45

Open bryanplong opened 8 years ago

bryanplong commented 8 years ago

Possible Bug - When attempting to save "local and global state" of Philips Hue bulbs (RGB/W Capability). It appears that "Capture state to local store" never actually saves anything but 'null' in this scenario and several others that previously worked. I am able to save state to a local variable and then load it without issue; however, variables don't give me the ability to check for "Only if state is empty".

Live Log:

0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ╔═══ Done in 532ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: trace ║╔══ Task processing took 525ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: trace ║║░░ Removing any existing ST safety nets
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: info ║║░░ Executing virtual command loadStateLocally (42ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Storing state variable :::8f4938e2-ea30-4053-98ae-006e91246c37::: with value null
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Load from state: attributes are [hue, saturation, level, switch], values are null
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Processing command task [taskId:2, time:1471747410650, idx:2, created:1471747400685, ownerId:6, data:[p:[[d:[hue, saturation, level, switch], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:8f4938e2-ea30-4053-98ae-006e91246c37]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: info ║║░░ Executing virtual command loadStateLocally (53ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Storing state variable :::f5cf5350-4682-4af5-9d82-c5ee794cce20::: with value null
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Load from state: attributes are [hue, saturation, level, switch], values are null
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Processing command task [taskId:2, time:1471747410650, idx:1, created:1471747400685, ownerId:6, data:[p:[[d:[hue, saturation, level, switch], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:f5cf5350-4682-4af5-9d82-c5ee794cce20]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ║║░░ Found 2 tasks due at this time
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: trace ║║░░ Installing ST safety net
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: trace ║║░░ Rescheduling time triggers
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: trace ║╚══ Processing tasks (v0.2.142.20160820)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:30 PM: debug ╚═══ Received a time event
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ╔═══ Done in 341ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: trace ║╔══ Task processing took 94ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: trace ║║░░ Removing any existing ST safety nets
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: info ║║░░ Scheduling ST job to run in 10.0s, at Sat, Aug 20 2016 @ 8:43 PM MDT
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: trace ║║░░ Rescheduling time triggers
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: trace ║╚══ Processing tasks (v0.2.142.20160820)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: trace ║╔══ Event processing took 245ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: info ║║░░ :diamonds: Latching Piston changed state to false :diamonds:
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ║║░░ Secondary IF block evaluation result is true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ║║░░ :clubs: Function eval_trg_changes_to for Upstairs Motion Sensor's motion [inactive] changes to 'inactive' returned true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ║║░░ Event eligibility for the secondary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ║║░░ Primary IF block evaluation result is false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ║║░░ :spades: Function eval_trg_changes_to for Upstairs Motion Sensor's motion [inactive] changes to 'active' returned false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ║║░░ Event eligibility for the primary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: trace ║╚══ Processing event motion for device Upstairs Motion Sensor with id 43f38e09-dbe3-4270-9bce-6d6ca9a958fc, value inactive, generated on Sun Aug 21 02:43:19 UTC 2016, about 1106ms ago (v0.2.142.20160820)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:20 PM: debug ╚═══ Received a dual block device event
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:09 PM: debug ╔═══ Done in 2080ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:08 PM: debug ║║░░ Processing command task [taskId:1, time:1471747388151, idx:4, created:1471747388160, marker:1471747388164, ownerId:8, data:[p:[[d:Crimson, t:enum, i:0], [d:null, t:text, i:1], [d:null, t:number, i:2], [d:null, t:number, i:3], [d:null, t:number, i:4]]], type:cmd, deviceId:8f4938e2-ea30-4053-98ae-006e91246c37]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:08 PM: trace ║║░░ Rescheduling time triggers
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:08 PM: debug ║║░░ Found 2 tasks due at this time
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:08 PM: trace ║╚══ Processing tasks (v0.2.142.20160820)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: info ║║░░ :diamonds: Latching Piston changed state to true :diamonds:
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: debug ║║░░ Event eligibility for the secondary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: debug ║║░░ Primary IF block evaluation result is true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: debug ║║░░ :clubs: Function eval_trg_changes_to for Upstairs Motion Sensor's motion [active] changes to 'active' returned true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: trace ║╚══ Processing event motion for device Upstairs Motion Sensor with id 43f38e09-dbe3-4270-9bce-6d6ca9a958fc, value active, generated on Sun Aug 21 02:43:05 UTC 2016, about 1306ms ago (v0.2.142.20160820)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: debug ║║░░ Event eligibility for the primary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21 8:43:07 PM: debug ╚═══ Received a dual block device event

Dashboard view:

dashboard

ady624 commented 8 years ago

Is this issue still happening?

bryanplong commented 8 years ago

It appears that it is working now!? Did you modify something in the latest version to fix it? I didn't see any notes referencing this issue. If so, thanks!

0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:54:27 PM: debug ╔═══ Done
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:54:27 PM: debug ╚═══ Initializing app...
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:56 PM: info ║║░░ Executing [Up 2].setSaturation(96)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:56 PM: info ║║░░ Executing [Up 1].off()
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:56 PM: info ║║░░ Executing [Up 2].setLevel(98)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:56 PM: info ║║░░ Executing [Up 1].setLevel(98)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:52 PM: info ║║░░ Executing [Up 1].setHue(0.8333333333333333)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:56 PM: info ║║░░ Executing virtual command loadStateLocally (4586ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:51 PM: debug ║║░░ Load from state: attributes are [switch, level, hue, saturation], values are [level:98, saturation:99, hue:3.6, switch:off]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:51 PM: debug ║║░░ Processing command task [taskId:3, time:1475088829490, idx:1, created:1475088799598, ownerId:6, data:[c:true, p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]], cc:null], type:cmd, deviceId:68e8c565-be3e-4030-815b-dc9fafb1cf68]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:51 PM: debug ║║░░ Found 5 tasks due at this time
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:51 PM: trace ║╚══ Processing tasks (v0.3.157.20160928)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:51 PM: trace ╚═══ Received a time event
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: trace ║║░░ Rescheduling time triggers
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: info ║║░░ Scheduling ST job to run in 30.0s, at Wed, Sep 28 2016 @ 12:53 PM MDT
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: trace ║╚══ Processing tasks (v0.3.157.20160928)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: trace ║╔══ Event processing took 468ms
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: debug ║║░░ ♣ Function eval_trg_changes_to for Upstairs Motion Sensor's motion [inactive] changes to 'inactive' returned true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: info ║║░░ ♦ Latching Piston changed state to false ♦
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: debug ║║░░ Secondary IF block evaluation result is true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: debug ║║░░ Primary IF block evaluation result is false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: debug ║║░░ Event eligibility for the secondary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: debug ║║░░ ♠ Function eval_trg_changes_to for Upstairs Motion Sensor's motion [inactive] changes to 'active' returned false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:19 PM: debug ║║░░ Event eligibility for the primary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:07 PM: info ║║░░ Executing command: [Up 1].setColor([hue:33, saturation:100, level:20]) (779ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Processing command task [taskId:1, time:1475088786084, idx:4, created:1475088786146, marker:1475088786173, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:d12bd3cc-915d-467e-9efa-87a12e8e1bb6]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:07 PM: info ║║░░ Executing virtual command saveStateLocally (31ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Processing command task [taskId:1, time:1475088786084, idx:5, marker:1475088786173, created:1475088786147, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:3b6a65b4-9c07-467a-a54e-c2d5c7b52900]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: info ║║░░ Executing virtual command saveStateLocally (38ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Storing state variable :::e6d85555-c146-4509-bc14-cbc2dbc3d771::: with value [switch:off, level:98, hue:32.4, saturation:98]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Storing state variable :::d12bd3cc-915d-467e-9efa-87a12e8e1bb6::: with value [switch:off, level:98, hue:50.4, saturation:99]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Save to state: attributes are [switch, level, hue, saturation], values are [switch:off, level:98, hue:32.4, saturation:98]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Processing command task [taskId:1, time:1475088786084, idx:3, marker:1475088786173, created:1475088786145, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:e6d85555-c146-4509-bc14-cbc2dbc3d771]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: info ║║░░ Executing virtual command saveStateLocally (28ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: info ║║░░ Executing virtual command saveStateLocally (31ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Storing state variable :::0a06176a-6507-4caf-8813-0ef77f22a5d6::: with value [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Save to state: attributes are [switch, level, hue, saturation], values are [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: info ║║░░ Executing virtual command saveStateLocally (49ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Processing command task [taskId:1, time:1475088786084, idx:2, created:1475088786144, marker:1475088786173, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:0a06176a-6507-4caf-8813-0ef77f22a5d6]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Save to state: attributes are [switch, level, hue, saturation], values are [switch:off, level:98, hue:3.6, saturation:99]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Storing state variable :::68e8c565-be3e-4030-815b-dc9fafb1cf68::: with value [switch:off, level:98, hue:3.6, saturation:99]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Processing command task [taskId:1, time:1475088786084, idx:1, marker:1475088786173, created:1475088786143, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:68e8c565-be3e-4030-815b-dc9fafb1cf68]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:06 PM: debug ║║░░ Found 10 tasks due at this time
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: debug ║║░░ Secondary IF block evaluation result is false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: debug ║║░░ ♠ Function eval_trg_changes_to for Upstairs Motion Sensor's motion [active] changes to 'inactive' returned false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: debug ║║░░ Event eligibility for the secondary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: debug ║║░░ Primary IF block evaluation result is true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: debug ║║░░ ♣ Function eval_trg_changes_to for Upstairs Motion Sensor's motion [active] changes to 'active' returned true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: debug ║║░░ Event eligibility for the primary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: trace ║╚══ Processing event motion for device Upstairs Motion Sensor with id 43f38e09-dbe3-4270-9bce-6d6ca9a958fc, value active, generated on Wed Sep 28 18:53:04 UTC 2016, about 851ms ago (v0.3.157.20160928)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:53:05 PM: trace ╚═══ Received a dual block device event
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:27 PM: debug ║║░░ Load from state: attributes are [switch, level, hue, saturation], values are [level:98, saturation:96, hue:3.6, switch:off]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: debug ║║░░ Processing command task [taskId:3, time:1475088676352, idx:3, created:1475088646465, ownerId:6, data:[c:true, p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]], cc:null], type:cmd, deviceId:e6d85555-c146-4509-bc14-cbc2dbc3d771]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: debug ║║░░ Processing command task [taskId:3, time:1475088676352, idx:4, created:1475088646466, ownerId:6, data:[c:true, p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]], cc:null], type:cmd, deviceId:d12bd3cc-915d-467e-9efa-87a12e8e1bb6]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: info ║║░░ Executing virtual command loadStateLocally (472ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: debug ║║░░ Storing state variable :::0a06176a-6507-4caf-8813-0ef77f22a5d6::: with value null
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: info ║║░░ Executing [Up 2].setSaturation(96)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: info ║║░░ Executing [Up 2].off()
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:26 PM: info ║║░░ Executing [Up 2].setLevel(98)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: info ║║░░ Executing [Up 2].setHue(0.8333333333333333)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: debug ║║░░ Load from state: attributes are [switch, level, hue, saturation], values are [level:98, saturation:96, hue:3.6, switch:off]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: debug ║║░░ Processing command task [taskId:3, time:1475088676352, idx:2, created:1475088646464, ownerId:6, data:[c:true, p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]], cc:null], type:cmd, deviceId:0a06176a-6507-4caf-8813-0ef77f22a5d6]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: debug ║║░░ Storing state variable :::68e8c565-be3e-4030-815b-dc9fafb1cf68::: with value null
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: info ║║░░ Executing [Up 1].off()
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: info ║║░░ Executing virtual command loadStateLocally (4850ms)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:20 PM: debug ║║░░ Load from state: attributes are [switch, level, hue, saturation], values are [level:98, saturation:96, hue:3.6, switch:off]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:25 PM: info ║║░░ Executing [Up 1].setSaturation(96)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:20 PM: debug ║║░░ Processing command task [taskId:3, time:1475088676352, idx:1, created:1475088646464, ownerId:6, data:[c:true, p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]], cc:null], type:cmd, deviceId:68e8c565-be3e-4030-815b-dc9fafb1cf68]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:20 PM: debug ║║░░ Found 5 tasks due at this time
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:20 PM: trace ║║░░ Installing ST safety net
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:20 PM: trace ║║░░ Rescheduling time triggers
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:19 PM: trace ║╚══ Processing tasks (v0.3.157.20160928)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:51:19 PM: trace ╚═══ Received a time event
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:46 PM: debug ║║░░ Secondary IF block evaluation result is true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:45 PM: trace ╚═══ Received a dual block device event
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:34 PM: debug ║║░░ Processing command task [taskId:2, time:1475088632087, idx:8, created:1475088632187, marker:1475088632192, ownerId:3, data:[p:[[d:Dark Green, t:enum, i:0], [d:seconds, t:text, i:1], [d:null, t:number, i:2], [d:null, t:number, i:3], [d:null, t:number, i:4]]], type:cmd, deviceId:e6d85555-c146-4509-bc14-cbc2dbc3d771]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:33 PM: debug ║║░░ Processing command task [taskId:1, time:1475088632087, idx:5, marker:1475088632192, created:1475088632174, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:3b6a65b4-9c07-467a-a54e-c2d5c7b52900]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:33 PM: debug ║║░░ Storing state variable :::3b6a65b4-9c07-467a-a54e-c2d5c7b52900::: with value [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:33 PM: debug ║║░░ Storing state variable :::e6d85555-c146-4509-bc14-cbc2dbc3d771::: with value [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:33 PM: debug ║║░░ Storing state variable :::d12bd3cc-915d-467e-9efa-87a12e8e1bb6::: with value [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:33 PM: debug ║║░░ Processing command task [taskId:1, time:1475088632087, idx:4, created:1475088632173, marker:1475088632192, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:d12bd3cc-915d-467e-9efa-87a12e8e1bb6]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Save to state: attributes are [switch, level, hue, saturation], values are [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Save to state: attributes are [switch, level, hue, saturation], values are [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Processing command task [taskId:1, time:1475088632087, idx:2, created:1475088632172, marker:1475088632192, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:0a06176a-6507-4caf-8813-0ef77f22a5d6]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Processing command task [taskId:1, time:1475088632087, idx:1, marker:1475088632192, created:1475088632172, ownerId:3, data:[p:[[d:[switch, level, hue, saturation], t:attributes, i:0], [d:true, t:bool, i:1]]], type:cmd, deviceId:68e8c565-be3e-4030-815b-dc9fafb1cf68]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Storing state variable :::68e8c565-be3e-4030-815b-dc9fafb1cf68::: with value [switch:off, level:98, hue:3.6, saturation:96]
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: trace ║╚══ Processing tasks (v0.3.157.20160928)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Found 10 tasks due at this time
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: trace ║║░░ Installing ST safety net
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ Secondary IF block evaluation result is false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: info ║║░░ ♦ Latching Piston changed state to true ♦
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:32 PM: debug ║║░░ ♠ Function eval_trg_changes_to for Upstairs Motion Sensor's motion [active] changes to 'inactive' returned false
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:31 PM: debug ║║░░ Event eligibility for the secondary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:31 PM: debug ║║░░ Primary IF block evaluation result is true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:31 PM: debug ║║░░ ♣ Function eval_trg_changes_to for Upstairs Motion Sensor's motion [active] changes to 'active' returned true
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:31 PM: debug ║║░░ Event eligibility for the primary IF block is 2 - ELIGIBLE (triggers required, event is a trigger)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:31 PM: trace ║╚══ Processing event motion for device Upstairs Motion Sensor with id 43f38e09-dbe3-4270-9bce-6d6ca9a958fc, value active, generated on Wed Sep 28 18:50:30 UTC 2016, about 1305ms ago (v0.3.157.20160928)
0e50ced2-b9d6-4d7e-9cbb-bd4cdc2e7e21  12:50:31 PM: trace ╚═══ Received a dual block device event