Stuck setting associations after upgrade

Vera Controllers, Services & Devices Vera Controllers & Cameras
1

Yesterday I migrated from my VeraLite running UI5 to a VeraEdge running the latest UI7. All my switches and controllers are Leviton and I use a few plugins (PLEG, Day/Night, and DSC Alarm Panel). Everything is working EXCEPT for 1 Leviton VRCZ4. I have 3 other VRCZ4 devices that are working fine, but this one gets stuck on “Setting special association”. It actually succeeds as the scene is run when I use the top button on the controller, but I can’t assign the other 4 buttons.

I’ve tried setting a manual association and that gets stuck “Setting user association”. I’ve reset the controller, re-added it and it keeps getting stuck. This particular controller is within 10 feet of my Edge; 2 of the other ones are further away. (I’ve even placed the Edge within 2 feet of the controller.)

Any suggestions? This is the last bit I need to get working to have my system worked like it did on UI5 on the VeraLite!

Thanks.

My log is below; it looks like it took 2 tries to receive something back from the controller and then the job failed.

<0x76b6c520>
06 07/12/15 7:08:59.862 Device_Variable::m_szValue_set device: 196 service: urn:micasaverde-com:serviceId:HaDevice1 variable: Configured was: EMPTY now: -2 #hooks: 0 upnp: 0 skip: 0 v:0x916db8/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:08:59.865 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: OK now: #153: Please wait! Getting the name #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.477 Device_Variable::m_szValue_set device: 196 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: NodeInfo was: EMPTY now: 2d,72,73,77,7c,82,85,86,91, #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x76b6c520>
04 07/12/15 7:09:00.478 <0x76b6c520>
06 07/12/15 7:09:00.479 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting the name now: #153: Please wait! Getting the name #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:1 <0x7676c520>
04 07/12/15 7:09:00.628 <0x76b6c520>
06 07/12/15 7:09:00.629 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting the name now: #153: Please wait! Getting the name #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:1 <0x7676c520>
06 07/12/15 7:09:00.629 Device_Variable::m_szValue_set device: 196 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: InitialName was: EMPTY now: Unnamed #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.630 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting the name now: #153: Please wait! Getting the location #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.631 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting the location now: #153: Please wait! Getting secure classes #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.632 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting secure classes now: #153: Please wait! Getting the manufacturer #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.633 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting the manufacturer now: #153: Please wait! Getting the manufacturer #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:1 <0x7676c520>
06 07/12/15 7:09:00.634 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting the manufacturer now: #153: Please wait! Getting Multi Channel info #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.635 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Please wait! Getting Multi Channel info now: #153: Setting the name #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
04 07/12/15 7:09:00.738 <0x76b6c520>
06 07/12/15 7:09:00.739 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Setting the name now: #153: Setting the name #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:1 <0x7676c520>
06 07/12/15 7:09:00.740 Device_Variable::m_szValue_set device: 196 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: ConfiguredName was: EMPTY now: 4 button zone contr #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.741 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Setting the name now: #153: Setting the location #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.742 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Setting the location now: #153: Setting the Group #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:00.742 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Setting the Group now: #153: Purging associations #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
04 07/12/15 7:09:00.887 <0x76b6c520>
06 07/12/15 7:09:00.889 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Purging associations now: #153: Purging associations #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:1 <0x7676c520>
04 07/12/15 7:09:00.997 <0x76b6c520>
04 07/12/15 7:09:01.097 <0x76b6c520>
04 07/12/15 7:09:01.207 <0x76b6c520>
04 07/12/15 7:09:01.317 <0x76b6c520>
06 07/12/15 7:09:01.319 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Purging associations now: #153: Purging associations #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:1 <0x7676c520>
06 07/12/15 7:09:01.320 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Purging associations now: #153: Setting user association #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:01.321 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Setting user association now: #153: Setting special configuration #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
06 07/12/15 7:09:01.322 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: NetStatusText was: #153: Setting special configuration now: #153: Setting special association #hooks: 0 upnp: 0 skip: 0 v:0xd50238/NONE duplicate:0 <0x7676c520>
04 07/12/15 7:09:01.447 <0x76b6c520>
02 07/12/15 7:09:04.100 UserData::AlarmCallback ALARM_RESYNC_DEVICES <0x7696c520>
02 07/12/15 7:09:04.586 RAServerSync::SyncDevice alt 0 response 200 url https://vera-us-oem-device12.mios.com/device/device/device/XXXXXX/localdevices with 39941 bytes LEAK this:114688 start:2191360 to 0x12dd000 <0x7696c520>
02 07/12/15 7:09:14.598 ZWaveSerial::GetFrame 0x7676bbe8 timed out now 0 m_listGetFramePending 0 LEAK this:-245760 start:1945600 to 0x12a1000 <0x7676c520>
02 07/12/15 7:09:14.599 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x7676c520>
02 07/12/15 7:09:14.599 ZWJob_SendData::ReturnMessageNotReceived job job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:5 to node 153 command 0x85/0x2 retries 0 of 2 quit 0 returned m_iFrameID 0 <0x7676c520>
02 07/12/15 7:09:14.697 ZWJob_SendData::ReceivedFrame ok job job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:5 to node 153 command 133/2 took 1 retries resend: 0 waiting 133/3 got 0 <0x76b6c520>
02 07/12/15 7:09:27.728 ZWaveSerial::GetFrame 0x7676bbe8 timed out now 0 m_listGetFramePending 0 <0x7676c520>
02 07/12/15 7:09:27.729 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x7676c520>
02 07/12/15 7:09:27.729 ZWJob_SendData::ReturnMessageNotReceived job job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:5 to node 153 command 0x85/0x2 retries 1 of 2 quit 0 returned m_iFrameID 0 <0x7676c520>
02 07/12/15 7:09:27.817 ZWJob_SendData::ReceivedFrame ok job job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:5 to node 153 command 133/2 took 2 retries resend: 0 waiting 133/3 got 0 <0x76b6c520>
02 07/12/15 7:09:40.858 ZWaveSerial::GetFrame 0x7676bbe8 timed out now 0 m_listGetFramePending 0 <0x7676c520>
02 07/12/15 7:09:40.859 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x7676c520>
02 07/12/15 7:09:40.859 ZWJob_SendData::ReturnMessageNotReceived job job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:5 to node 153 command 0x85/0x2 retries 2 of 2 quit 0 returned m_iFrameID 0 <0x7676c520>
02 07/12/15 7:09:40.860 ZWJob_ConfigureNode::ChildChanged aborting job#494 :add_config dev:196 (0xd496d8) P:9 S:5 for job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:5 <0x7676c520>
04 07/12/15 7:09:40.861 <0x7676c520>
04 07/12/15 7:09:40.862 <0x7676c520>
04 07/12/15 7:09:40.864 <0x7676c520>
04 07/12/15 7:09:40.865 <0x7676c520>
04 07/12/15 7:09:40.866 <0x7676c520>
04 07/12/15 7:09:40.867 <0x7676c520>
04 07/12/15 7:09:40.869 <0x7676c520>
06 07/12/15 7:09:40.869 Device_Variable::m_szValue_set device: 196 service: urn:micasaverde-com:serviceId:HaDevice1 variable: Configured was: -2 now: 0 #hooks: 0 upnp: 0 skip: 0 v:0x916db8/NONE duplicate:0 <0x7676c520>
04 07/12/15 7:09:40.871 <0x7676c520>
04 07/12/15 7:09:40.877 <0x7676c520>
01 07/12/15 7:09:40.898 ZWJob_SendData::JobFailed job#504 :assoc get 153grp 1 dev:196 (0x116e278) N:153 P:8 S:3 Priority 8 <0x7676c520>
0

2

I have an Aeotec Smart Switch Gen5 with the same issue running the latest firmware.
It says “Purging associations” all the time and every 6 hours it stops responding and then starts responding again.

I have tried healing, unpairing/pairing and resetting the smart switch. No luck.
It’s located about 1m from the Vera Edge.

3

Hi,

Can you both send us a trouble ticket through the form below ?
http://support.getvera.com/customer/portal/emails/new

Be sure to include your Vera unit serial number and the tech support access code. You can enable it by going to Settings > Tech Support and then clicking on the Enable button at the bottom of the page. We’ll look into it right away and get back to you shortly.

Thanks.

4

Email sent. Thanks.

5

I worked with support on this and was told that my controllers were talking to the Vera through a relay and that I should put the Vera right next to the controller when assigning scenes. What is odd that after a few days, the problems went away and now everything is working fine.