Manually adding Z-Wave neighbor for door sensor (ZD2102)

dinki · June 9, 2016, 1:21pm

I’ve got a Z-Wave door sensor (ZD2102) that is problematic. It works fine near the controller but is very spotty when it is at it’s intended location. I have a Z-Wave garage door tilt sensor (ZG8101) that is about one foot away from the door sensor and it works flawlessly. I looked at the neighbors in the garage door sensor and it has an additional neighbor that the door sensor does not have. That neighbor is an always on thermostat that is roughly halfway between the controller and the garage door. The door sensor does not have that neighbor. The only neighbor is an always on lamp module that is located very close to the controller.

I’ve tried manually editing that node’s xml file to include the thermostat node to see if this increases the range. Unfortunately, it doesn’t appear that the edited file is loaded or perhaps it’s rejecting the edit.

Both the garage and door sensor are battery powered. I’m running OH 1.3.8 with Z-wave binding 1.9.0.b3 on Win 7 using a Z-Stick 2.

I really don’t know what to do to try and add this neighbor. Can anyone offer any suggestions on getting this device to work as reliably as the garage door sensor next to it?

mstormi · June 9, 2016, 2:18pm

You cannot edit the zwave routing tables manually. Editing xml file doesn’t change anything on the device, btw.
Use habmin to issue a ‘heal’ on the broken node and/or the whole network.
Only mains-powered zwave nodes act as radio routers, so to use the garage door sensor for a neighbor won’t work.
But the thermostat should be available (but is it really mains powered ?)

dinki · June 9, 2016, 2:29pm

Correct. I want to use the thermostat as the neighbor, not the garage door sensor.

I understand what you’re saying about healing the node, but don’t I need to do that while it is at it’s location? This is problematic due to range problems I think. I guess I could wake it up and put it closer to the thermostat then issue the heal. Hopefully it will pick up the thermostat as a neighbor and hopefully that will increase my range, or is this not the way it works?

mstormi · June 9, 2016, 2:32pm

Right, issue heal first and try waking it up at its ‘bad’ position. Try more than once if needed. If that doesn’t work, move it closer to the controller before retrying, best would be to stay beyond the thermostat, otherwise it’ll likely not discover that one to be a useful neighbor.

rlkoshak · June 9, 2016, 4:21pm

That is not the way it works.

Zwave dynamically forms a mesh network based on signal strength, errors, etc. Neighbors are detected dynamically. So even if you move it close to the thermostat once you move it back it will no longer have the signal strength to reach it reliably and cease to be a neighbor.

The only way you can really solve this is to move/add a mains powered device closer to your garage that can act as the relay neighbor. So long as there are fewer than 4 hops between the most remote device and the controller it should be fine.

I’ve also found it works best if you don’t have a choke point. For example, in my network for awhile only one mains powered device could see my controller so all messages were routed through that one device and my network was a bit unreliable. Adding a second mains powered device in another room which could see the controller and all the other devices gave each device at least two paths to the controller and my reliability is great now.

dinki · June 9, 2016, 4:23pm

The weird thing is that I have another battery sensor of what I could only imagine is of similar type and quality that is pratically in the same spot that works just fine. Frustrating to say the least.

rlkoshak · June 9, 2016, 4:27pm

Wireless signal propagation is weird. Sometimes mere inches can make the difference between working great and no signal at all. Its one of the drawbacks of zwave I’ve found. It works great if you have good coverage of mains powered devices.

Remember, even if they are listed as neighbors, battery powered devices do not relay messages (it would use up the battery) so do not help improve your network coverage.

dinki · June 9, 2016, 4:29pm

Understood about battery powered nodes. So would something like the DSD37-ZWUS Z-wave repeater help in this situation? I’m looking for something inexpensive and that doesn’t take up too much space when plugged in.

rlkoshak · June 9, 2016, 4:36pm

That is actually exactly what I used to add a second path to my controller. However, were I to do it over again I would probably do a zwave outlet, appliance controller, or light switch instead to add something that could at least be given a function above and beyond just being a repeater, even if I don’t have a use for it just yet.

dinki · June 11, 2016, 10:20pm

I’m back at this. Tried doing a reinitialize and a heal. This is what I see in the log file:

2016-06-11 10:26:52.357 [DEBUG] [.z.i.config.ZWaveConfiguration:977 ]- NODE 6: Heal node
2016-06-11 10:26:52.358 [DEBUG] [z.internal.ZWaveNetworkMonitor:275 ]- NODE 6: Starting heal
2016-06-11 10:35:27.910 [DEBUG] [.z.i.config.ZWaveConfiguration:993 ]- NODE 6: re-initialising node
2016-06-11 10:35:27.918 [DEBUG] [b.z.i.protocol.ZWaveController:506 ]- NODE 6: Init node thread start
2016-06-11 10:35:27.923 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:138 ]- NODE 6: Serializing from file etc\zwave\node6.xml
2016-06-11 10:35:27.923 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:141 ]- NODE 6: Error serializing from file: file does not exist.
2016-06-11 10:35:27.924 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2016-06-11 10:35:27.924 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2016-06-11 10:35:27.924 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:337 ]- NODE 6: Node advancer: Initialisation starting
2016-06-11 10:35:27.924 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to PROTOINFO
2016-06-11 10:35:27.924 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2016-06-11 10:35:27.924 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:346 ]- NODE 6: Node advancer: PROTOINFO - send IdentifyNode
2016-06-11 10:35:27.925 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 6: Node advancer - queued packet. Queue length is 1
2016-06-11 10:35:27.925 [DEBUG] [b.z.i.protocol.ZWaveController:595 ]- NODE 6: Init node thread finished
2016-06-11 10:35:27.931 [DEBUG] [i.p.s.IdentifyNodeMessageClass:56  ]- NODE 6: ProtocolInfo
2016-06-11 10:35:27.931 [DEBUG] [i.p.s.IdentifyNodeMessageClass:72  ]- NODE 6: Listening = false
2016-06-11 10:35:27.931 [DEBUG] [i.p.s.IdentifyNodeMessageClass:73  ]- NODE 6: Routing = true
2016-06-11 10:35:27.931 [DEBUG] [i.p.s.IdentifyNodeMessageClass:74  ]- NODE 6: Beaming = true
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:75  ]- NODE 6: Version = 4
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:76  ]- NODE 6: FLIRS = false
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:77  ]- NODE 6: Security = false
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:78  ]- NODE 6: Max Baud = 40000
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:94  ]- NODE 6: Basic = Routing Slave
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:102 ]- NODE 6: Generic = Binary Sensor
2016-06-11 10:35:27.932 [DEBUG] [i.p.s.IdentifyNodeMessageClass:110 ]- NODE 6: Specific = Routing Binary Sensor
2016-06-11 10:35:27.932 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243 ]- NODE 6: Creating new instance of command class NO_OPERATION
2016-06-11 10:35:27.932 [DEBUG] [.z.internal.protocol.ZWaveNode:613 ]- NODE 6: Adding command class NO_OPERATION to the list of supported command classes.
2016-06-11 10:35:27.932 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243 ]- NODE 6: Creating new instance of command class BASIC
2016-06-11 10:35:27.932 [DEBUG] [.z.internal.protocol.ZWaveNode:613 ]- NODE 6: Adding command class BASIC to the list of supported command classes.
2016-06-11 10:35:27.933 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243 ]- NODE 6: Creating new instance of command class SENSOR_BINARY
2016-06-11 10:35:27.933 [DEBUG] [.z.internal.protocol.ZWaveNode:613 ]- NODE 6: Adding command class SENSOR_BINARY to the list of supported command classes.
2016-06-11 10:35:27.933 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PROTOINFO: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 10:35:27.934 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2016-06-11 10:35:27.935 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208 ]- NODE 6: Node advancer - message removed from queue. Queue size 0.
2016-06-11 10:35:27.935 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - PROTOINFO: queue length(0), free to send(true)
2016-06-11 10:35:27.935 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - PROTOINFO try 1: stageAdvanced(false)
2016-06-11 10:35:27.935 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to NEIGHBORS
2016-06-11 10:35:27.935 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - NEIGHBORS try 0: stageAdvanced(true)
2016-06-11 10:35:27.935 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:356 ]- NODE 6: Node advancer: NEIGHBORS - send RoutingInfo
2016-06-11 10:35:27.935 [DEBUG] [p.s.GetRoutingInfoMessageClass:30  ]- NODE 6: Request routing info
2016-06-11 10:35:27.936 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 6: Node advancer - queued packet. Queue length is 1
2016-06-11 10:35:27.945 [DEBUG] [p.s.GetRoutingInfoMessageClass:49  ]- NODE 6: Got NodeRoutingInfo request.
2016-06-11 10:35:27.946 [DEBUG] [p.s.GetRoutingInfoMessageClass:79  ]- NODE 6: Neighbor nodes: 5
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 10:35:27.947 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - NEIGHBORS: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208 ]- NODE 6: Node advancer - message removed from queue. Queue size 0.
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - NEIGHBORS: queue length(0), free to send(true)
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - NEIGHBORS try 1: stageAdvanced(false)
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to FAILED_CHECK
2016-06-11 10:35:27.948 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - FAILED_CHECK try 0: stageAdvanced(true)
2016-06-11 10:35:27.948 [DEBUG] [i.p.s.IsFailedNodeMessageClass:32  ]- NODE 6: Requesting IsFailedNode status from controller.
2016-06-11 10:35:27.949 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 6: Node advancer - queued packet. Queue length is 1
2016-06-11 10:35:27.953 [DEBUG] [i.p.s.IsFailedNodeMessageClass:56  ]- NODE 6: Is currently marked as healthy by the controller
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.954 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PING: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - FAILED_CHECK: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208 ]- NODE 6: Node advancer - message removed from queue. Queue size 0.
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - FAILED_CHECK: queue length(0), free to send(true)
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - FAILED_CHECK try 1: stageAdvanced(false)
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to WAIT
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - WAIT try 0: stageAdvanced(true)
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:380 ]- NODE 6: Node advancer: WAIT - Listening=false, FrequentlyListening=false
2016-06-11 10:35:27.955 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:398 ]- NODE 6: Node advancer: WAIT - Still waiting!
2016-06-11 10:35:31.202 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1020]- NODE 6: Node advancer - WAIT: The WAIT is over!
2016-06-11 10:35:31.202 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 12:58:03.587 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 12:58:03.587 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation
2016-06-11 14:23:06.942 [INFO ] [rialApiGetInitDataMessageClass:60  ]- NODE 6: Node found
2016-06-11 14:23:06.947 [DEBUG] [b.z.i.protocol.ZWaveController:506 ]- NODE 6: Init node thread start
2016-06-11 14:23:07.002 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:138 ]- NODE 6: Serializing from file etc\zwave\node6.xml
2016-06-11 14:23:07.002 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:141 ]- NODE 6: Error serializing from file: file does not exist.
2016-06-11 14:23:07.017 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2016-06-11 14:23:07.021 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2016-06-11 14:23:07.021 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:337 ]- NODE 6: Node advancer: Initialisation starting
2016-06-11 14:23:07.022 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to PROTOINFO
2016-06-11 14:23:07.022 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2016-06-11 14:23:07.023 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:346 ]- NODE 6: Node advancer: PROTOINFO - send IdentifyNode
2016-06-11 14:23:07.023 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 6: Node advancer - queued packet. Queue length is 1
2016-06-11 14:23:07.023 [DEBUG] [b.z.i.protocol.ZWaveController:595 ]- NODE 6: Init node thread finished
2016-06-11 14:23:07.048 [DEBUG] [i.p.s.IdentifyNodeMessageClass:56  ]- NODE 6: ProtocolInfo
2016-06-11 14:23:07.048 [DEBUG] [i.p.s.IdentifyNodeMessageClass:72  ]- NODE 6: Listening = false
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:73  ]- NODE 6: Routing = true
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:74  ]- NODE 6: Beaming = true
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:75  ]- NODE 6: Version = 4
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:76  ]- NODE 6: FLIRS = false
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:77  ]- NODE 6: Security = false
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:78  ]- NODE 6: Max Baud = 40000
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:94  ]- NODE 6: Basic = Routing Slave
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:102 ]- NODE 6: Generic = Binary Sensor
2016-06-11 14:23:07.049 [DEBUG] [i.p.s.IdentifyNodeMessageClass:110 ]- NODE 6: Specific = Routing Binary Sensor
2016-06-11 14:23:07.049 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243 ]- NODE 6: Creating new instance of command class NO_OPERATION
2016-06-11 14:23:07.050 [DEBUG] [.z.internal.protocol.ZWaveNode:613 ]- NODE 6: Adding command class NO_OPERATION to the list of supported command classes.
2016-06-11 14:23:07.050 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243 ]- NODE 6: Creating new instance of command class BASIC
2016-06-11 14:23:07.050 [DEBUG] [.z.internal.protocol.ZWaveNode:613 ]- NODE 6: Adding command class BASIC to the list of supported command classes.
2016-06-11 14:23:07.050 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:243 ]- NODE 6: Creating new instance of command class SENSOR_BINARY
2016-06-11 14:23:07.050 [DEBUG] [.z.internal.protocol.ZWaveNode:613 ]- NODE 6: Adding command class SENSOR_BINARY to the list of supported command classes.
2016-06-11 14:23:07.051 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - PROTOINFO: Transaction complete (IdentifyNode:Request) success(true)
2016-06-11 14:23:07.051 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2016-06-11 14:23:07.051 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208 ]- NODE 6: Node advancer - message removed from queue. Queue size 0.
2016-06-11 14:23:07.051 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - PROTOINFO: queue length(0), free to send(true)
2016-06-11 14:23:07.052 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - PROTOINFO try 1: stageAdvanced(false)
2016-06-11 14:23:07.052 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to NEIGHBORS
2016-06-11 14:23:07.052 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - NEIGHBORS try 0: stageAdvanced(true)
2016-06-11 14:23:07.052 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:356 ]- NODE 6: Node advancer: NEIGHBORS - send RoutingInfo
2016-06-11 14:23:07.052 [DEBUG] [p.s.GetRoutingInfoMessageClass:30  ]- NODE 6: Request routing info
2016-06-11 14:23:07.052 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 6: Node advancer - queued packet. Queue length is 1
2016-06-11 14:23:07.138 [DEBUG] [p.s.GetRoutingInfoMessageClass:49  ]- NODE 6: Got NodeRoutingInfo request.
2016-06-11 14:23:07.139 [DEBUG] [p.s.GetRoutingInfoMessageClass:79  ]- NODE 6: Neighbor nodes: 5
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - NEIGHBORS: Transaction complete (GetRoutingInfo:Request) success(true)
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208 ]- NODE 6: Node advancer - message removed from queue. Queue size 0.
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - NEIGHBORS: queue length(0), free to send(true)
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - NEIGHBORS try 1: stageAdvanced(false)
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to FAILED_CHECK
2016-06-11 14:23:07.140 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - FAILED_CHECK try 0: stageAdvanced(true)
2016-06-11 14:23:07.140 [DEBUG] [i.p.s.IsFailedNodeMessageClass:32  ]- NODE 6: Requesting IsFailedNode status from controller.
2016-06-11 14:23:07.141 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 6: Node advancer - queued packet. Queue length is 1
2016-06-11 14:23:07.215 [DEBUG] [i.p.s.IsFailedNodeMessageClass:56  ]- NODE 6: Is currently marked as healthy by the controller
2016-06-11 14:23:07.216 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1034]- NODE 6: Node advancer - FAILED_CHECK: Transaction complete (IsFailedNodeID:Request) success(true)
2016-06-11 14:23:07.216 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 1.
2016-06-11 14:23:07.216 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:208 ]- NODE 6: Node advancer - message removed from queue. Queue size 0.
2016-06-11 14:23:07.217 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 6: Node advancer - FAILED_CHECK: queue length(0), free to send(true)
2016-06-11 14:23:07.217 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - FAILED_CHECK try 1: stageAdvanced(false)
2016-06-11 14:23:07.217 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:890 ]- NODE 6: Node advancer - advancing to WAIT
2016-06-11 14:23:07.217 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:332 ]- NODE 6: Node advancer: loop - WAIT try 0: stageAdvanced(true)
2016-06-11 14:23:07.217 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:380 ]- NODE 6: Node advancer: WAIT - Listening=false, FrequentlyListening=false
2016-06-11 14:23:07.217 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:398 ]- NODE 6: Node advancer: WAIT - Still waiting!
2016-06-11 14:23:38.940 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 14:23:38.941 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation
2016-06-11 14:23:39.007 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 14:23:39.007 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation
2016-06-11 14:25:38.908 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 14:25:38.909 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation
2016-06-11 14:26:04.481 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1020]- NODE 6: Node advancer - WAIT: The WAIT is over!
2016-06-11 14:26:04.481 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:202 ]- NODE 6: Node advancer - checking initialisation queue. Queue size 0.
2016-06-11 14:29:07.457 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 14:29:07.457 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation
2016-06-11 14:33:42.292 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 14:33:42.292 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation
2016-06-11 15:00:38.615 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry_Battery is not completed initialisation
2016-06-11 15:00:38.615 [DEBUG] [.z.internal.ZWaveActiveBinding:193 ]- NODE 6: Polling list: item Door_Laundry is not completed initialisation

One of things that really stands out is:

NODE 6: Polling list: item Door_Laundry is not completed initialisation

I guess there’s not much hope outside of getting a mains device. sigh … Was hoping not to spend more money…

rlkoshak · June 12, 2016, 3:20am

Really look at your zwave network in habmin. In particular look at who is a neighbor to whom and where they are physically located. This will help you find the optimum placement for a mains powered device.

dinki · June 12, 2016, 6:53pm

Just to make sure I’m doing this right, I should bring my zstick to the door sensor while it’s in include mode (back taken off the door sensor), put the zstick in include mode, make sure it is included then bring it back to the computer, start openhab and let it finish the set up. I do not need to leave the zstick near the door sensor for any length of time, right?

The reason I keep fighting with this is that it did work with Vera before and also that that garage sensor is even farther away but within a foot of the door sensor and works without problem. I’d hate to spend money on something if it’s a configuration problem. Sorry for being a pain.

chris · June 12, 2016, 7:04pm

The sensor needs to be in direct range of openHAB, with the stick plugged in, so that the binding can configure the device. You then need to wake up the device so that the configuration can complete - this is the same for all zwave battery devices, and all controllers (including Vera etc). This allows things like the wakeup period, and wakeup node to be set. Once these things are configured, then you can move the two apart.

dinki · June 12, 2016, 7:06pm

Oh… I can try that then… So perhaps I should put the controller in include mode via habmin rather than the way I did it (unplugged using button on the zstick).

chris · June 12, 2016, 7:17pm

It doesn’t matter really. When you restart the binding it will read all the devices from the controller, and it will then try and communicate with them. What kicks things off is when you wake up the device (normally there’s a button to press - maybe you need to press it 3 times) - you might need to do this a few times to get things all sorted.

dinki · June 12, 2016, 7:19pm

I’ve put the device next to the controller, removed the previous instance (node 6) and readded (node 11). How do I know when this process is complete? I’m looking at the information in habmin and I see that it has neighbors and other information but it does not list the name like it had previously. Is there a period of time I have to wait for this to complete or is this listed as completed somewhere in the zwave.log file? Many thanks.

chris · June 12, 2016, 7:22pm

The log will give detail, but the best thing is to look at HABmin - when it shows a green ‘light’ then it’s completed initialisation. Somewhere there’s also an initialisation stage shown (I think!) and this should be progressing each time you wake up the device.

dinki · June 12, 2016, 7:26pm

Ah… Okay… Now it’s showing up with the name as before but it is grey light. Just keep waking the device up until it goes green?

chris · June 12, 2016, 7:29pm

Yes - if it gets stuck where the stage doesn’t change, then let me know…

I actually have this device here so I have a feeling you may have a problem in the STATIC stage - if it stays in that stage let me know. I’m not sure if the problem is only in OH2, or also in OH1…

dinki · June 12, 2016, 7:29pm

Now it’s green. So I need to bring it back to location and wake it up or do I just try to put it in place?