Platform information:
- Hardware: Raspberry 3b
- OS: Linux openHABianPi 4.19.66-v7+ #1253
- openHAB version: 3.0
- openHABian configuration tool version: [stable]v1.6.1-1021(01d4e59)
- Java Version: 11.0.9 2020-10-20 LTS
After successful inclusion of a brand new “Heatit Z-Push Button 4” in Openhab 3.0.0 Release-Build I cannot configure the thing becaued of the unknown device. I tried to send “Node Information Frame” multiple times with a distance of 10 cm to my controller (Aeotec Z-Stick Gen5 Plus).
I sent the Node Information Frame as described in the instructions (https://opensmarthouse.org/zwavedatabase/1162/reference/Manual-Heatit-Z-Push-Button-4--FW-1-26-Ver2019-A-ENG.pdf):
Set the Push Button to adding/removing mode: Press and hold
down both I- and O-buttons of Group 2 for 3 seconds. The LED
indicator turns on to indicate that the Push Button has already
sent out a Node Information Frame. The user may repeat the
operation to turn the adding/removing mode off.
But unfortunately I always get the warning in the log:
Device discovery could not resolve to a thingType! 019B:0300:A306::1.26
The behavior is strange in my opinion, as the device seems to be present in the database (see OpenSmartHouse Z-Wave Device Database)
This is a debug-log of sending the Node Information Frame:
16:33:08.565 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 4C 02 84 07 3E
16:33:08.576 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=76, callback=0, payload=00 4C 02 84 07
16:33:08.585 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=76, callback=0, payload=00 4C 02 84 07
16:33:08.598 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
16:33:08.609 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Application Command Request (ALIVE:DONE)
16:33:08.619 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: resetResendCount initComplete=true isDead=false
16:33:08.629 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Is awake with 0 messages in the queue
16:33:08.635 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Start sleep timer at 1000ms
16:33:08.643 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 76: Got an event from Z-Wave network: ZWaveNodeStatusEvent
16:33:08.653 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 76: Node Status event - Node is AWAKE
16:33:08.663 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Commands processed 1.
16:33:08.669 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d950d7.
16:33:08.676 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
16:33:08.689 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
16:33:08.693 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
16:33:08.697 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
16:33:09.143 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: WakeupTimerTask 0 Messages waiting, state DONE
16:33:09.642 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: WakeupTimerTask 0 Messages waiting, state DONE
16:33:09.651 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: No more messages, go back to sleep
16:33:09.658 [DEBUG] [.commandclass.ZWaveWakeUpCommandClass] - NODE 76: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
16:33:09.668 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: SECURITY not supported
16:33:09.677 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
16:33:09.686 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1bcd04c
16:33:09.696 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Bump transaction 580 priority from Immediate to Immediate
16:33:09.712 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Adding to device queue
16:33:09.721 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Added 580 to queue - size 14
16:33:09.727 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
16:33:09.740 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 4C 02 84 08 25 91 93
16:33:09.750 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 76: Sending REQUEST Message = 01 09 00 13 4C 02 84 08 25 91 93
16:33:09.756 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
16:33:09.759 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
16:33:09.762 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 580: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 145
16:33:09.768 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
16:33:09.774 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
16:33:09.777 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
16:33:09.780 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 580: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 145
16:33:09.791 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
16:33:09.796 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
16:33:09.802 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
16:33:09.809 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
16:33:09.814 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
16:33:09.806 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 91 00 00 02 78
16:33:09.819 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 580: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 145
16:33:09.824 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=145, payload=91 00 00 02
16:33:09.825 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
16:33:09.834 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 580: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 145
16:33:09.840 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
16:33:09.845 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 76: sentData successfully placed on stack.
16:33:09.850 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 580: Advanced to WAIT_REQUEST
16:33:09.854 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: TID 580: Transaction not completed
16:33:09.858 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=145, payload=91 00 00 02
16:33:09.863 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 580: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 145
16:33:09.867 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
16:33:09.874 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 580: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 145
16:33:09.878 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 580: (Callback 145)
16:33:09.883 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
16:33:09.888 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 580: callback 145
16:33:09.893 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=145, payload=91 00 00 02
16:33:09.899 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 76: SendData Request. CallBack ID = 145, Status = Transmission complete and ACK received(0)
16:33:09.906 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: resetResendCount initComplete=true isDead=false
16:33:09.911 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 580: Transaction COMPLETED
16:33:09.917 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Response processed after 156ms
16:33:09.921 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: TID 580: Transaction completed
16:33:09.925 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: notifyTransactionResponse TID:580 DONE
16:33:09.929 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 76: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
16:33:09.931 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 580: Transaction event listener: DONE: DONE ->
16:33:09.936 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
16:33:09.936 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Went to sleep COMPLETE
16:33:09.940 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
16:33:10.207 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1C 00 49 84 4C 16 04 18 01 5E 85 8E 70 5B 59 55 86 72 5A 73 80 98 9F 84 6C 7A EF 26 1A
16:33:10.213 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=76, callback=132, payload=84 4C 16 04 18 01 5E 85 8E 70 5B 59 55 86 72 5A 73 80 98 9F 84 6C 7A EF 26
16:33:10.220 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=76, callback=132, payload=84 4C 16 04 18 01 5E 85 8E 70 5B 59 55 86 72 5A 73 80 98 9F 84 6C 7A EF 26
16:33:10.226 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
16:33:10.232 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
16:33:10.236 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: null
16:33:10.241 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=76, callback=132, payload=84 4C 16 04 18 01 5E 85 8E 70 5B 59 55 86 72 5A 73 80 98 9F 84 6C 7A EF 26
16:33:10.249 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 76: Application update request. Node information received. Transaction null
16:33:10.255 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: resetResendCount initComplete=true isDead=false
16:33:10.259 [DEBUG] [message.ApplicationUpdateMessageClass] - NODE 76: Application update - no transaction.
16:33:10.265 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
16:33:10.270 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
16:33:10.516 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Is awake with 0 messages in the queue
16:33:10.522 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Start sleep timer at 1000ms
16:33:10.527 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 76: Got an event from Z-Wave network: ZWaveNodeStatusEvent
16:33:10.540 [DEBUG] [ave.internal.protocol.ZWaveController] - NODE 76: Node Status event - Node is AWAKE
16:33:11.028 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: WakeupTimerTask 0 Messages waiting, state DONE
16:33:11.528 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: WakeupTimerTask 0 Messages waiting, state DONE
16:33:11.534 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: No more messages, go back to sleep
16:33:11.543 [DEBUG] [.commandclass.ZWaveWakeUpCommandClass] - NODE 76: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
16:33:11.550 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: SECURITY not supported
16:33:11.555 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
16:33:11.564 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1b221b1
16:33:11.573 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Bump transaction 581 priority from Immediate to Immediate
16:33:11.579 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Adding to device queue
16:33:11.587 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Added 581 to queue - size 14
16:33:11.599 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
16:33:11.618 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 4C 02 84 08 25 92 90
16:33:11.624 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 76: Sending REQUEST Message = 01 09 00 13 4C 02 84 08 25 92 90
16:33:11.639 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
16:33:11.639 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
16:33:11.650 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
16:33:11.649 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 581: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 146
16:33:11.662 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
16:33:11.673 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 581: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 146
16:33:11.678 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
16:33:11.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
16:33:11.680 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
16:33:11.686 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
16:33:11.698 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
16:33:11.706 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 92 00 00 02 7B
16:33:11.705 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
16:33:11.711 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=146, payload=92 00 00 02
16:33:11.714 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 581: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 146
16:33:11.720 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
16:33:11.725 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 581: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 146
16:33:11.730 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
16:33:11.735 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 76: sentData successfully placed on stack.
16:33:11.740 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 581: Advanced to WAIT_REQUEST
16:33:11.746 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: TID 581: Transaction not completed
16:33:11.751 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=146, payload=92 00 00 02
16:33:11.758 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 581: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 146
16:33:11.761 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
16:33:11.767 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 581: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 146
16:33:11.773 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 581: (Callback 146)
16:33:11.778 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
16:33:11.782 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 581: callback 146
16:33:11.786 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=146, payload=92 00 00 02
16:33:11.791 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 76: SendData Request. CallBack ID = 146, Status = Transmission complete and ACK received(0)
16:33:11.797 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: resetResendCount initComplete=true isDead=false
16:33:11.804 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 581: Transaction COMPLETED
16:33:11.808 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: Response processed after 158ms
16:33:11.812 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: TID 581: Transaction completed
16:33:11.818 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 76: notifyTransactionResponse TID:581 DONE
16:33:11.822 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 76: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
16:33:11.822 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 581: Transaction event listener: DONE: DONE ->
16:33:11.826 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
16:33:11.830 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 76: Went to sleep COMPLETE
16:33:11.835 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
I have already tried the following things without success:
- Removed the device and reinitialized it.
- Device reset to factory settings
- Soft reset of the controller
- Reboot of the Raspberry Pi
Does anyone have any idea what the problem is?
