No spamming, you’re in the same boat as I. Same lock (BE469). I will say some of the configuration settings have gone through, as the original code was 4-digits, when I changed the setting to 6 digits, I did notice it no longer was accepting the old code (expected behavior) and now would let you enter 6 digits before giving you the Red X for a non-existent code.
I’ve set to DEBUG level and made the update. I don’t see anything alarming in the logs myself, it looks as though the transaction went through successfully. Validated the code is not working in the door lock. I notice in HABmin as well that the code transaction has little orange pending bubble still. Log output is below.
I’m also wondering if I’ll be able to get the quicker status updates from manual operation. Looking at the product page with ZWaveAlliance - this lock doesn’t have associations like others for instant status updates. I know obviously the mapping piece outlined would be helpful, but in my debug logs I don’t even see anything relative to actions when putting in the wrong code, unlocking the door, anything. All I can do is use the item to lock/unlock - but no way to really read the status it seems. I think unfortunately though, this is due to the lock capabilities. A new Yale lock with ZWave Plus is on it’s way … I think this lock will be getting replaced.
NODE26 = Lock
09:30:08.526 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 26: Configuration update received
09:30:08.538 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 26: Configuration update usercode_2 to 091686
09:30:08.538 [DEBUG] [mmandclass.ZWaveUserCodeCommandClass] - NODE 26: Setting user code for 2
09:30:08.539 [DEBUG] [ommandClassTransactionPayloadBuilder] - At build null
09:30:08.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: Adding to device queue
09:30:08.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: Added to queue - size 0
09:30:08.540 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
09:30:08.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 26: listening == false, frequentlyListening == true, awake == false
09:30:08.541 [DEBUG] [e.internal.protocol.ZWaveTransaction] - >>>>> transaction payload is the same [[99, 1, 2, 1, 48, 57, 49, 54, 56, 54]] == [[99, 1, 2, 1, 48, 57, 49, 54, 56, 54]]
09:30:08.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from sendQueue
09:30:08.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
09:30:08.541 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 1A 0A 63 01 02 01 30 39 31 36 38 36 25 62 CB
09:30:08.542 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 26: Sending REQUEST Message = 01 11 00 13 1A 0A 63 01 02 01 30 39 31 36 38 36 25 62 CB
09:30:08.542 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
09:30:08.542 [DEBUG] [e.internal.protocol.ZWaveTransaction] - transactionStart type SendData
09:30:08.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID:556 [WAIT_RESPONSE] callback: 98
09:30:08.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
09:30:08.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 0
09:30:08.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
09:30:08.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:10 EST 2017 - 2000ms
09:30:08.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID:556 [WAIT_RESPONSE] callback: 98
09:30:08.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:08.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:10 EST 2017 - 2000ms
09:30:08.544 [DEBUG] [mmandclass.ZWaveUserCodeCommandClass] - NODE 26: Creating new message for application command USER_CODE_GET(2)
09:30:08.545 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
09:30:08.547 [DEBUG] [ommandClassTransactionPayloadBuilder] - At build COMMAND_CLASS_USER_CODE
09:30:08.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
09:30:08.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
09:30:08.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: Adding to device queue
09:30:08.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: Added to queue - size 0
09:30:08.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
09:30:08.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
09:30:08.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
09:30:08.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID:556 [WAIT_RESPONSE] callback: 98
09:30:08.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
09:30:08.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:08.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
09:30:08.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
09:30:08.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:10 EST 2017 - 1995ms
09:30:08.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
09:30:08.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:08.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:10 EST 2017 - 1994ms
09:30:08.554 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zwave:device:15a1b48bdc7:node26' has been updated.
09:30:08.557 [INFO ] [marthome.event.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=usercode_2, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]
09:30:08.562 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
09:30:08.565 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
09:30:08.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:08.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:08.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:08.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID:556 [WAIT_RESPONSE] callback: 98
09:30:08.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
09:30:08.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID:556 [WAIT_RESPONSE] callback: 98
09:30:08.566 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:08.567 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 26: Sent Data successfully placed on stack.
09:30:08.567 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance ST: WAIT_RESPONSE
09:30:08.567 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TX: null
09:30:08.568 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance WT: null
09:30:08.568 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance RX: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:08.568 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TO: WAIT_REQUEST
09:30:08.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction 98 advanced to WAIT_REQUEST
09:30:08.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: **** Transaction not completed
09:30:08.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
09:30:08.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
09:30:08.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
09:30:08.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:08.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:13 EST 2017 - 5000ms
09:30:09.797 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 62 00 00 85 0C
09:30:09.799 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
09:30:09.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=SendData[0x13], type=Request[0x00], dest=0, callback=98, payload=62 00 00 85
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=SendData[0x13], type=Request[0x00], dest=0, callback=98, payload=62 00 00 85
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=98, payload=62 00 00 85
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID:556 [WAIT_REQUEST] callback: 98
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID:556 [WAIT_REQUEST] callback: 98
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - checking transaction 98 (Callback 98) ......
09:30:09.800 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
09:30:09.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to transaction 98......
09:30:09.800 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=98, payload=62 00 00 85
09:30:09.800 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 26: SendData Request. CallBack ID = 98, Status = Transmission complete and ACK received(0)
09:30:09.801 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 26: resetResendCount initComplete=true isDead=false
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - Transaction 556 COMPLETED
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance ST: DONE
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TX: null
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance WT: null
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance RX: Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=98, payload=62 00 00 85
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TO: DONE
09:30:09.801 [DEBUG] [e.internal.protocol.ZWaveTransaction] - >>>>> transaction payload is the same [[99, 1, 2, 1, 48, 57, 49, 54, 56, 54]] == [[99, 1, 2, 1, 48, 57, 49, 54, 56, 54]]
09:30:09.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: Response processed after 1259ms
09:30:09.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: **** Transaction completed
09:30:09.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: notifyTransactionResponse 556
09:30:09.804 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
09:30:09.804 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 26: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
09:30:09.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
09:30:09.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
09:30:09.804 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 26: listening == false, frequentlyListening == true, awake == false
09:30:09.804 [DEBUG] [e.internal.protocol.ZWaveTransaction] - >>>>> transaction payload is the same [[99, 2, 2]] == [[99, 2, 2]]
09:30:09.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from sendQueue
09:30:09.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
09:30:09.805 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 1A 03 63 02 02 25 63 DA
09:30:09.805 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 26: Sending REQUEST Message = 01 0A 00 13 1A 03 63 02 02 25 63 DA
09:30:09.806 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
09:30:09.806 [DEBUG] [e.internal.protocol.ZWaveTransaction] - transactionStart type SendData
09:30:09.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID:557 [WAIT_RESPONSE] callback: 99
09:30:09.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_USER_CODE
09:30:09.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 3
09:30:09.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
09:30:09.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:09.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:11 EST 2017 - 2000ms
09:30:09.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID:557 [WAIT_RESPONSE] callback: 99
09:30:09.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:09.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:11 EST 2017 - 1999ms
09:30:09.807 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
09:30:09.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
09:30:09.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID:557 [WAIT_RESPONSE] callback: 99
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:09.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:11 EST 2017 - 1998ms
09:30:09.817 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
09:30:09.820 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
09:30:09.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:09.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:09.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:09.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID:557 [WAIT_RESPONSE] callback: 99
09:30:09.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
09:30:09.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID:557 [WAIT_RESPONSE] callback: 99
09:30:09.822 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:09.822 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 26: Sent Data successfully placed on stack.
09:30:09.823 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance ST: WAIT_RESPONSE
09:30:09.823 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TX: null
09:30:09.823 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance WT: ApplicationCommandHandler
09:30:09.823 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance RX: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01
09:30:09.823 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TO: WAIT_REQUEST
09:30:09.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction 99 advanced to WAIT_REQUEST
09:30:09.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: **** Transaction not completed
09:30:09.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
09:30:09.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
09:30:09.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
09:30:09.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
09:30:09.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Wed Feb 22 09:30:14 EST 2017 - 4999ms
09:30:09.834 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 63 00 00 02 8A
09:30:09.834 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=SendData[0x13], type=Request[0x00], dest=0, callback=99, payload=63 00 00 02
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=SendData[0x13], type=Request[0x00], dest=0, callback=99, payload=63 00 00 02
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=99, payload=63 00 00 02
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID:557 [WAIT_REQUEST] callback: 99
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID:557 [WAIT_REQUEST] callback: 99
09:30:09.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - checking transaction 99 (Callback 99) ......
09:30:09.835 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to transaction 99......
09:30:09.835 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=99, payload=63 00 00 02
09:30:09.835 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 26: SendData Request. CallBack ID = 99, Status = Transmission complete and ACK received(0)
09:30:09.835 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 26: resetResendCount initComplete=true isDead=false
09:30:09.835 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance ST: WAIT_REQUEST
09:30:09.835 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TX: null
09:30:09.835 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance WT: ApplicationCommandHandler
09:30:09.835 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance RX: Message: class=SendData[0x13], type=Request[0x00], dest=0, callback=99, payload=63 00 00 02
09:30:09.835 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TransactionAdvance TO: DONE
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction 99 advanced to DONE
09:30:09.835 [DEBUG] [e.internal.protocol.ZWaveTransaction] - >>>>> transaction payload is the same [[99, 2, 2]] == [[99, 2, 2]]
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: Response processed after 29ms
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: **** Transaction completed
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 26: notifyTransactionResponse 557
09:30:09.835 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
09:30:09.835 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 26: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
09:30:09.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
09:30:09.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
09:30:09.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer