If you’re wondering about the version, this logging is from the correct version of the binding at least, so security is supported.
1 Like
255 │ Active │ 80 │ 2.2.0.201801021718 │ org.openhab.binding.zwave
BTW I don’t know if this is help but I downloaded zwave PC controller and sent commands (on/off) to the door lock and it was working…
It sounds like there’s a key issue - are you sure you are using the same keys as when it was included?
what do you mean the same keys?
The same network key that you used when including the device must always be used - otherwise it won’t work.
I don’t familiar with network key… sorry.
how can I check it?
The network security key is set in the controller thing in the network settings group. If you have run the Zensys tool and it works, it probably uses a different key (I don’t know what it does) - for sure it’s VERY unlikely to have used the same key as you are using with openHAB, so I suspect that this might be your problem.
How can I fix this problem?
BTW Here are debug logs: (The lock is Node 65)
Here are debug logs: (The lock is Node 65)
01-Jun-2018 00:10:38.304 [WARN ] [ng.zwave.internal.converter.ZWaveDoorLockConverter] - NODE 65: Command class COMMAND_CLASS_DOOR_LOCK not found
01-Jun-2018 00:11:45.102 [ERROR] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.
01-Jun-2018 00:11:45.112 [ERROR] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.
01-Jun-2018 00:11:45.123 [ERROR] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.
01-Jun-2018 00:11:45.171 [ERROR] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.
01-Jun-2018 00:11:45.181 [ERROR] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.
01-Jun-2018 00:11:45.191 [ERROR] [protocol.serialmessage.RequestNodeInfoMessageClass] - Request node info not placed on stack due to error.
01-Jun-2018 00:14:25.618 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 65: ZWaveCommandClassTransactionPayload - send to node
01-Jun-2018 00:14:25.620 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 65: Encapsulating message, endpoint 0
01-Jun-2018 00:14:25.622 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 65: SECURITY check internal
01-Jun-2018 00:14:25.624 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 65: Command Class COMMAND_CLASS_SECURITY is NOT required to be secured
01-Jun-2018 00:14:25.626 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@10fb50b
01-Jun-2018 00:14:25.628 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Adding to device queue
01-Jun-2018 00:14:25.630 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Added to queue - size 14
01-Jun-2018 00:14:25.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:25.633 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
01-Jun-2018 00:14:25.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:25.637 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:25 IDT 2018 - 65ms
01-Jun-2018 00:14:25.704 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - XXXXXXXXX Timeout.......... 1 outstanding transactions
01-Jun-2018 00:14:25.706 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 62: TID 438: Timeout at state WAIT_DATA. 3 retries remaining.
01-Jun-2018 00:14:25.707 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 438: Transaction is current transaction, so clearing!!!!!
01-Jun-2018 00:14:25.708 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 438: Transaction CANCELLED
01-Jun-2018 00:14:25.710 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
01-Jun-2018 00:14:25.712 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
01-Jun-2018 00:14:25.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 62: notifyTransactionResponse TID:438 CANCELLED
01-Jun-2018 00:14:25.720 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
01-Jun-2018 00:14:25.729 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 65: listening == false, frequentlyListening == true, awake == false
01-Jun-2018 00:14:25.730 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 438: Transaction event listener: DONE: CANCELLED ->
01-Jun-2018 00:14:25.731 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue
01-Jun-2018 00:14:25.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Transaction requires security
01-Jun-2018 00:14:25.736 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 65: isNonceAvailable = null
01-Jun-2018 00:14:25.740 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 62: -- To notify -- TIMEOUT_WAITING_FOR_DATA
01-Jun-2018 00:14:25.746 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ********* Transaction Response Complete 438 --
01-Jun-2018 00:14:25.743 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1
01-Jun-2018 00:14:25.749 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 62: Node Init response (0) TIMEOUT_WAITING_FOR_DATA
01-Jun-2018 00:14:25.749 [DEBUG] [saction.ZWaveCommandClassTransactionPayloadBuilder] - At build COMMAND_CLASS_SECURITY
01-Jun-2018 00:14:25.754 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 41 02 98 40 25 E2 B9
01-Jun-2018 00:14:25.759 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 65: Sending REQUEST Message = 01 09 00 13 41 02 98 40 25 E2 B9
01-Jun-2018 00:14:25.759 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 62: No data from device, but it was ACK'd. Possibly not supported? (Try 0)
01-Jun-2018 00:14:25.762 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
01-Jun-2018 00:14:25.765 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: Transaction Start type SendData
01-Jun-2018 00:14:25.765 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
01-Jun-2018 00:14:25.767 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 477: [WAIT_RESPONSE] requiresResponse=true callback: 226
01-Jun-2018 00:14:25.767 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
01-Jun-2018 00:14:25.769 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_SECURITY
01-Jun-2018 00:14:25.771 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 128
01-Jun-2018 00:14:25.769 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
01-Jun-2018 00:14:25.774 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
01-Jun-2018 00:14:25.777 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
01-Jun-2018 00:14:25.779 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
01-Jun-2018 00:14:25.780 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
01-Jun-2018 00:14:25.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
01-Jun-2018 00:14:25.782 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:25.779 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:25.784 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
01-Jun-2018 00:14:25.786 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:25.788 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:25.786 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:27 IDT 2018 - 1994ms
01-Jun-2018 00:14:25.792 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 477: [WAIT_RESPONSE] requiresResponse=true callback: 226
01-Jun-2018 00:14:25.792 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:25.794 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:25.797 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:27 IDT 2018 - 1983ms
01-Jun-2018 00:14:25.799 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
01-Jun-2018 00:14:25.804 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:25.809 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:27 IDT 2018 - 1971ms
01-Jun-2018 00:14:25.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:25.822 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 477: [WAIT_RESPONSE] requiresResponse=true callback: 226
01-Jun-2018 00:14:25.826 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
01-Jun-2018 00:14:25.830 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 477: [WAIT_RESPONSE] requiresResponse=true callback: 226
01-Jun-2018 00:14:25.834 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:25.839 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 65: sentData successfully placed on stack.
01-Jun-2018 00:14:25.847 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance ST: WAIT_RESPONSE
01-Jun-2018 00:14:25.850 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance WT: ApplicationCommandHandler {}
01-Jun-2018 00:14:25.853 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance RX: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:25.856 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance TO: WAIT_REQUEST
01-Jun-2018 00:14:25.858 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 477: Advanced to WAIT_REQUEST
01-Jun-2018 00:14:25.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: TID 477: Transaction not completed
01-Jun-2018 00:14:25.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:25.866 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:25.868 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
01-Jun-2018 00:14:25.870 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:25.873 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:30 IDT 2018 - 4988ms
01-Jun-2018 00:14:27.158 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 E2 00 00 8C 85
01-Jun-2018 00:14:27.161 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
01-Jun-2018 00:14:27.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=226, payload=E2 00 00 8C
01-Jun-2018 00:14:27.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=226, payload=E2 00 00 8C
01-Jun-2018 00:14:27.167 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=226, payload=E2 00 00 8C
01-Jun-2018 00:14:27.169 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 477: [WAIT_REQUEST] requiresResponse=true callback: 226
01-Jun-2018 00:14:27.170 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
01-Jun-2018 00:14:27.172 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 477: [WAIT_REQUEST] requiresResponse=true callback: 226
01-Jun-2018 00:14:27.173 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 477: (Callback 226)
01-Jun-2018 00:14:27.176 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
01-Jun-2018 00:14:27.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 477: callback 226
01-Jun-2018 00:14:27.179 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=226, payload=E2 00 00 8C
01-Jun-2018 00:14:27.181 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 65: SendData Request. CallBack ID = 226, Status = Transmission complete and ACK received(0)
01-Jun-2018 00:14:27.183 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance ST: WAIT_REQUEST
01-Jun-2018 00:14:27.184 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance WT: ApplicationCommandHandler {}
01-Jun-2018 00:14:27.186 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance RX: Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=226, payload=E2 00 00 8C
01-Jun-2018 00:14:27.188 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance RQ: RREQ=true, RCLS=ApplicationCommandHandler
01-Jun-2018 00:14:27.189 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance TO: WAIT_DATA
01-Jun-2018 00:14:27.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 477: Advanced to WAIT_DATA
01-Jun-2018 00:14:27.194 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - XXXXXXXXXXXXXXXXX lastTransaction COMPLETED - at DATA - 226
01-Jun-2018 00:14:27.195 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: TID 477: Transaction not completed
01-Jun-2018 00:14:27.197 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:27.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:27.199 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
01-Jun-2018 00:14:27.200 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:27.202 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:32 IDT 2018 - 4993ms
01-Jun-2018 00:14:27.239 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 41 0A 98 80 3C B4 EE BD EE 7E 89 82 F8
01-Jun-2018 00:14:27.244 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
01-Jun-2018 00:14:27.249 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=65, callback=0, payload=00 41 0A 98 80 3C B4 EE BD EE 7E 89 82
01-Jun-2018 00:14:27.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=65, callback=0, payload=00 41 0A 98 80 3C B4 EE BD EE 7E 89 82
01-Jun-2018 00:14:27.257 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=65, callback=0, payload=00 41 0A 98 80 3C B4 EE BD EE 7E 89 82
01-Jun-2018 00:14:27.258 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
01-Jun-2018 00:14:27.259 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Application Command Request (ALIVE:SECURITY_REPORT)
01-Jun-2018 00:14:27.261 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 65: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
01-Jun-2018 00:14:27.262 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 65: SECURITY check internal
01-Jun-2018 00:14:27.263 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 65: Received COMMAND_CLASS_SECURITY V0 SECURITY_NONCE_REPORT
01-Jun-2018 00:14:27.265 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 65: NONCE Received start...
01-Jun-2018 00:14:27.267 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 65: NONCE Received start null
01-Jun-2018 00:14:27.269 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 65: NONCE Received ZWaveNonce [nonceBytes=(3C B4 EE BD EE 7E 89 82 ), timer=496193, valid=true]
01-Jun-2018 00:14:27.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Commands processed 1.
01-Jun-2018 00:14:27.272 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12659d.
01-Jun-2018 00:14:27.273 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Checking transaction 477 ApplicationCommandHandler.
01-Jun-2018 00:14:27.274 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Checking transaction : state >> WAIT_DATA
01-Jun-2018 00:14:27.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Checking transaction : node >> 65
01-Jun-2018 00:14:27.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Checking transaction : class >> 152 == 152.
01-Jun-2018 00:14:27.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Checking transaction : commd >> 128 == 128.
01-Jun-2018 00:14:27.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12659d.
01-Jun-2018 00:14:27.281 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance ST: WAIT_DATA
01-Jun-2018 00:14:27.282 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance WT: ApplicationCommandHandler {}
01-Jun-2018 00:14:27.284 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance RX: Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=65, callback=0, payload=00 41 0A 98 80 3C B4 EE BD EE 7E 89 82
01-Jun-2018 00:14:27.286 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 477: TransactionAdvance TO: DONE
01-Jun-2018 00:14:27.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: notifyTransactionResponse TID:477 DONE
01-Jun-2018 00:14:27.289 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
01-Jun-2018 00:14:27.290 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 65: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
01-Jun-2018 00:14:27.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
01-Jun-2018 00:14:27.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
01-Jun-2018 00:14:27.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:27.295 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
01-Jun-2018 00:14:27.296 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from secureQueue
01-Jun-2018 00:14:27.297 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: Transaction requires security
01-Jun-2018 00:14:27.298 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: NONCE available so encap and send.
01-Jun-2018 00:14:27.299 [DEBUG] [.protocol.commandclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1
01-Jun-2018 00:14:27.303 [DEBUG] [al.protocol.commandclass.ZWaveSecurityCommandClass] - NODE 65: SECURITY_TXD 98 02
01-Jun-2018 00:14:27.307 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 13 41 16 98 81 C9 04 86 68 37 6E 8D 83 3D E5 13 3C 78 1C DC A9 66 C1 33 96 25 E3 E9
01-Jun-2018 00:14:27.310 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 65: Sending REQUEST Message = 01 1D 00 13 41 16 98 81 C9 04 86 68 37 6E 8D 83 3D E5 13 3C 78 1C DC A9 66 C1 33 96 25 E3 E9
01-Jun-2018 00:14:27.312 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
01-Jun-2018 00:14:27.313 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 476: Transaction Start type SendData
01-Jun-2018 00:14:27.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 476: [WAIT_RESPONSE] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.316 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_SECURITY
01-Jun-2018 00:14:27.316 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
01-Jun-2018 00:14:27.317 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 3
01-Jun-2018 00:14:27.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
01-Jun-2018 00:14:27.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:27.320 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
01-Jun-2018 00:14:27.321 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:29 IDT 2018 - 1999ms
01-Jun-2018 00:14:27.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 476: [WAIT_RESPONSE] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.323 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:27.325 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
01-Jun-2018 00:14:27.325 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:29 IDT 2018 - 1994ms
01-Jun-2018 00:14:27.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
01-Jun-2018 00:14:27.329 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
01-Jun-2018 00:14:27.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 476: [WAIT_RESPONSE] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.332 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
01-Jun-2018 00:14:27.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
01-Jun-2018 00:14:27.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:27.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:27.339 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:27.339 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:27.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
01-Jun-2018 00:14:27.345 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:27.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:29 IDT 2018 - 1972ms
01-Jun-2018 00:14:27.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:27.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 476: [WAIT_RESPONSE] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
01-Jun-2018 00:14:27.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 476: [WAIT_RESPONSE] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.360 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:27.363 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 65: sentData successfully placed on stack.
01-Jun-2018 00:14:27.365 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 476: TransactionAdvance ST: WAIT_RESPONSE
01-Jun-2018 00:14:27.367 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 476: TransactionAdvance WT: ApplicationCommandHandler {}
01-Jun-2018 00:14:27.370 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 476: TransactionAdvance RX: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
01-Jun-2018 00:14:27.372 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 476: TransactionAdvance TO: WAIT_REQUEST
01-Jun-2018 00:14:27.374 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 476: Advanced to WAIT_REQUEST
01-Jun-2018 00:14:27.377 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 65: TID 476: Transaction not completed
01-Jun-2018 00:14:27.379 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:27.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:27.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
01-Jun-2018 00:14:27.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:27.388 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:32 IDT 2018 - 4989ms
01-Jun-2018 00:14:27.613 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 E1 22 71
01-Jun-2018 00:14:27.615 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
01-Jun-2018 00:14:27.619 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[0x48], type=Request[0x00], dest=34, callback=225, payload=E1 22
01-Jun-2018 00:14:27.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=RequestNodeNeighborUpdate[0x48], type=Request[0x00], dest=34, callback=225, payload=E1 22
01-Jun-2018 00:14:27.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[0x48], type=Request[0x00], dest=34, callback=225, payload=E1 22
01-Jun-2018 00:14:27.628 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 476: [WAIT_REQUEST] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.631 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
01-Jun-2018 00:14:27.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 476: [WAIT_REQUEST] requiresResponse=true callback: 227
01-Jun-2018 00:14:27.637 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 476: (Callback 227)
01-Jun-2018 00:14:27.639 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - NO callback match! (227 <> 225)
01-Jun-2018 00:14:27.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ****************** Transaction not correlated
01-Jun-2018 00:14:27.644 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[0x48], type=Request[0x00], dest=34, callback=225, payload=E1 22
01-Jun-2018 00:14:27.648 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction
01-Jun-2018 00:14:27.651 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
01-Jun-2018 00:14:27.653 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
01-Jun-2018 00:14:27.655 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
01-Jun-2018 00:14:27.657 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
01-Jun-2018 00:14:27.659 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Fri Jun 01 00:14:32 IDT 2018 - 4717ms
01-Jun-2018 00:14:27.677 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 E3 00 00 23 2BI’m using raspberry pi, for the Zwnsys tool I took the controller to my PC and used it from it.
But the inclusion was using the habmin when it connected to the raspberry pi.
You need to completely reset the device and re-include it. Make sure that you know what the key is so when you reinstall, you can make sure it’s set correctly, or if you use other software, it’s also known.
Please can you delete and reformat using the </> button. Currently this is very hard to use as it’s pasted as a quote and I can’t easily process this due to the extra characters on the line.
I’m not sure what’s going on, but if you didn’t transfer the key, it’s unlikely that be the same in both places.
I’ve tried to factory reset the luck and still the same problem…
[ng.zwave.internal.converter.ZWaveDoorLockConverter] - NODE 66: Command class COMMAND_CLASS_DOOR_LOCK not found
Please ensure that the device is securely included. Please check the logs to see what is happening.
How can I be sure the drive is securely included?
What are the logs I need? Debug? Inclusion process?
The logs above are same as now.
If you provide a fill debug log of the initialisation I will have a look at what is happening. Otherwise we are just guessing.
Habmin will show in the properties if the secure inclusion was ok.
I’ll try to do it again with the logs in debug mode.
I see is not secured, tried a few times and still I have no idea why…
Then this is the likely reason that the command class is not found. You need to resolve why it’s not securely included.
Tried everything.
event this process: OH2 Z-Wave refactoring and testing... and SECURITY
still no secure inclusion…
I have noticed that no one of my zwave nodes connected as secure…
I had a backup of the zwave stick before the upgrade to 2.3 (which worked perfect) and in the old version it wasn’t secure too…
I’ve solved the problem.
I’ve removed the zwave stick thing and re-created it and now it is working!
thanks!