I am using iViri along with iBeacons in my home to have a Multi-Switch triggered when I enter different rooms. So my wife has a multi-switch assigned to her iPhone and so do I that has 5 active buttons named (Bedroom, Door Lock, Basement, Kitchen, Living Room) and the behavior I have noticed is that like this morning my wife go up for work, entered the kitchen, the lights came on because her button was on and then she left the house where her button went off and no kitchen button was active (not mine nor hers) but when I got out of bed and walk out of the bedroom I noticed that the kitchen lights were still on. I signed onto my Vera’s UI and there were no buttons switched on for kitchen and the PLEG had a true condition that should turn off all of those lights, so I walked into the kitchen which turned on my kitchen button, the lights stayed on and when I exited they turned off. What I am thinking is either there is a memory issue on my Veralite where the condition changes are not always being activated. I actually deleted and rebuilt the PLEG yesterday in hopes that it was an issue with some old conditions and triggers that used to belong to this PLEG but it appears the behavior is still there. Please help me with what to do next to troubleshoot this!
Activate PLEG’s Debug to a Standalone File. You can find this by clicking on the Log button on the Control tab. This will allow you to look back if it happens again to see what PLEG did as a result of the triggers.
Thanks I just turned it on and this happens multiple times a day so we should be able to get another instance of it today. Should I post the log file here for people to review? I am curious if the Veralite isn’t powerful enough to handle all of this logic occurring with my proximity buttons.
If you do post the debug log, be sure to post a pdf of the PLEG’s current Status report too.
I would be surprised if the problem is happening because Vera Lite has run out of horse-power…
Condition 12 turns on my living rooms lights which fired and turned them on. You can see that condition is no longer true as of 14:57:54 and condition 13 that turns them off is now true but the lights have stayed on. The stuff you requested is attached, I have a screenshot showing that the lights are still on but it is too large to attach, would you like to give me an email address to send them to?
I search the Lua log and found this, but the light remains on.
08 04/29/14 14:57:54.026 JobHandler_LuaUPnP::HandleActionRequest device: 80 service: urn:dcineco-com:serviceId:MSwitch1 action: SetStatus5 <0x2f3e6680>
08 04/29/14 14:57:54.027 JobHandler_LuaUPnP::HandleActionRequest argument timeout=30 <0x2f3e6680>
08 04/29/14 14:57:54.027 JobHandler_LuaUPnP::HandleActionRequest argument minimumdelay=2000 <0x2f3e6680>
08 04/29/14 14:57:54.028 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=80 <0x2f3e6680>
08 04/29/14 14:57:54.028 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:dcineco-com:serviceId:MSwitch1 <0x2f3e6680>
08 04/29/14 14:57:54.028 JobHandler_LuaUPnP::HandleActionRequest argument action=SetStatus5 <0x2f3e6680>
08 04/29/14 14:57:54.028 JobHandler_LuaUPnP::HandleActionRequest argument newStatus5=0 <0x2f3e6680>
06 04/29/14 14:57:54.030 Device_Variable::m_szValue_set device: 80 service: urn:dcineco-com:serviceId:MSwitch1 variable: Status5 was: 1 now: 0 #hooks: 1 upnp: 0 v:0x98b240/NONE duplicate:0 <0x2b271000>
08 04/29/14 14:57:54.040 JobHandler_LuaUPnP::HandleActionRequest device: 80 service: urn:dcineco-com:serviceId:MSwitch1 action: SetStatus5 <0x2e7e6680>
08 04/29/14 14:57:54.045 JobHandler_LuaUPnP::HandleActionRequest argument timeout=30 <0x2e7e6680>
08 04/29/14 14:57:54.045 JobHandler_LuaUPnP::HandleActionRequest argument minimumdelay=2000 <0x2e7e6680>
08 04/29/14 14:57:54.045 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=80 <0x2e7e6680>
08 04/29/14 14:57:54.045 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:dcineco-com:serviceId:MSwitch1 <0x2e7e6680>
08 04/29/14 14:57:54.046 JobHandler_LuaUPnP::HandleActionRequest argument action=SetStatus5 <0x2e7e6680>
08 04/29/14 14:57:54.056 JobHandler_LuaUPnP::HandleActionRequest argument newStatus5=0 <0x2e7e6680>
06 04/29/14 14:57:54.092 Device_Variable::m_szValue_set device: 81 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: ConditionSatisfied was: None now: c13 #hooks: 0 upnp: 0 v:0x920e00/NONE duplicate:0 <0x2b271000>
06 04/29/14 14:57:54.093 Device_Variable::m_szValue_set device: 81 service: urn:rts-services-com:serviceId:ProgramLogicEG variable: LastConditionChange was: 1398801461 now: 1398801474 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2b271000>
08 04/29/14 14:57:54.094 JobHandler_LuaUPnP::HandleActionRequest device: 81 service: urn:rts-services-com:serviceId:ProgramLogicC action: RunAction <0x2b271000>
08 04/29/14 14:57:54.094 JobHandler_LuaUPnP::HandleActionRequest argument conditionName=c13 <0x2b271000>
06 04/29/14 14:57:54.105 Device_Variable::m_szValue_set device: 81 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ObjectStatusMap was: {{name = ‘jHome’, state = true, seq = 1398790513.1971, oseq = 1398790087.9809},{name = ‘jBedroom’, state = false, seq = 1398790724.8018, oseq = 1398790763.6919},{name = ‘jDoorLock’, state = false, seq = 1398790560.0108, oseq = 1398790604.5543},{name = ‘jBasement’, state = false, seq = 1398735468.2069, oseq = 1398735512.1457},{name = ‘jKitchen’, state = true, seq = 1398801443.5027, oseq = 1398794452.4583},{name = ‘jLivingRoom’, state = true, seq = 1398797277.3551, oseq = 1398797272.187},{name = ‘lHome’, state = false, seq = 1398793559.473, oseq = 1398796542.3505},{name = ‘lBedroom’, state = false, seq = 1398771552.3809, oseq = 1398773409.0034},{name = ‘lDoorLock’, state = false, seq = 0, oseq = 0},{name = ‘lBasement’, state = false, seq = 1398737684.6121, oseq = 1398737708.8928},{name = ‘lKitchen’, state = false, seq = 1398793876.8906, oseq = 1398796542.6759},{name = ‘lLivingRoom’, state = false, seq = 1398734483.906, oseq = 1398737875.3947},{name = ‘Night’, state = false, seq = 1398733548.218, oseq = 1398769618.1968},{name = ‘WeatherCondition1’, state = false, seq = 0, oseq = 1398735964.1724},{name = ‘WeatherCondition2’, state = true, seq = 1398735964.1727, oseq = 0},{name = ‘c1’, state = true, seq = 1398790513.2039, oseq = 1398790087.9918},{name = ‘c3’, state = true, seq = 1398790513.2147, oseq = 1398790088.0167},{name = ‘c4’, state = false, seq = 1398790724.833, oseq = 1398790763.7213},{name = ‘c5’, state = true, seq = 1398790763.7241, oseq = 1398790724.8395},{name = ‘c6’, state = false, seq = 1398790560.0313, oseq = 1398790604.6075},{name = ‘c7’, state = true, seq = 1398790604.6103, oseq = 1398790560.0378},{name = ‘c8’, state = false, seq = 1398737684.6307, oseq = 1398737708.9223},{name = ‘c9’, state = true, seq = 1398737708.9239, oseq = 1398737684.6358},{name = ‘c10’, state = true, seq = 1398801443.5606, oseq = 1398796542.711},{name = ‘c11’, state = false, seq = 1398796542.7136, oseq = 1398801443.5675},{name = ‘c12’, state = true, seq = 1398797277.3986, oseq = 1398797272.27},{name = ‘c13’, state = false, seq = 1398797272.2727, oseq = 1398797277.4051},{name = ‘c14’, state = false, seq = 1398744166.1704, oseq = 1398780220.0082},{name = ‘NOW’, state = true, seq = 1398801401.1004, oseq = 0},} now: {{name = ‘jHome’, state = true, seq = 1398790513.1971, oseq = 1398790087.9809},{name = ‘jBedroom’, state = false, seq = 1398790724.8018, oseq = 1398790763.6919},{name = ‘jDoorLock’, state = false, seq = 1398790560.0108, oseq = 1398790604.5543},{name = ‘jBasement’, state = false, seq = 1398735468.2069, oseq = 1398735512.1457},{name = ‘jKitchen’, state = true, seq = 1398801443.5027, oseq = 1398794452.4583},{name = ‘jLivingRoom’, state = false, seq = 1398797277.3551, oseq = 1398801474.0476},{name = ‘lHome’, state = false, seq = 1398793559.473, oseq = 1398796542.3505},{name = ‘lBedroom’, state = false, seq = 1398771552.3809, oseq = 1398773409.0034},{name = ‘lDoorLock’, state = false, seq = 0, oseq = 0},{name = ‘lBasement’, state = false, seq = 1398737684.6121, oseq = 1398737708.8928},{name = ‘lKitchen’, state = false, seq = 1398793876.8906, oseq = 1398796542.6759},{name = ‘lLivingRoom’, state = false, seq = 1398734483.906, oseq = 1398737875.3947},{name = ‘Night’, state = false, seq = 1398733548.218, oseq = 1398769618.1968},{name = ‘WeatherCondition1’, state = false, seq = 0, oseq = 1398735964.1724},{name = ‘WeatherCondition2’, state = true, seq = 1398735964.1727, oseq = 0},{name = ‘c1’, state = true, seq = 1398790513.2039, oseq = 1398790087.9918},{name = ‘c3’, state = true, seq = 1398790513.2147, oseq = 1398790088.0167},{name = ‘c4’, state = false, seq = 1398790724.833, oseq = 1398790763.7213},{name = ‘c5’, state = true, seq = 1398790763.7241, oseq = 1398790724.8395},{name = ‘c6’, state = false, seq = 1398790560.0313, oseq = 1398790604.6075},{name = ‘c7’, state = true, seq = 1398790604.6103, oseq = 1398790560.0378},{name = ‘c8’, state = false, seq = 1398737684.6307, oseq = 1398737708.9223},{name = ‘c9’, state = true, seq = 1398737708.9239, oseq = 1398737684.6358},{name = ‘c10’, state = true, seq = 1398801443.5606, oseq = 1398796542.711},{name = ‘c11’, state = false, seq = 1398796542.7136, oseq = 1398801443.5675},{name = ‘c12’, state = false, seq = 1398797277.3986, oseq = 1398801474.0889},{name = ‘c13’, state = true, seq = 1398801474.0915, oseq = 1398797277.4051},{name = ‘c14’, state = false, seq = 1398744166.1704, oseq = 1398780220.0082},{name = ‘NOW’, state = true, seq = 1398801461.1004, oseq = 0},} #hooks: 0 upnp: 0 v:0x96a430/NONE duplicate:0 <0x2b271000>
04 04/29/14 14:57:54.118 <0x2b271000>
06 04/29/14 14:57:54.119 Device_Variable::m_szValue_set device: 80 service: urn:dcineco-com:serviceId:MSwitch1 variable: Status5 was: 0 now: 0 #hooks: 1 upnp: 0 v:0x98b240/NONE duplicate:1 <0x2b271000>
04 04/29/14 14:57:54.120 <0x2b271000>
06 04/29/14 14:57:54.124 Device_Variable::m_szValue_set device: 81 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ActionsMap was: {} now: {} #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2b271000>
08 04/29/14 14:57:54.125 JobHandler_LuaUPnP::HandleActionRequest device: 26 service: urn:upnp-org:serviceId:SwitchPower1 action: SetTarget <0x2b271000>
08 04/29/14 14:57:54.125 JobHandler_LuaUPnP::HandleActionRequest argument newTargetValue=0 <0x2b271000>
06 04/29/14 14:57:54.125 Device_Variable::m_szValue_set device: 26 service: urn:upnp-org:serviceId:SwitchPower1 variable: Target was: 1 now: 0 #hooks: 0 upnp: 0 v:0x6b6138/NONE duplicate:0 <0x2b271000>
02 04/29/14 14:57:54.127 ZWJob_SendData UPDATE MANUAL ROUTE 25=(nil) <0x2b271000>
02 04/29/14 14:57:54.128 UPDATE MANUAL ROUTE2 25=(nil) <0x2bda5680>
02 04/29/14 14:57:54.129 ZW_Send_Data node 25 NO ROUTE (nil) <0x2bda5680>
04 04/29/14 14:57:54.131 <0x2b271000>
02 04/29/14 14:57:54.228 UPDATE MANUAL ROUTE2 25=(nil) <0x2bda5680>
02 04/29/14 14:57:54.228 ZW_Send_Data node 25 NO ROUTE (nil) <0x2bda5680>
06 04/29/14 14:57:54.336 Device_Variable::m_szValue_set device: 26 service: urn:upnp-org:serviceId:SwitchPower1 variable: Status was: 1 now: 0 #hooks: 1 upnp: 0 v:0x6b63b8/NONE duplicate:0 <0x2b9a5680>
04 04/29/14 14:57:54.338 <0x2b9a5680>
The only thing I can see if that my app is sending the request to disable the multi-switch 2 times. So it is sending a status update twice to set the status to 0, which I could change but not sure I want to as I want to get whichever request across the fastest and with the iOS API there are 2 functions that get call backs when I come in contact with an iBeacon. Does PLEG not handle the second one appropriately? It should ignore it knowing that the condition is already false.
From the logs … it looks like your PLEG is set to ByPass
Its not set to Bypass.
Also I am running 6.7 for both Core Logic and PLEG.
My fault … I was looking at the wrong thing.
Is the device ID for your Light #26 ?
From the Vera Log you sent the Z-Wave command was sent:
06 04/29/14 14:57:54.124 Device_Variable::m_szValue_set device: 81 service: urn:rts-services-com:serviceId:ProgramLogicC variable: ActionsMap was: {} now: {} #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2b271000>
08 04/29/14 14:57:54.125 JobHandler_LuaUPnP::HandleActionRequest device: 26 service: urn:upnp-org:serviceId:SwitchPower1 action: SetTarget <0x2b271000>
08 04/29/14 14:57:54.125 JobHandler_LuaUPnP::HandleActionRequest argument newTargetValue=0 <0x2b271000>
06 04/29/14 14:57:54.125 Device_Variable::m_szValue_set device: 26 service: urn:upnp-org:serviceId:SwitchPower1 variable: Target was: 1 now: 0 #hooks: 0 upnp: 0 v:0x6b6138/NONE duplicate:0 <0x2b271000>
02 04/29/14 14:57:54.127 ZWJob_SendData UPDATE MANUAL ROUTE 25=(nil) <0x2b271000>
02 04/29/14 14:57:54.128 UPDATE MANUAL ROUTE2 25=(nil) <0x2bda5680>
02 04/29/14 14:57:54.129 ZW_Send_Data node 25 NO ROUTE (nil) <0x2bda5680>
04 04/29/14 14:57:54.131 <Job ID="31" Name="" Device="81" Created="2014-04-29 14:57:54" Started="2014-04-29 14:57:54" Completed="2014-04-29 14:57:54" Duration="0.35931000" Runtime="0.9781000" Status="Successful" LastNote=""/> <0x2b271000>
02 04/29/14 14:57:54.228 UPDATE MANUAL ROUTE2 25=(nil) <0x2bda5680>
02 04/29/14 14:57:54.228 ZW_Send_Data node 25 NO ROUTE (nil) <0x2bda5680>
06 04/29/14 14:57:54.336 Device_Variable::m_szValue_set device: 26 service: urn:upnp-org:serviceId:SwitchPower1 variable: Status was: 1 now: 0 #hooks: 1 upnp: 0 v:0x6b63b8/NONE duplicate:0 <0x2b9a5680>
04 04/29/14 14:57:54.338 <Job ID="32" Name="OFF node 25" Device="26" Created="2014-04-29 14:57:54" Started="2014-04-29 14:57:54" Completed="2014-04-29 14:57:54" Duration="0.210988000" Runtime="0.209622000" Status="Successful" LastNote="Transmit was ok" Node="25" NodeType="ZWaveNonDimmableLight" NodeDescription="Main Living Room Lights"/> <0x2b9a5680>Yes that is the correct device ID and just checked that. It just happened now where the Lights turned off but the conditions are still set to keep the light on. See attachments for the logs and status report.
Attached is a screenshot of that device. This is just weird, the Vera is reporting the device on but I am sitting here and the lights are clearly off.
So the lights just turning off, is it possible that the Vera sent out the previous command to turn it off when that condition was satisfied and ~30+ minutes later the command actually got to the device? (Could this be why the UI didn’t update this last time when it went off?) Either way the condition is still met to keep the light on and the light isn’t on. Do I need to have it resolve the network or something to resolve this, my wife is about to kill me or at least put an end to my home automation dreams.
At this point int time … this does not look like a PLEG problem.
Actually it looks like you Switch is doing exactly the opposite of what it is told.
Not sure if it’s Vera or the switch, the Odds are a problem with the switch.
This is happening with multiple devices in the home that I haven’t had a problem with in the past.
What type of switch is device 26? Does is work quickly, correctly and reliably from the UI?
This can be caused by one bad Z-Wave node. Cycling the main house power or each separate circuit can sometimes shake it loose.
It is a GE/Jasco 3-way switch. I can turn it on from the UI and it turns right on and the same with turning it off it reacts right away. I have 5 rooms setup to use these iBeacons and send a command to the multi-switch and then have this one PLEG designated to handling what happens when the switch is on and when it is off. This works 98.9% of the time, there is a very small number where the lights will stay on when the switch is no longer on and vice versa. At this point I am not blaming PLEG because it seems to be right, maybe something wrong with the Vera’s neighbors setup and the request gets lost but eventually makes it to the node?
Did your problems with Z-Wave start when you fired-up your new beacons? What frequency do they run at?