- Platform information:
- Hardware: Raspberry Pi 3B
- OS: Openhabian 1.5
- Java Runtime Environment: I do not know, I ran all updates in the config tool
- openHAB version: 2.4.0-1
I am not able to discover any Z-Wave devices with the Vision ZU1401-5 USB dongle. The Z-Wave binding is installed and shows “online” (I installed and configured it in PaperUI). When I click on the ‘+’ in the inbox and choose the Z-Wave binding then my assumption is that I should be able to include my Z-Wave sensor (by power up and press connect button) and see it in Openhab.
When doing so the Vision ZU1401-5 does not show any change in the LEDs visible. The sensor I use is a WADWAZ-1 an listed as compatible device. I repeated the whole inclusion process several times with different settings for the Z-Wave binding and different sensors without succes. I also do not get any error messages.
I managed to switch the log to debug mode and got that output when pressing the ‘+’ in the inbox:
2019-11-30 14:27:37.395 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@361307
==> /var/log/openhab2/openhab.log <==
2019-11-30 14:27:37.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:23 DONE
2019-11-30 14:27:37.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-11-30 14:27:37.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-11-30 14:28:07.290 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:c1ac8adc
2019-11-30 14:28:07.292 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
2019-11-30 14:28:07.294 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2019-11-30 14:28:07.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 24 to queue - size 1
2019-11-30 14:28:07.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-11-30 14:28:07.300 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 11 A4
2019-11-30 14:28:07.302 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 11 A4
2019-11-30 14:28:07.304 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-11-30 14:28:07.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 24: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 17
2019-11-30 14:28:07.307 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-11-30 14:28:07.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-11-30 14:28:07.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-11-30 14:28:07.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 24: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 17
2019-11-30 14:28:07.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-11-30 14:28:07.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-11-30 14:28:07.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-11-30 14:28:07.357 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 11 06 00 00 A5
2019-11-30 14:28:07.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=17, payload=11 06 00 00
2019-11-30 14:28:07.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=17, payload=11 06 00 00
2019-11-30 14:28:07.364 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 24: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 17
2019-11-30 14:28:07.366 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-11-30 14:28:07.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 24: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 17
2019-11-30 14:28:07.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 24: (Callback 17)
2019-11-30 14:28:07.372 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-11-30 14:28:07.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 24: callback 17
2019-11-30 14:28:07.376 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=17, payload=11 06 00 00
2019-11-30 14:28:07.378 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2019-11-30 14:28:07.380 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone
2019-11-30 14:28:07.382 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2019-11-30 14:28:07.384 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 25 to queue - size 1
2019-11-30 14:28:07.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-11-30 14:28:07.388 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2019-11-30 14:28:07.391 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done
2019-11-30 14:28:07.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 24: Advanced to DONE
2019-11-30 14:28:07.395 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 88ms
2019-11-30 14:28:07.396 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 24: Transaction completed
2019-11-30 14:28:07.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:24 DONE
2019-11-30 14:28:07.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-11-30 14:28:07.404 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-11-30 14:28:07.406 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5
2019-11-30 14:28:07.409 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5
2019-11-30 14:28:07.411 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-11-30 14:28:07.412 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-11-30 14:28:07.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2019-11-30 14:28:07.415 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-11-30 14:28:07.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-11-30 14:28:07.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2019-11-30 14:28:07.421 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-11-30 14:28:07.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-11-30 14:28:07.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-11-30 14:28:12.415 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 25: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-11-30 14:28:12.417 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 25: Transaction is current transaction, so clearing!!!
2019-11-30 14:28:12.419 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 25: Transaction CANCELLED
2019-11-30 14:28:12.421 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:25 CANCELLED
2019-11-30 14:28:12.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-11-30 14:28:51.287 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised`