Zwave binding doesn't see any devices?

Setup, Configuration and Use Beginners
1

I’m using an Aeotec Z-Stick Gen5 ZWave controller … had everything working, then it seemed to stop. I’ve reset everything hoping to reconnect the devices, and now my controller can’t see anything. I’m able to add the Z-Wave controller, search for devices, and the little blue light flashes, but none of my devices show up. Any thoughts on why? DEBUG logs from my zwave binding are below.

2020-06-13 17:31:58.435 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:74c8ebd6
2020-06-13 17:31:58.437 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2020-06-13 17:31:58.442 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:74c8ebd6
2020-06-13 17:31:58.444 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2020-06-13 17:31:58.448 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2020-06-13 17:31:58.450 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion
2020-06-13 17:31:58.453 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2020-06-13 17:31:58.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 12 to queue - size 1
2020-06-13 17:31:58.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-06-13 17:31:58.461 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 05 74
2020-06-13 17:31:58.465 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 05 74
2020-06-13 17:31:58.468 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-06-13 17:31:58.469 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-06-13 17:31:58.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-13 17:31:58.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 12: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 5
2020-06-13 17:31:58.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-13 17:31:58.475 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 05 01 00 00 B6
2020-06-13 17:31:58.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 12: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 5
2020-06-13 17:31:58.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=5, payload=05 01 00 00
2020-06-13 17:31:58.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-06-13 17:31:58.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=5, payload=05 01 00 00
2020-06-13 17:31:58.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 12: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 5
2020-06-13 17:31:58.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-06-13 17:31:58.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 12: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 5
2020-06-13 17:31:58.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 12: (Callback 5)
2020-06-13 17:31:58.494 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-06-13 17:31:58.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 12: callback 5
2020-06-13 17:31:58.499 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=5, payload=05 01 00 00
2020-06-13 17:31:58.501 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2020-06-13 17:31:58.502 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart
2020-06-13 17:31:58.504 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 12: Transaction COMPLETED
2020-06-13 17:31:58.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 35ms
2020-06-13 17:31:58.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 12: Transaction completed
2020-06-13 17:31:58.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:12 DONE
2020-06-13 17:31:58.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-13 17:31:58.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.




2020-06-13 17:32:58.441 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:74c8ebd6
2020-06-13 17:32:58.445 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
2020-06-13 17:32:58.448 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-06-13 17:32:58.451 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 13 to queue - size 1
2020-06-13 17:32:58.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-06-13 17:32:58.459 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 06 B3
2020-06-13 17:32:58.463 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 06 B3
2020-06-13 17:32:58.467 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-06-13 17:32:58.470 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-06-13 17:32:58.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-13 17:32:58.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 13: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 6
2020-06-13 17:32:58.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-13 17:32:58.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 6
2020-06-13 17:32:58.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-06-13 17:32:58.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-13 17:32:58.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-06-13 17:32:58.520 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 06 06 00 00 B2
2020-06-13 17:32:58.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=6, payload=06 06 00 00
2020-06-13 17:32:58.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=6, payload=06 06 00 00
2020-06-13 17:32:58.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 13: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 6
2020-06-13 17:32:58.528 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-06-13 17:32:58.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 13: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 6
2020-06-13 17:32:58.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 13: (Callback 6)
2020-06-13 17:32:58.534 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-06-13 17:32:58.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 13: callback 6
2020-06-13 17:32:58.538 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=6, payload=06 06 00 00
2020-06-13 17:32:58.541 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2020-06-13 17:32:58.543 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone
2020-06-13 17:32:58.545 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-06-13 17:32:58.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 14 to queue - size 1
2020-06-13 17:32:58.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-06-13 17:32:58.552 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2020-06-13 17:32:58.554 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done
2020-06-13 17:32:58.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 13: Advanced to DONE
2020-06-13 17:32:58.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 86ms
2020-06-13 17:32:58.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 13: Transaction completed
2020-06-13 17:32:58.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:13 DONE
2020-06-13 17:32:58.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-13 17:32:58.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-06-13 17:32:58.572 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5
2020-06-13 17:32:58.574 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5
2020-06-13 17:32:58.577 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-06-13 17:32:58.578 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-06-13 17:32:58.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 14: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-06-13 17:32:58.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-13 17:32:58.582 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-06-13 17:32:58.586 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 14: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-06-13 17:32:58.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-06-13 17:32:58.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-06-13 17:32:58.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-06-13 17:33:03.582 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 14: Timeout at state WAIT_REQUEST. 3 retries remaining.
2020-06-13 17:33:03.596 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 14: Transaction is current transaction, so clearing!!!!!
2020-06-13 17:33:03.598 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 14: Transaction CANCELLED
2020-06-13 17:33:03.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:14 CANCELLED
2020-06-13 17:33:03.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-06-13 17:33:14.101 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
2

I’m guessing this means you did a reset of the z-wave stick? If this is the case, your existing devices will need to be “excluded” first, then put into “inclusion” mode on the device itself before the discovery process will see them (this is a z-wave thing, not a openHAB thing)

1 Like
3

Yes … tried exclusion in OpenHAB and then excluding the devices, but it would not work. Finally installed the ZWave tools found in this note (Aoetec z-stick gen5 (Z-wave Node 003) appears as OFFLINE - COMMUNICATION_ERROR Please help), excluded them using that controller, and now it’s working again in OpenHAB!

4

For future reference, you can use the stick itself to exclude devices without any other software, unplug the stick and then hold down the button on it until it starts flashing red , you can then initiate the include/exclude function on your devices. Just cary it around with you from device to device (it has an internal battery). I have never needed to use the zensys software to exclude devices

1 Like
5

Many of the cheaper devices exclude but leave behind the zombie node on the controller network. The software allows you to delete those nodes so they do not affect node routing.

7

If it were a hardware problem, I’d expect the problem to occur both in OpenHAB and the Silicon Labs software? Since it works fine in SL tools, it seems to point to a problem in the OpenHAB driver for this chip…

8

my USB stick does not have a button?

9

Most models do not.

You can also factory default the device & then include it. My experience is that works better for some cheaper Chinese devices anyway.

10

the “Aeotec Z-Stick Gen5 ZWave” stick should have a button (i have a few of these) . The “disco” light doubles as a one where doing a short press puts the stick in inclusion mode and a long press puts it into exclude mode. Does yours not have this ?