I have been noticing some odd behavior from my Vera2. When I get up in the morning all of my devices will will function properly. They will respond successfully to a manual poll and any on/off or lock/unlock functions work just fine.
At some point during the day, things start to break down and most devices do not function reliably.
I thought that maybe it was a memory issue so I incorporated a nightly restart scene, but the restart does not seem to have any benefit (manual or via the scene).
I am on UI5 (1.5.408) and have unchecked the ‘Use Vera routing instead of Z-Wave’ option. I did this because the nightly heal seemed to be breaking communications (worse then they are now).
I have 12 Zwave devices. When I issue the ‘free’ command I get the following:
total used free shared buffers
Mem: 30540 29616 924 0 3092
Swap: 0 0 0
Total: 30540 29616 924During the unstable times I see the following in my logs:
02 09/16/12 18:43:09.187 JobHandler::PurgeCompletedJobs purge job#6 :pollnode #10 (0x857b38) N:10 P:100 S:2 pollnode #10 1 cmds status 2 <0xc04>
02 09/16/12 18:43:37.014 ZW_Send_Data node 11 USING ROUTE 255.101.100.27 <0xc04>
02 09/16/12 18:43:39.220 ZWaveSerial::GetFrame 0x7f3ff8f0 timed out now 0 m_listGetFramePending 0 <0xc04>
02 09/16/12 18:43:39.221 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0xc04>
01 09/16/12 18:43:39.222 ZWJob_PollNode::Run job job#7 :pollnode #11 (0x83a288) N:11 P:100 S:1 ZW_Send_Data to node 11 failed 1 req 0x866cd0/1 abort m_iFrameID 0 <0xc04>
02 09/16/12 18:43:39.223 ZWJob_PollNode::PollFailed job job#7 :pollnode #11 (0x83a288) N:11 P:100 S:1 node 11 battery 0 <0xc04>
06 09/16/12 18:43:39.223 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: LastError was: Poll failed now: Poll failed #hooks: 0 upnp: 0 v:0x8f3e50/NONE duplicate:1 <0xc04>
04 09/16/12 18:43:39.226 <0xc04>
02 09/16/12 18:43:39.227 JobHandler::PurgeCompletedJobs purge job#7 :pollnode #11 (0x83a288) N:11 P:100 S:2 pollnode #11 1 cmds status 2 <0xc04>
02 09/16/12 18:44:07.017 ZW_Send_Data node 12 NO ROUTE (nil) <0xc04>
02 09/16/12 18:44:09.640 ZWaveSerial::GetFrame 0x7f3ff8f0 timed out now 0 m_listGetFramePending 0 <0xc04>
02 09/16/12 18:44:09.641 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0xc04>
01 09/16/12 18:44:09.642 ZWJob_PollNode::Run job job#8 :pollnode #12 (0x849860) N:12 P:100 S:1 ZW_Send_Data to node 12 failed 1 req 0x838570/1 abort m_iFrameID 0 <0xc04>
02 09/16/12 18:44:09.643 ZWJob_PollNode::PollFailed job job#8 :pollnode #12 (0x849860) N:12 P:100 S:1 node 12 battery 0 <0xc04>
06 09/16/12 18:44:09.644 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: LastError was: Poll failed now: Poll failed #hooks: 0 upnp: 0 v:0x8f3e50/NONE duplicate:1 <0xc04>
04 09/16/12 18:44:09.646 <0xc04>
02 09/16/12 18:44:09.647 JobHandler::PurgeCompletedJobs purge job#8 :pollnode #12 (0x849860) N:12 P:100 S:2 pollnode #12 1 cmds status 2 <0xc04>
02 09/16/12 18:44:37.016 ZW_Send_Data node 13 NO ROUTE (nil) <0xc04>
02 09/16/12 18:44:42.120 ZWaveSerial::GetFrame 0x7f3ff8f0 timed out now 0 m_listGetFramePending 0 <0xc04>
02 09/16/12 18:44:42.121 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0xc04>
01 09/16/12 18:44:42.122 ZWJob_PollNode::Run job job#9 :pollnode #13 (0x796f38) N:13 P:100 S:1 ZW_Send_Data to node 13 failed 1 req 0x935528/1 abort m_iFrameID 0 <0xc04>
02 09/16/12 18:44:42.123 ZWJob_PollNode::PollFailed job job#9 :pollnode #13 (0x796f38) N:13 P:100 S:1 node 13 battery 0 <0xc04>
06 09/16/12 18:44:42.124 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: LastError was: Poll failed now: Poll failed #hooks: 0 upnp: 0 v:0x8f3e50/NONE duplicate:1 <0xc04>
04 09/16/12 18:44:42.126 <0xc04>
02 09/16/12 18:44:42.127 JobHandler::PurgeCompletedJobs purge job#9 :pollnode #13 (0x796f38) N:13 P:100 S:2 pollnode #13 1 cmds status 2 <0xc04>
02 09/16/12 18:45:07.015 ZW_Send_Data node 14 NO ROUTE (nil) <0xc04>
02 09/16/12 18:45:12.210 ZWaveSerial::GetFrame 0x7f3ff8f0 timed out now 0 m_listGetFramePending 0 <0xc04>
02 09/16/12 18:45:12.211 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0xc04>
01 09/16/12 18:45:12.212 ZWJob_PollNode::Run job job#10 :pollnode #14 (0x894f30) N:14 P:100 S:1 ZW_Send_Data to node 14 failed 1 req 0x83de40/1 abort m_iFrameID 0 <0xc04>
02 09/16/12 18:45:12.213 ZWJob_PollNode::PollFailed job job#10 :pollnode #14 (0x894f30) N:14 P:100 S:1 node 14 battery 0 <0xc04>
06 09/16/12 18:45:12.214 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: LastError was: Poll failed now: Poll failed #hooks: 0 upnp: 0 v:0x8f3e50/NONE duplicate:1 <0xc04>
04 09/16/12 18:45:12.216 <0xc04>
02 09/16/12 18:45:12.218 JobHandler::PurgeCompletedJobs purge job#10 :pollnode #14 (0x894f30) N:14 P:100 S:2 pollnode #14 1 cmds status 2 <0xc04>
02 09/16/12 18:45:37.014 ZW_Send_Data node 8 NO ROUTE (nil) <0xc04>
04 09/16/12 18:45:37.193 <0x803>
02 09/16/12 18:46:07.015 ZW_Send_Data node 10 NO ROUTE (nil) <0xc04>
02 09/16/12 18:46:09.180 ZWaveSerial::GetFrame 0x7f3ff8f0 timed out now 0 m_listGetFramePending 0 <0xc04>
02 09/16/12 18:46:09.181 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0xc04>
01 09/16/12 18:46:09.182 ZWJob_PollNode::Run job job#12 :pollnode #10 (0x79f560) N:10 P:100 S:1 ZW_Send_Data to node 10 failed 1 req 0x83f428/1 abort m_iFrameID 0 <0xc04>
02 09/16/12 18:46:09.183 ZWJob_PollNode::PollFailed job job#12 :pollnode #10 (0x79f560) N:10 P:100 S:1 node 10 battery 0 <0xc04>
06 09/16/12 18:46:09.184 Device_Variable::m_szValue_set device: 1 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: LastError was: Poll failed now: Poll failed #hooks: 0 upnp: 0 v:0x8f3e50/NONE duplicate:1 <0xc04>I am not very good at interpreting the Vera logs, but it appears that the Zwave communications are not functioning?
Any ideas on what my issue(s) could be?
Thanks for your time.