Z-Wave clock_rep / wake up jobs

ajlennon · December 12, 2011, 6:54pm

There seems to be a clock_rep job running every few minutes for each Z-Wave node on my network.

04      12/12/11 17:56:48.847   <Job ID="27" Name="clock_rep" Device="7" Created="11-12-12 17:56:48" Started="11-12-12 17:56:48" Completed="11-12-12 17:56:48" Duration="0.104481000" Runtime="0.97214000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:00:44.936   <Job ID="35" Name="clock_rep" Device="7" Created="11-12-12 18:00:44" Started="11-12-12 18:00:44" Completed="11-12-12 18:00:44" Duration="0.103050000" Runtime="0.99493000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:02:22.056   <Job ID="41" Name="clock_rep" Device="8" Created="11-12-12 18:02:21" Started="11-12-12 18:02:21" Completed="11-12-12 18:02:22" Duration="0.103361000" Runtime="0.99165000" Status="Successful" LastNote="Transmit was ok" Node="7" NodeType="ZWaveThermostat" NodeDescription="Back Radiator"/> <0x803>
04      12/12/11 18:04:41.193   <Job ID="5" Name="clock_rep" Device="7" Created="11-12-12 18:04:41" Started="11-12-12 18:04:41" Completed="11-12-12 18:04:41" Duration="0.180369000" Runtime="0.96403000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:05:35.343   <Job ID="9" Name="clock_rep" Device="10" Created="11-12-12 18:05:35" Started="11-12-12 18:05:35" Completed="11-12-12 18:05:35" Duration="0.100642000" Runtime="0.98058000" Status="Successful" LastNote="Transmit was ok" Node="9" NodeType="ZWaveThermostat" NodeDescription="Bella Radiator"/> <0x803>
04      12/12/11 18:07:02.386   <Job ID="13" Name="clock_rep" Device="9" Created="11-12-12 18:07:02" Started="11-12-12 18:07:02" Completed="11-12-12 18:07:02" Duration="0.103653000" Runtime="0.96339000" Status="Successful" LastNote="Transmit was ok" Node="8" NodeType="ZWaveThermostat" NodeDescription="Bedroom Radiator"/> <0x803>
04      12/12/11 18:08:37.253   <Job ID="17" Name="clock_rep" Device="7" Created="11-12-12 18:08:37" Started="11-12-12 18:08:37" Completed="11-12-12 18:08:37" Duration="0.100649000" Runtime="0.92598000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:12:33.403   <Job ID="26" Name="clock_rep" Device="7" Created="11-12-12 18:12:33" Started="11-12-12 18:12:33" Completed="11-12-12 18:12:33" Duration="0.100382000" Runtime="0.97896000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:16:29.603   <Job ID="32" Name="clock_rep" Device="7" Created="11-12-12 18:16:29" Started="11-12-12 18:16:29" Completed="11-12-12 18:16:29" Duration="0.100664000" Runtime="0.95404000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:17:04.393   <Job ID="35" Name="clock_rep" Device="8" Created="11-12-12 18:17:04" Started="11-12-12 18:17:04" Completed="11-12-12 18:17:04" Duration="0.100775000" Runtime="0.96061000" Status="Successful" LastNote="Transmit was ok" Node="7" NodeType="ZWaveThermostat" NodeDescription="Back Radiator"/> <0x803>
04      12/12/11 18:20:25.773   <Job ID="41" Name="clock_rep" Device="7" Created="11-12-12 18:20:25" Started="11-12-12 18:20:25" Completed="11-12-12 18:20:25" Duration="0.100682000" Runtime="0.96177000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:20:26.906   <Job ID="43" Name="clock_rep" Device="10" Created="11-12-12 18:20:26" Started="11-12-12 18:20:26" Completed="11-12-12 18:20:26" Duration="0.103498000" Runtime="0.101023000" Status="Successful" LastNote="Transmit was ok" Node="9" NodeType="ZWaveThermostat" NodeDescription="Bella Radiator"/> <0x803>
04      12/12/11 18:21:59.957   <Job ID="47" Name="clock_rep" Device="9" Created="11-12-12 18:21:59" Started="11-12-12 18:21:59" Completed="11-12-12 18:21:59" Duration="0.104272000" Runtime="0.99855000" Status="Successful" LastNote="Transmit was ok" Node="8" NodeType="ZWaveThermostat" NodeDescription="Bedroom Radiator"/> <0x803>
04      12/12/11 18:24:22.016   <Job ID="4" Name="clock_rep" Device="7" Created="11-12-12 18:24:21" Started="11-12-12 18:24:21" Completed="11-12-12 18:24:22" Duration="0.103263000" Runtime="0.100728000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:28:18.123   <Job ID="12" Name="clock_rep" Device="7" Created="11-12-12 18:28:18" Started="11-12-12 18:28:18" Completed="11-12-12 18:28:18" Duration="0.100610000" Runtime="0.95259000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:31:46.363   <Job ID="20" Name="clock_rep" Device="8" Created="11-12-12 18:31:46" Started="11-12-12 18:31:46" Completed="11-12-12 18:31:46" Duration="0.100691000" Runtime="0.97634000" Status="Successful" LastNote="Transmit was ok" Node="7" NodeType="ZWaveThermostat" NodeDescription="Back Radiator"/> <0x803>
04      12/12/11 18:32:14.333   <Job ID="23" Name="clock_rep" Device="7" Created="11-12-12 18:32:14" Started="11-12-12 18:32:14" Completed="11-12-12 18:32:14" Duration="0.100637000" Runtime="0.98090000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:35:18.247   <Job ID="29" Name="clock_rep" Device="10" Created="11-12-12 18:35:18" Started="11-12-12 18:35:18" Completed="11-12-12 18:35:18" Duration="0.103796000" Runtime="0.101359000" Status="Successful" LastNote="Transmit was ok" Node="9" NodeType="ZWaveThermostat" NodeDescription="Bella Radiator"/> <0x803>
04      12/12/11 18:36:10.543   <Job ID="33" Name="clock_rep" Device="7" Created="11-12-12 18:36:10" Started="11-12-12 18:36:10" Completed="11-12-12 18:36:10" Duration="0.100650000" Runtime="0.98140000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>
04      12/12/11 18:36:57.276   <Job ID="36" Name="clock_rep" Device="9" Created="11-12-12 18:36:57" Started="11-12-12 18:36:57" Completed="11-12-12 18:36:57" Duration="0.103373000" Runtime="0.100831000" Status="Successful" LastNote="Transmit was ok" Node="8" NodeType="ZWaveThermostat" NodeDescription="Bedroom Radiator"/> <0x803>
04      12/12/11 18:40:06.663   <Job ID="42" Name="clock_rep" Device="7" Created="11-12-12 18:40:06" Started="11-12-12 18:40:06" Completed="11-12-12 18:40:06" Duration="0.100626000" Runtime="0.97161000" Status="Successful" LastNote="Transmit was ok" Node="6" NodeType="ZWaveThermostat" NodeDescription="Kitchen Radiator"/> <0x803>

This seems to be sending a a clock report to each node.

There also seems to be a wake_up sent to each node regularly.

Can anybody explain whether this is, in fact, what is happening, and if so why there would be a need to send these commands so frequently?

Thanks, Alex

sparkejj · December 12, 2011, 7:00pm

What is the [tt]Wakeup interval[/tt] set to for these devices?

ajlennon · December 12, 2011, 8:00pm

240s

Edit: But I don’t see why the controller should be setting the clock and / or the wake up interval so frequently. Even if the clock is only as accurate as an old PC RTC you’re expecting +/- 1 min/month accuracy

sparkejj · December 12, 2011, 8:13pm

So they wake up every 4 minutes. Looks like indeed you got three wake-up events for the HRT4-ZW at 4 minute (roughly) intervals , but you’re missing a wake-up for the Kitchen Radiator?

The clock stuff is maybe what Vera does in response to a wake-up.

Ap15e · December 12, 2011, 8:26pm

From [tt]http://wiki.micasaverde.com/index.php/Release_Notes[/tt]:

3. Automatically set the clock on ZWave devices that support COMMAND_CLASS_CLOCK

IIRC, I complained about not being able to disable this kind of time hijacking a long time ago.

ajlennon · December 13, 2011, 10:18am

Oti@ No, the point I’m making is that the Vera is sending commands to set the clock and the wake up interval every few minutes which I don’t believe is correct behaviour

Ap15e - Ah you’ve been through this then. OK. Did you file a bug report?

Alex

sparkejj · December 13, 2011, 12:13pm

Point was understood. However, I think the traces you are seeing are in response to the device waking up at the configured interval of 4 minutes. It’s my impression that Vera has a pre-determined set of actions, based on the type of device / what it supports, that she executes when the device is awake. Apparently, according to your traces and @Ap15e’s comment, setting the clock is one of them. And I’ve always assumed the [tt]wakeup done[/tt] trace marks the end of the wake-up actions/interval and tells the device the controller is done communicating with it, so it can go back to sleep right that moment, rather than waiting for subsequent communication and eventually timing out.

(In other words, I think there is a [tt]wakeup[/tt] trace (the device notifies Vera it’s awake), then actions in response to that (Vera is talking to the device), then a [tt]wakeup done[/tt] (Vera is done talking to the device).)

ajlennon · December 13, 2011, 4:58pm

However, I think the traces you are seeing are in response to the device waking up at the configured interval of 4 minutes

Yes I concur, at least on the interval being set prior to “wake up done”. The clock setting seems to be occurring elsewhere

Whatever the trigger, I’m still not understanding if this is needed behaviour? i.e. set clock/interval on configuration and perhaps once a week or even once a day, but not every time the device wakes up. I can’t imagine that is good for battery life.

Ap15e · December 13, 2011, 9:42pm

Ap15e - Ah you've been through this then. OK. Did you file a bug report?

No, I didn’t.

Another interesting question is what happens when Vera V1/V2 cannot get the current time from the internet …