Zwave nodes not discovered

thoberre · September 26, 2020, 9:26am

Hi,

I just started to try out OpenHAB 3.0.0-SNAPSHOT after being on the 2.5.x branch for some time.

The OH3 looks really promising on solving my biggest challenge on using OH properly since I never achieved to tag my items properly, not being comfortable with the fileconfigs so I could e.g use semantic tagging properly.

However I ran into a challenge with the Zwave binding after upgrading.

I have an Aeontec Gen5 stick for the Z-wave controller running on an RPI3.

I’m on the latest snapshot release 3.0.0-SNAPSHOT (#1943) for both core and addons.
Z-Wave binding on 3.0.0.202009141829│openHAB Add-ons :: Bundles :: ZWave Binding

After upgrading my zwave devices was all OFFLINE. I removed all config from OH and thought I just discover them back.

However the discovery of zwave items does not show any results. I’m pretty confident my config is still on the stick since my zwave debug logs shows nodes

It also seems like OH gets messages from the nodes already included like theese:
[WARN ] [nal.protocol.ZWaveTransactionManager] - NODE 3: Not initialized (ie node unknown), ignoring message.

Any pointers on how to go forward on this?

020-09-26 11:21:03.423 [DEBUG] [age.SerialApiGetInitDataMessageClass] - Got MessageSerialApiGetInitData response.
2020-09-26 11:21:03.425 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 1: Node found
2020-09-26 11:21:03.426 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 3: Node found
2020-09-26 11:21:03.428 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 7: Node found
2020-09-26 11:21:03.430 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 8: Node found
2020-09-26 11:21:03.431 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 9: Node found
2020-09-26 11:21:03.433 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 10: Node found
2020-09-26 11:21:03.435 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 11: Node found
2020-09-26 11:21:03.436 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 12: Node found
2020-09-26 11:21:03.438 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 13: Node found
2020-09-26 11:21:03.440 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 16: Node found
2020-09-26 11:21:03.441 [DEBUG] [age.SerialApiGetInitDataMessageClass] - NODE 17: Node found
2020-09-26 11:21:03.443 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2020-09-26 11:21:03.445 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2020-09-26 11:21:03.447 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller------------
2020-09-26 11:21:03.448 [DEBUG] [age.SerialApiGetInitDataMessageClass] - # Nodes = 11
2020-09-26 11:21:03.450 [DEBUG] [age.SerialApiGetInitDataMessageClass] - ----------------------------------------------------------------------------
2020-09-26 11:21:03.452 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 49: Transaction COMPLETED
2020-09-26 11:21:03.454 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 1: Init node thread start
2020-09-26 11:21:03.456 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 3: Init node thread start
2020-09-26 11:21:03.463 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 7: Init node thread start
2020-09-26 11:21:03.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 162ms
2020-09-26 11:21:03.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 49: Transaction completed
2020-09-26 11:21:03.471 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 17: Init node thread start
2020-09-26 11:21:03.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:49 DONE
2020-09-26 11:21:03.470 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 16: Init node thread start
2020-09-26 11:21:03.468 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 11: Init node thread start
2020-09-26 11:21:03.470 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 9: Init node thread start
2020-09-26 11:21:03.469 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 12: Init node thread start
2020-09-26 11:21:03.469 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 13: Init node thread start
2020-09-26 11:21:03.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-09-26 11:21:03.463 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 8: Init node thread start
2020-09-26 11:21:03.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-09-26 11:21:03.486 [DEBUG] [ve.internal.protocol.ZWaveController] - Starting waiting for init threads
2020-09-26 11:21:03.520 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 05 F9 
2020-09-26 11:21:03.521 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_1_init
2020-09-26 11:21:03.467 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 10: Init node thread start
2020-09-26 11:21:03.530 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 05 F9 
2020-09-26 11:21:03.534 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-09-26 11:21:03.534 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-09-26 11:21:03.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-09-26 11:21:03.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction
SendNextMessage started: TID 50: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-09-26 11:21:03.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-09-26 11:21:03.550 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_1_init complete
2020-09-26 11:21:03.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 50: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0
2020-09-26 11:21:03.561 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 05 1C E3 
2020-09-26 11:21:03.563 [DEBUG] [ve.internal.protocol.ZWaveController] - Waiting for init thread Node_3_init
2020-09-26 11:21:03.568 [DEBUG] [ve.internal.protocol.ZWaveController] - Init thread Node_3_init complete

Edit: After running updating to #1945 my Items are back - not sure if the new version did the magic or my unstructured troubleshooting started something. However now the states, specially for the secured items (lock) are not updated.

I understand that running oh3 at the current state is experimental, but a pointer on what to excpect at the current level would be great!

Edit2: So last one was my network security key had changed. Added back the old one and my devices looks good.

Bruce_Osborne · September 26, 2020, 10:57am

The Z-Zwave binding has only found my controller with recent releases. I was going to try and add a device to my test system but have not yet tried that.

bf8392 · January 13, 2021, 1:48pm

Hi =), I’m running the latest stable with exact the same z-stick and it works there! I needed some time to get used to the main-ui, but it is togehther with z-wave an absolute masterpiece of software! Try it out again it should work now…

By the Way: Thanks to all the developers of OH3, z-Wave and all other bindings…openhab and the new main ui is unbeatable smarthome-system now! @thoberre try out the new way to add metadata…it became extremly easy and will give you a whole lot more control and customization options over the ui!