zWave Messages Duplicated

Community Playground General Discussions
1

I appear to have a situation with my Vera where the same messages appear many times over in the log files within a second or so, for example:

06 01/21/12 19:52:22.913 Device_Variable::m_szValue_set device: 65 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327175250 now: 1327175542 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>
06 01/21/12 19:52:23.172 Device_Variable::m_szValue_set device: 65 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327175542 now: 1327175543 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>
06 01/21/12 19:52:23.642 Device_Variable::m_szValue_set device: 65 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327175543 now: 1327175543 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06 01/21/12 19:52:23.822 Device_Variable::m_szValue_set device: 65 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327175543 now: 1327175543 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06 01/21/12 19:52:23.882 Device_Variable::m_szValue_set device: 65 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327175543 now: 1327175543 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:1 <0x803>
06 01/21/12 19:52:24.022 Device_Variable::m_szValue_set device: 65 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327175543 now: 1327175544 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>

It looks to me like the message from this device is being reported to the Vera over and over again because this device can’t really be waking up 6 times in just over a second I am sure. My z-Wave network appears to be very unreliable as a whole. Transmitting messages to powered devices is slow and transmitting messages to battery powered events that go to sleep is a very hit and miss process. I have tried healing it but it doesn’t really help very much. So I really wonder if this is a normal thing to see in your log files?

2 
root@HomeControl:/tmp/log/cmh# tail -f LuaUPnP.log | grep LastWakeup
06      01/22/12 12:19:15.288   Device_Variable::m_szValue_set device: 16830 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327229001 now: 1327231155 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>
06      01/22/12 12:26:54.680   Device_Variable::m_szValue_set device: 16751 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327229656 now: 1327231614 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>
06      01/22/12 12:30:57.873   Device_Variable::m_szValue_set device: 16695 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327230016 now: 1327231857 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>
06      01/22/12 12:36:00.334   Device_Variable::m_szValue_set device: 16709 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327228248 now: 1327232159 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>
06      01/22/12 12:36:00.929   Device_Variable::m_szValue_set device: 16709 service: urn:micasaverde-com:serviceId:ZWaveDevice1 variable: LastWakeup was: 1327232159 now: 1327232160 #hooks: 0 upnp: 0 v:(nil)/NONE duplicate:0 <0x803>

Vera V1@1.1.1338, device #16709 is a HSM100 …

3

I see you appear to have a duplication in your logs where the same device gave a message twice, although not quite six times. I did wonder if this was a message being sent by a device that is picked up by several other devices on the network that are then routing the message back, but really I have no idea and I guess nobody else does either?