I’ll get it to you when I get back home later. Thanks
@chris Here is slightly before the log snippet I sent (I had only put it in debug right before I ran the test so maybe it wasn’t fully recording yet, can definitely do it again to see if it gets more?). I don’t see DOOR_LOCK command class in it. This was one of the locks i included by using zensys tools and then had OpenHAB discover it off my Zwave stick.
2017-08-24 09:48:05.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=107, callback=0, payload=00 6B 02 98 40
2017-08-24 09:48:05.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=107, callback=0, payload=00 6B 02 98 40
2017-08-24 09:48:05.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=107, callback=0, payload=00 6B 02 98 40
2017-08-24 09:48:05.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-08-24 09:48:05.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 107: Application Command Request (ALIVE:DONE)
2017-08-24 09:48:05.052 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 107: resetResendCount initComplete=true isDead=false
2017-08-24 09:48:05.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 107: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2017-08-24 09:48:05.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 107: SECURITY check internal
2017-08-24 09:48:05.055 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 107: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET
2017-08-24 09:48:05.056 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2017-08-24 09:48:05.063 [DEBUG] [ommandClassTransactionPayloadBuilder] - At build null
2017-08-24 09:48:05.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 107: Added to secure queue - size 1
2017-08-24 09:48:05.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 107: Commands processed 1.
2017-08-24 09:48:05.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 107: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@94a3ea.
2017-08-24 09:48:05.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-24 09:48:05.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-08-24 09:48:05.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-24 09:48:05.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-08-24 09:48:05.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from secureQueue
2017-08-24 09:48:05.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-08-24 09:48:05.089 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 6B 0A 98 80 FC CA AF E4 EE 37 FE 89 25 54 26
2017-08-24 09:48:05.092 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 107: Sending REQUEST Message = 01 11 00 13 6B 0A 98 80 FC CA AF E4 EE 37 FE 89 25 54 26
2017-08-24 09:48:05.094 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-08-24 09:48:05.096 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9478: Transaction Start type SendData
2017-08-24 09:48:05.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9478: [WAIT_RESPONSE] requiresResponse=true callback: 84
2017-08-24 09:48:05.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-08-24 09:48:05.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 0
2017-08-24 09:48:05.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-08-24 09:48:05.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Aug 24 09:48:07 MDT 2017 - 1999ms
2017-08-24 09:48:05.104 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-08-24 09:48:05.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 9478: [WAIT_RESPONSE] requiresResponse=true callback: 84
2017-08-24 09:48:05.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-08-24 09:48:05.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-08-24 09:48:05.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Aug 24 09:48:07 MDT 2017 - 1995ms
2017-08-24 09:48:05.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-08-24 09:48:05.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-08-24 09:48:05.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9478: [WAIT_RESPONSE] requiresResponse=true callback: 84
2017-08-24 09:48:05.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-08-24 09:48:05.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-24 09:48:05.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
2017-08-24 09:48:05.112 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-08-24 09:48:05.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-08-24 09:48:05.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-08-24 09:48:05.114 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-24 09:48:05.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Aug 24 09:48:07 MDT 2017 - 1987ms
2017-08-24 09:48:05.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-24 09:48:05.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-24 09:48:05.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-24 09:48:05.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9478: [WAIT_RESPONSE] requiresResponse=true callback: 84
2017-08-24 09:48:05.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-08-24 09:48:05.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 9478: [WAIT_RESPONSE] requiresResponse=true callback: 84
2017-08-24 09:48:05.122 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-24 09:48:05.123 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 107: Sent Data successfully placed on stack.
2017-08-24 09:48:05.124 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9478: TransactionAdvance ST: WAIT_RESPONSE
2017-08-24 09:48:05.125 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9478: TransactionAdvance WT: null {}
2017-08-24 09:48:05.129 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 54 00 00 02 BD
2017-08-24 09:48:05.136 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-24 09:48:05.136 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9478: TransactionAdvance RX: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
2017-08-24 09:48:05.138 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 9478: TransactionAdvance TO: WAIT_REQUEST
2017-08-24 09:48:05.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 9478: Advanced to WAIT_REQUEST
2017-08-24 09:48:05.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 107: TID 9478: Transaction not completed
2017-08-24 09:48:05.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-08-24 09:48:05.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
2017-08-24 09:48:05.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-08-24 09:48:05.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-08-24 09:48:05.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Aug 24 09:48:10 MDT 2017 - 4996ms
2017-08-24 09:48:05.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=84, payload=54 00 00 02
2017-08-24 09:48:05.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=84, payload=54 00 00 02
2017-08-24 09:48:05.153 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1E 00 04 00 6B 18 98 81 96 7D 53 8F 46 66 AE A0 A8 87 08 3A F6 FC 51 3B 84 B2 37 CF F9 CC 10
2017-08-24 09:48:05.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=84, payload=54 00 00 02
2017-08-24 09:48:05.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9478: [WAIT_REQUEST] requiresResponse=true callback: 84
2017-08-24 09:48:05.157 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-08-24 09:48:05.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-08-24 09:48:05.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=107, callback=0, payload=00 6B 18 98 81 96 7D 53 8F 46 66 AE A0 A8 87 08 3A F6 FC 51 3B 84 B2 37 CF F9 CC
2017-08-24 09:48:05.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 9478: [WAIT_REQUEST] requiresResponse=true callback: 84
2017-08-24 09:48:05.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 9478: (Callback 84)
2017-08-24 09:48:05.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=107, callback=0, payload=00 6B 18 98 81 96 7D 53 8F 46 66 AE A0 A8 87 08 3A F6 FC 51 3B 84 B2 37 CF F9 CC
2017-08-24 09:48:05.162 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2017-08-24 09:48:05.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 9478: callback 84