Z-Wave Devices stopped communicating

petewill · March 28, 2014, 4:00am

Hello,

I have been researching this problem but I haven’t been able to get it fixed. I have also logged a call with support almost a week ago but haven’t heard anything. Would really love to get everything working again. A less than two weeks ago my z-wave devices started to respond much slower and sometimes not at all. I didn’t change much except I added one device (an Arduino sensor from the MySensors plugin). I had previously had other arduino sensors connected for a long time (you’ll see one in the logs) with no issues. I have since tried removing the added device and restoring from two different backups but those things did not help. Would someone mind taking a look at the snippet of text from my log file to see if anything can be figured out from it? I am at a loss as to what to try next.

02 03/27/14 19:27:53.568 ZWaveSerial::GetFrame 0x2c18af58 timed out now 0 m_listGetFramePending 0 <0x2c18b680> 02 03/27/14 19:27:53.568 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x2c18b680> 01 03/27/14 19:27:53.569 ZWJob_PollNode::Run job job#25 :pollnode #6 dev:7 (0x12ae800) N:6 P:100 S:1 ZW_Send_Data to node 6 failed 1 req 0xdbd1b0/1 abort m_iFrameID 0 <0x2c18b680> 02 03/27/14 19:27:53.569 ZWJob_PollNode::PollFailed job job#25 :pollnode #6 dev:7 (0x12ae800) N:6 P:100 S:1 node 6 battery 0 <0x2c18b680> 06 03/27/14 19:27:53.570 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:0x1005600/NONE duplicate:1 <0x2c18b680> 04 03/27/14 19:27:53.571 <0x2c18b680> 02 03/27/14 19:27:53.571 JobHandler::PurgeCompletedJobs purge job#25 :pollnode #6 dev:7 (0x12ae800) N:6 P:100 S:2 pollnode #6 1 cmds status 2 <0x2c18b680> 02 03/27/14 19:28:17.103 ZW_Send_Data node 24 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:28:23.157 ZWaveSerial::GetFrame 0x2c18af58 timed out now 0 m_listGetFramePending 0 <0x2c18b680> 02 03/27/14 19:28:23.157 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x2c18b680> 01 03/27/14 19:28:23.158 ZWJob_PollNode::Run job job#26 :pollnode #24 dev:50 (0x10ad348) N:24 P:100 S:1 ZW_Send_Data to node 24 failed 1 req 0x10b7b60/1 abort m_iFrameID 0 <0x2c18b680> 02 03/27/14 19:28:23.159 ZWJob_PollNode::PollFailed job job#26 :pollnode #24 dev:50 (0x10ad348) N:24 P:100 S:1 node 24 battery 0 <0x2c18b680> 06 03/27/14 19:28:23.159 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:0x1005600/NONE duplicate:1 <0x2c18b680> 04 03/27/14 19:28:23.160 <0x2c18b680> 02 03/27/14 19:28:23.161 JobHandler::PurgeCompletedJobs purge job#26 :pollnode #24 dev:50 (0x10ad348) N:24 P:100 S:2 pollnode #24 1 cmds status 2 <0x2c18b680> 50 03/27/14 19:28:25.798 luup_log:27: Arduino: Set Var: 4;3;1;0;45.9 <0x2edcd680> 50 03/27/14 19:28:25.798 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '45.9' <0x2edcd680> 50 03/27/14 19:28:25.798 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 45.9, 56 <0x2edcd680> 06 03/27/14 19:28:25.799 Device_Variable::m_szValue_set device: 56 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 46.0 now: 45.9 #hooks: 0 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.800 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 56 <0x2edcd680> 06 03/27/14 19:28:25.800 Device_Variable::m_szValue_set device: 56 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970006 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.828 luup_log:27: Arduino: Set Var: 4;2;1;1;35.0 <0x2edcd680> 50 03/27/14 19:28:25.829 luup_log:27: Arduino: Setting variable 'CurrentLevel' to value '35.0' <0x2edcd680> 50 03/27/14 19:28:25.829 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HumiditySensor1,CurrentLevel, 35.0, 57 <0x2edcd680> 06 03/27/14 19:28:25.829 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HumiditySensor1 variable: CurrentLevel was: 35.2 now: 35.0 #hooks: 1 upnp: 0 v:0xfa7328/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.831 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 57 <0x2edcd680> 06 03/27/14 19:28:25.831 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970072 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.833 luup_log:27: Arduino: Set Var: 4;1;1;0;47.0 <0x2edcd680> 50 03/27/14 19:28:25.833 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '47.0' <0x2edcd680> 50 03/27/14 19:28:25.833 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 47.0, 58 <0x2edcd680> 06 03/27/14 19:28:25.834 Device_Variable::m_szValue_set device: 58 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 47.2 now: 47.0 #hooks: 1 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.835 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 58 <0x2edcd680> 06 03/27/14 19:28:25.836 Device_Variable::m_szValue_set device: 58 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970072 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.837 luup_log:27: Arduino: Set Var: 4;0;1;4;81653 <0x2edcd680> 50 03/27/14 19:28:25.837 luup_log:27: Arduino: Setting variable 'CurrentPressure' to value '81653' <0x2edcd680> 50 03/27/14 19:28:25.838 luup_log:27: Arduino: urn:upnp-org:serviceId:BarometerSensor1,CurrentPressure, 81653, 59 <0x2edcd680> 06 03/27/14 19:28:25.838 Device_Variable::m_szValue_set device: 59 service: urn:upnp-org:serviceId:BarometerSensor1 variable: CurrentPressure was: 81663 now: 81653 #hooks: 1 upnp: 0 v:0xf7b720/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.840 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 59 <0x2edcd680> 06 03/27/14 19:28:25.840 Device_Variable::m_szValue_set device: 59 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970072 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 02 03/27/14 19:28:47.103 ZW_Send_Data node 25 NO ROUTE (nil) <0x2c18b680> 04 03/27/14 19:28:47.238 <0x2bd8b680> 50 03/27/14 19:28:58.838 luup_log:27: Arduino: Set Var: 4;3;1;0;45.7 <0x2edcd680> 50 03/27/14 19:28:58.838 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '45.7' <0x2edcd680> 50 03/27/14 19:28:58.838 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 45.7, 56 <0x2edcd680> 06 03/27/14 19:28:58.839 Device_Variable::m_szValue_set device: 56 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 45.9 now: 45.7 #hooks: 0 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.839 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 56 <0x2edcd680> 06 03/27/14 19:28:58.840 Device_Variable::m_szValue_set device: 56 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.868 luup_log:27: Arduino: Set Var: 4;2;1;1;34.9 <0x2edcd680> 50 03/27/14 19:28:58.868 luup_log:27: Arduino: Setting variable 'CurrentLevel' to value '34.9' <0x2edcd680> 50 03/27/14 19:28:58.869 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HumiditySensor1,CurrentLevel, 34.9, 57 <0x2edcd680> 06 03/27/14 19:28:58.869 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HumiditySensor1 variable: CurrentLevel was: 35.0 now: 34.9 #hooks: 1 upnp: 0 v:0xfa7328/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.871 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 57 <0x2edcd680> 06 03/27/14 19:28:58.871 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.872 luup_log:27: Arduino: Set Var: 4;1;1;0;46.7 <0x2edcd680> 50 03/27/14 19:28:58.873 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '46.7' <0x2edcd680> 50 03/27/14 19:28:58.873 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 46.7, 58 <0x2edcd680> 06 03/27/14 19:28:58.873 Device_Variable::m_szValue_set device: 58 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 47.0 now: 46.7 #hooks: 1 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.875 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 58 <0x2edcd680> 06 03/27/14 19:28:58.875 Device_Variable::m_szValue_set device: 58 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.876 luup_log:27: Arduino: Set Var: 4;0;1;4;81644 <0x2edcd680> 50 03/27/14 19:28:58.877 luup_log:27: Arduino: Setting variable 'CurrentPressure' to value '81644' <0x2edcd680> 50 03/27/14 19:28:58.877 luup_log:27: Arduino: urn:upnp-org:serviceId:BarometerSensor1,CurrentPressure, 81644, 59 <0x2edcd680> 06 03/27/14 19:28:58.878 Device_Variable::m_szValue_set device: 59 service: urn:upnp-org:serviceId:BarometerSensor1 variable: CurrentPressure was: 81653 now: 81644 #hooks: 1 upnp: 0 v:0xf7b720/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.879 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 59 <0x2edcd680> 06 03/27/14 19:28:58.880 Device_Variable::m_szValue_set device: 59 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 02 03/27/14 19:29:17.103 ZZZ-POLLING H1,C1,H2,C2,/1/1 <0x2c18b680> 02 03/27/14 19:29:17.104 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:19.398 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 06 03/27/14 19:29:19.727 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 67 now: 67 #hooks: 1 upnp: 0 v:0xeec408/NONE duplicate:1 <0x2bd8b680> 02 03/27/14 19:29:21.729 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:24.049 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:26.358 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 06 03/27/14 19:29:26.657 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:HVAC_FanOperatingMode1 variable: Mode was: Auto now: Auto #hooks: 1 upnp: 0 v:0xec58b8/NONE duplicate:1 <0x2bd8b680> 02 03/27/14 19:29:28.659 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:30.958 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680>

Thanks,

Pete

hek · March 28, 2014, 6:27am

[quote=“petewill, post:1, topic:180416”]Hello,

I have been researching this problem but I haven’t been able to get it fixed. I have also logged a call with support almost a week ago but haven’t heard anything. Would really love to get everything working again. A less than two weeks ago my z-wave devices started to respond much slower and sometimes not at all. I didn’t change much except I added one device (an Arduino sensor from the MySensors plugin). I had previously had other arduino sensors connected for a long time (you’ll see one in the logs) with no issues. I have since tried removing the added device and restoring from two different backups but those things did not help. Would someone mind taking a look at the snippet of text from my log file to see if anything can be figured out from it? I am at a loss as to what to try next.

02 03/27/14 19:27:53.568 ZWaveSerial::GetFrame 0x2c18af58 timed out now 0 m_listGetFramePending 0 <0x2c18b680> 02 03/27/14 19:27:53.568 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x2c18b680> 01 03/27/14 19:27:53.569 ZWJob_PollNode::Run job job#25 :pollnode #6 dev:7 (0x12ae800) N:6 P:100 S:1 ZW_Send_Data to node 6 failed 1 req 0xdbd1b0/1 abort m_iFrameID 0 <0x2c18b680> 02 03/27/14 19:27:53.569 ZWJob_PollNode::PollFailed job job#25 :pollnode #6 dev:7 (0x12ae800) N:6 P:100 S:1 node 6 battery 0 <0x2c18b680> 06 03/27/14 19:27:53.570 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:0x1005600/NONE duplicate:1 <0x2c18b680> 04 03/27/14 19:27:53.571 <0x2c18b680> 02 03/27/14 19:27:53.571 JobHandler::PurgeCompletedJobs purge job#25 :pollnode #6 dev:7 (0x12ae800) N:6 P:100 S:2 pollnode #6 1 cmds status 2 <0x2c18b680> 02 03/27/14 19:28:17.103 ZW_Send_Data node 24 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:28:23.157 ZWaveSerial::GetFrame 0x2c18af58 timed out now 0 m_listGetFramePending 0 <0x2c18b680> 02 03/27/14 19:28:23.157 ZWaveJobHandler::SendDataAbort got m_iFrameID 0 <0x2c18b680> 01 03/27/14 19:28:23.158 ZWJob_PollNode::Run job job#26 :pollnode #24 dev:50 (0x10ad348) N:24 P:100 S:1 ZW_Send_Data to node 24 failed 1 req 0x10b7b60/1 abort m_iFrameID 0 <0x2c18b680> 02 03/27/14 19:28:23.159 ZWJob_PollNode::PollFailed job job#26 :pollnode #24 dev:50 (0x10ad348) N:24 P:100 S:1 node 24 battery 0 <0x2c18b680> 06 03/27/14 19:28:23.159 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:0x1005600/NONE duplicate:1 <0x2c18b680> 04 03/27/14 19:28:23.160 <0x2c18b680> 02 03/27/14 19:28:23.161 JobHandler::PurgeCompletedJobs purge job#26 :pollnode #24 dev:50 (0x10ad348) N:24 P:100 S:2 pollnode #24 1 cmds status 2 <0x2c18b680> 50 03/27/14 19:28:25.798 luup_log:27: Arduino: Set Var: 4;3;1;0;45.9 <0x2edcd680> 50 03/27/14 19:28:25.798 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '45.9' <0x2edcd680> 50 03/27/14 19:28:25.798 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 45.9, 56 <0x2edcd680> 06 03/27/14 19:28:25.799 Device_Variable::m_szValue_set device: 56 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 46.0 now: 45.9 #hooks: 0 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.800 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 56 <0x2edcd680> 06 03/27/14 19:28:25.800 Device_Variable::m_szValue_set device: 56 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970006 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.828 luup_log:27: Arduino: Set Var: 4;2;1;1;35.0 <0x2edcd680> 50 03/27/14 19:28:25.829 luup_log:27: Arduino: Setting variable 'CurrentLevel' to value '35.0' <0x2edcd680> 50 03/27/14 19:28:25.829 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HumiditySensor1,CurrentLevel, 35.0, 57 <0x2edcd680> 06 03/27/14 19:28:25.829 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HumiditySensor1 variable: CurrentLevel was: 35.2 now: 35.0 #hooks: 1 upnp: 0 v:0xfa7328/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.831 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 57 <0x2edcd680> 06 03/27/14 19:28:25.831 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970072 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.833 luup_log:27: Arduino: Set Var: 4;1;1;0;47.0 <0x2edcd680> 50 03/27/14 19:28:25.833 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '47.0' <0x2edcd680> 50 03/27/14 19:28:25.833 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 47.0, 58 <0x2edcd680> 06 03/27/14 19:28:25.834 Device_Variable::m_szValue_set device: 58 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 47.2 now: 47.0 #hooks: 1 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.835 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 58 <0x2edcd680> 06 03/27/14 19:28:25.836 Device_Variable::m_szValue_set device: 58 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970072 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.837 luup_log:27: Arduino: Set Var: 4;0;1;4;81653 <0x2edcd680> 50 03/27/14 19:28:25.837 luup_log:27: Arduino: Setting variable 'CurrentPressure' to value '81653' <0x2edcd680> 50 03/27/14 19:28:25.838 luup_log:27: Arduino: urn:upnp-org:serviceId:BarometerSensor1,CurrentPressure, 81653, 59 <0x2edcd680> 06 03/27/14 19:28:25.838 Device_Variable::m_szValue_set device: 59 service: urn:upnp-org:serviceId:BarometerSensor1 variable: CurrentPressure was: 81663 now: 81653 #hooks: 1 upnp: 0 v:0xf7b720/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:25.840 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970105, 59 <0x2edcd680> 06 03/27/14 19:28:25.840 Device_Variable::m_szValue_set device: 59 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970072 now: 1395970105 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 02 03/27/14 19:28:47.103 ZW_Send_Data node 25 NO ROUTE (nil) <0x2c18b680> 04 03/27/14 19:28:47.238 <0x2bd8b680> 50 03/27/14 19:28:58.838 luup_log:27: Arduino: Set Var: 4;3;1;0;45.7 <0x2edcd680> 50 03/27/14 19:28:58.838 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '45.7' <0x2edcd680> 50 03/27/14 19:28:58.838 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 45.7, 56 <0x2edcd680> 06 03/27/14 19:28:58.839 Device_Variable::m_szValue_set device: 56 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 45.9 now: 45.7 #hooks: 0 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.839 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 56 <0x2edcd680> 06 03/27/14 19:28:58.840 Device_Variable::m_szValue_set device: 56 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.868 luup_log:27: Arduino: Set Var: 4;2;1;1;34.9 <0x2edcd680> 50 03/27/14 19:28:58.868 luup_log:27: Arduino: Setting variable 'CurrentLevel' to value '34.9' <0x2edcd680> 50 03/27/14 19:28:58.869 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HumiditySensor1,CurrentLevel, 34.9, 57 <0x2edcd680> 06 03/27/14 19:28:58.869 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HumiditySensor1 variable: CurrentLevel was: 35.0 now: 34.9 #hooks: 1 upnp: 0 v:0xfa7328/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.871 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 57 <0x2edcd680> 06 03/27/14 19:28:58.871 Device_Variable::m_szValue_set device: 57 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.872 luup_log:27: Arduino: Set Var: 4;1;1;0;46.7 <0x2edcd680> 50 03/27/14 19:28:58.873 luup_log:27: Arduino: Setting variable 'CurrentTemperature' to value '46.7' <0x2edcd680> 50 03/27/14 19:28:58.873 luup_log:27: Arduino: urn:upnp-org:serviceId:TemperatureSensor1,CurrentTemperature, 46.7, 58 <0x2edcd680> 06 03/27/14 19:28:58.873 Device_Variable::m_szValue_set device: 58 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 47.0 now: 46.7 #hooks: 1 upnp: 0 v:0xeec408/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.875 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 58 <0x2edcd680> 06 03/27/14 19:28:58.875 Device_Variable::m_szValue_set device: 58 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.876 luup_log:27: Arduino: Set Var: 4;0;1;4;81644 <0x2edcd680> 50 03/27/14 19:28:58.877 luup_log:27: Arduino: Setting variable 'CurrentPressure' to value '81644' <0x2edcd680> 50 03/27/14 19:28:58.877 luup_log:27: Arduino: urn:upnp-org:serviceId:BarometerSensor1,CurrentPressure, 81644, 59 <0x2edcd680> 06 03/27/14 19:28:58.878 Device_Variable::m_szValue_set device: 59 service: urn:upnp-org:serviceId:BarometerSensor1 variable: CurrentPressure was: 81653 now: 81644 #hooks: 1 upnp: 0 v:0xf7b720/NONE duplicate:0 <0x2edcd680> 50 03/27/14 19:28:58.879 luup_log:27: Arduino: urn:micasaverde-com:serviceId:HaDevice1,LastUpdate, 1395970138, 59 <0x2edcd680> 06 03/27/14 19:28:58.880 Device_Variable::m_szValue_set device: 59 service: urn:micasaverde-com:serviceId:HaDevice1 variable: LastUpdate was: 1395970105 now: 1395970138 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2edcd680> 02 03/27/14 19:29:17.103 ZZZ-POLLING H1,C1,H2,C2,/1/1 <0x2c18b680> 02 03/27/14 19:29:17.104 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:19.398 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 06 03/27/14 19:29:19.727 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 67 now: 67 #hooks: 1 upnp: 0 v:0xeec408/NONE duplicate:1 <0x2bd8b680> 02 03/27/14 19:29:21.729 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:24.049 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:26.358 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 06 03/27/14 19:29:26.657 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:HVAC_FanOperatingMode1 variable: Mode was: Auto now: Auto #hooks: 1 upnp: 0 v:0xec58b8/NONE duplicate:1 <0x2bd8b680> 02 03/27/14 19:29:28.659 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680> 02 03/27/14 19:29:30.958 ZW_Send_Data node 2 NO ROUTE (nil) <0x2c18b680>

Thanks,

Pete[/quote]

Try placing the Arduino gateway radio as far away as the USB connector permits (to rule out radio interference).

petewill · March 28, 2014, 1:09pm

@HEK, thanks for the reply. I really doubt it has anything to do with your sensors. I have had the configuration I’m running now (without the additional sensor that was most likely a coincidence) for over a month and everything was working flawlessly. I am going to upgrade the gateway to the most recent build soon, so I will test then.

I’m wondering if it has something to do with my Yale door lock getting low on batteries. I read on the forum that someone else had a Yale lock like I do and when the batteries got low they had similar problems. My batteries in the lock say they are at 90% but it has been installed for 4 months so I’m not sure how accurate that is. I’m really hoping that is the solution because I don’t know what else to do…

hek · March 28, 2014, 1:27pm

Ok, just make sure the last sensor isn’t flooding the radio network (e.g. no delays or sleep in loop section).

petewill · March 28, 2014, 7:40pm

Ok, I will take another look at the code but the sensor isn’t even plugged in right now. So I would think if it was a radio thing it would start working as soon as it was unplugged? Maybe the Z-Wave network somehow tried to adapt to account for any flooding? Is that even a possibility? I don’t really understand how the communication works with the mesh network.

hek · March 28, 2014, 7:49pm

Yes, agree that it is far fetched. Probably just the normal low memory problems people are experiencing from time to time.

petewill · March 29, 2014, 6:54pm

Hi All,

I tried to find out how much memory my vera is using but I’m not sure I did it correctly. Here’s what I did:

Logged into Vera with WinSCP
Navigated to the /usr/bin folder
Opened the Terminal with ctrl + t
Typed “top” in the command field and pressed Execute

I got some results but the console stopped responding and I had to force close it. If that’s not the way to check memory can someone please let me know how to do it? Also, I have attached my results as a picture since it was frozen and I couldn’t copy the text. It looks like I have only used 53% of my memory?

I ran a Z-Wave heal last night and quite a few devices are listed as not configured. One of them (Living Room Outl) is no more than 10 feet away and only passing through a plywood subfloor. I made sure as many devices were not running as I could. I disabled PLEG and unplugged my Arduino gateway.

Does anyone have any idea what I could try next? Is there any way to analyze how the Z-Wave devices are communicating beyond doing a heal? It really seems like something is wrong in the area. What is so strange to me is everything was working beautifully a couple of weeks ago. Also, for the future, should I be backing up my Z-Wave network regularly? I always thought just having the standard backup going to MCVs servers was enough but I’m starting to think I was very wrong.

Thanks in advance,

Pete

capjay · March 30, 2014, 6:47am

doing a full heal is a good idea. you might have found the culprit (Living Room Outl) why isn’t it configuring? Try to “configure now” a few times; if it fails, try to cut the AC to it, reconnect, then “configure now”; if this fails, then it is probably faulty and is causing slow connections (far away nodes routing thru it, etc.), as for the battery-powered devices, it is normal that they do not configure till they wake up, so wait for them.

I suggest keeping the Arduino gateway off (to make sure it’s not causing the problem in the first place)… also did you recently add new wireless devices? (new cordless phone, baby camera, cheapo wireless mouse, etc. they may interfere with 900Mhz)

petewill · March 31, 2014, 1:55pm

@capjay,

Thank you very much for the reply. I spent most of the weekend trying different things to figure this out and nothing seemed to fix the communication issues. The night before you replied I did another z-wave repair and then ALL of my devices were listed as not configured. I tried to do the configure now on my living room outlet and that didn’t help so I removed the power from it to see if it might be the problem. That didn’t help either. I even tried moving my Vera to a different location in the house to see if that would help but unfortunately it didn’t.

I had my Arduino gateway powered off the entire weekend and I didn’t see any difference. I am fairly confident that the Arduino plugins aren’t causing the problems. Many other people are using them and no one has reported problems. I’m wondering if it’s one of my Z-Wave devices. Has anyone played with the manual routing? I’m wondering if I should start playing with the routing to see if I can isolate the devices and maybe find the one that is causing the problems?

I have been racking my brain to think of anything that could be interfering with the z-wave network. I can’t think of anything that we have added in the past two months that is wireless.

As of right now I have restored my Vera to how it was before I started playing with the settings. Things are working better than they were this weekend but still not very good.

I guess the next step is to read up on z-wave and how it communicates to see if any sort of routing changes will help. Anyone have any advice on what to do and what not to do?

Thanks,

Pete

capjay · March 31, 2014, 2:08pm

more info on manual routing: http://forum.micasaverde.com/index.php/topic,6898.msg44456.html#msg44456

you can also try to “stress test” outlet-powered devices to see which ones are not pinging or respond too slowly to pings.

petewill · March 31, 2014, 8:31pm

@capjay, thank you again. I tried to stress test a few devices around the house and here are my results.

Living room outlet (1 floor up, 10 -15 ft from the Vera)
Download : Starting stress test of node 6
Sent 10 direct pings, 0 were ok, average 200ms
Sent 10 pings, 8 were ok, average 1400ms
Sent 10 polls, 10 were ok, average 1900ms

Bathroom fan (2 floors up and maybe 25ft away - thermostat is in between Vera and fan)
Download : Starting stress test of node 24
Sent 10 direct pings, 0 were ok, average 300ms
Sent 10 pings, 1 were ok, average 4800ms
Sent 10 polls, 0 were ok, average 9300ms

Thermostat (1 floor up, 10-15 ft away - next to living room outlet)
Download : Starting stress test of node 2
Sent 10 direct pings, 8 were ok, average 400ms
Sent 10 pings, 10 were ok, average 400ms
Sent 10 polls, 7 were ok, average 1800ms

Front Outside Light (1 floor up, 15-20 ft away - 6 ft from thermostat)
Download : Starting stress test of node 25
Sent 10 direct pings, 0 were ok, average 200ms
Sent 10 pings, 0 were ok, average 300ms
Sent 10 polls, 0 were ok, average 5200ms

Front Door Lock (1 floor up, 15-20 ft away - right next to Front Outside Light)
Download : Starting stress test of node 10
Sent 10 direct pings, 10 were ok, average 1300ms
Sent 10 pings, 10 were ok, average 1400ms
Sent 10 polls, 0 were ok, average 6400ms

Front Outside Light - I did the test again about 15 minutes later and got great results…
Download : Starting stress test of node 15
Sent 10 direct pings, 0 were ok, average 200ms
Sent 10 pings, 0 were ok, average 5700ms
Sent 10 polls, 0 were ok, average 11100ms

This stress test seems to be just what I was looking for. Especially the direct pings. Is there any way I can tell which path was taken when it’s not communicating through direct pings? I’d like to know which devices are working correctly.

Also, it is very odd to me is that the Front Door Lock gets 10 of 10 direct pings and the Front Outside Light gets none even though they are right next to each other. Does anyone have any idea why? I still need to do some reading on the manual routing but if the devices aren’t communicating via direct ping that may not be a good option.

Thank you again for all the help. This forum is awesome!

Pete

spartan466 · September 30, 2014, 7:04pm

Any update on this? I am having a similar problem where everything was working flawlessly and then devices stopped responding.

petewill · September 30, 2014, 11:37pm

I guess I never updated because I don’t know exactly what caused it to start working. Here is what I have in my notes (everything has been working great since I made this change but maybe it was a coincidence):
I read on the forums (http://forum.micasaverde.com/index.php/topic,10534.30.html) to check the setting to “Limit neighbors to Z-Wave discovery (requires Vera routing)” under Setup | Z-Wave Settings. After I did that everything started working.

Spartan, you may want to install the EventWatch plugin to see how much memory your have. Hope that helps.