Hi
Like many other on this forum I have been having problem getting the ezmotion to report back it’s light and temp information, so I have been looking in the logs to see what it has been doing… Appologies for posting log extracts but the EZmotion manual suggests that device informaiton would come back during each wake up, yet each scheduled wake up does not do anything - Here are the usual updates in the logs.
06 01/04/13 14:48:29.469 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: e[35;1mLastWakeupe[0m was: 1357308884 now: 1357310909 #hooks: 1 upnp: 0 v:(nil)/NONE duplicate:0 <0x2c395680>
02 01/04/13 14:48:29.471 e[33;1mZWaveNode::Wakeup did a poll for 13 9387 seconds interval 10800 existing (nil) heal (nil)e[0m <0x2c395680>
02 01/04/13 14:48:29.472 e[33;1mZWJob_SendData UPDATE MANUAL ROUTE 13=(nil)e[0m <0x2c395680>
02 01/04/13 14:48:29.474 e[33;1mUPDATE MANUAL ROUTE2 13=(nil)e[0m <0x2c595680>
02 01/04/13 14:48:29.475 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
04 01/04/13 14:48:29.581 <Job ID="1917" Name="Wakeup done 13" Device="118" Created="2013-01-04 14:48:29" Started="2013-01-04 14:48:29" Completed="2013-01-04 14:48:29" Duration="0.107948000" Runtime="0.106318000" Status="Successful" LastNote="Transmit was ok" Node="13" NodeType="ZWaveMultiEmbedded" NodeDescription="_3-in-1 sensor"/> <0x2c395680>
However, very occasionally it does update and when it does, the logs show something very different
06 01/04/13 15:23:35.199 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: e[35;1mLastWakeupe[0m was: 1357310909 now: 1357313015 #hooks: 1 upnp: 0 v:(nil)/NONE duplicate:0 <0x2c395680>
02 01/04/13 15:23:35.202 e[33;1mZWJob_GetNodeDetails::ZWJob_GetNodeDetails skipping return_route for 13 2/1/-1 job job#1978 :getnodedetails_ri_wake 13 (0x1559608) P:50 S:0e[0m <0x2c395680>
02 01/04/13 15:23:35.203 e[33;1mZWJob_SendData UPDATE MANUAL ROUTE 13=(nil)e[0m <0x2c395680>
02 01/04/13 15:23:35.207 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
06 01/04/13 15:23:35.339 Device_Variable::m_szValue_set device: 119 service: urn:micasaverde-com:serviceId:GenericSensor1 variable: e[35;1mCurrentLevele[0m was: 0 now: 0 #hooks: 1 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c395680>
02 01/04/13 15:23:35.341 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
06 01/04/13 15:23:35.469 Device_Variable::m_szValue_set device: 120 service: urn:micasaverde-com:serviceId:LightSensor1 variable: e[35;1mCurrentLevele[0m was: 48 now: 34 #hooks: 1 upnp: 0 v:0xca9d88/NONE duplicate:0 <0x2c395680>
02 01/04/13 15:23:35.472 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
06 01/04/13 15:23:35.619 Device_Variable::m_szValue_set device: 121 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: e[35;1mCurrentTemperaturee[0m was: 20 now: 22 #hooks: 1 upnp: 0 v:0xb0d020/NONE duplicate:0 <0x2c395680>
02 01/04/13 15:23:35.689 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
06 01/04/13 15:23:35.829 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:GenericSensor1 variable: e[35;1mCurrentLevele[0m was: 0 now: 0 #hooks: 1 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c395680>
02 01/04/13 15:23:35.831 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
06 01/04/13 15:23:35.979 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:HaDevice1 variable: e[35;1mBatteryDatee[0m was: 1357291801 now: 1357313015 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2c395680>
06 01/04/13 15:23:35.980 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:HaDevice1 variable: e[35;1mBatteryLevele[0m was: 100 now: 100 #hooks: 0 upnp: 0 v:0xa63030/NONE duplicate:1 <0x2c395680>
04 01/04/13 15:23:35.981 <Job ID="1977" Name="pollnode_wake #13 5 cmds" Device="118" Created="2013-01-04 15:23:35" Started="2013-01-04 15:23:35" Completed="2013-01-04 15:23:35" Duration="0.779772000" Runtime="0.776011000" Status="Successful" LastNote="" Node="13" NodeType="ZWaveMultiEmbedded" NodeDescription="_3-in-1 sensor"/> <0x2c395680>
06 01/04/13 15:23:36.049 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: e[35;1mLastRouteUpdatee[0m was: 1357269587 now: 1357313016 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x2c595680>
06 01/04/13 15:23:36.050 Device_Variable::m_szValue_set device: 118 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: e[35;1mNeighborse[0m was: 1,6, now: 1,6, #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x2c595680>
04 01/04/13 15:23:36.051 <Job ID="1978" Name="getnodedetails_ri_wake 13" Device="118" Created="2013-01-04 15:23:35" Started="2013-01-04 15:23:35" Completed="2013-01-04 15:23:36" Duration="0.848983000" Runtime="0.67981000" Status="Successful" LastNote=""/> <0x2c595680>
02 01/04/13 15:23:36.053 e[33;1mUPDATE MANUAL ROUTE2 13=(nil)e[0m <0x2c595680>
02 01/04/13 15:23:36.053 e[33;1mZW_Send_Data node 13 NO ROUTE (nil)e[0m <0x2c595680>
04 01/04/13 15:23:36.160 <Job ID="1979" Name="Wakeup done 13" Device="118" Created="2013-01-04 15:23:35" Started="2013-01-04 15:23:36" Completed="2013-01-04 15:23:36" Duration="0.956787000" Runtime="0.107406000" Status="Successful" LastNote="Transmit was ok" Node="13" NodeType="ZWaveMultiEmbedded" NodeDescription="_3-in-1 sensor"/> <0x2c395680>
With my basic knowledge it looks like it is executing a different process/job - “ZWJob_GetNodeDetails::ZWJob_GetNodeDetails skipping return_route for 13.” which is proving successfull ?
Does anyone know if I can get this 'GetNodeDetalls" command run at every wake up time ?