Preformatted text
2021-01-17 13:50:13.326 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Command received zwave:device:6b5203e5:node23:switch_dimmer → ON [OnOffType]
2021-01-17 13:50:13.326 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 23: Creating new message for command SWITCH_MULTILEVEL_SET
2021-01-17 13:50:13.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
2021-01-17 13:50:13.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2021-01-17 13:50:13.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Adding to device queue
2021-01-17 13:50:13.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Added 3447 to queue - size 11
2021-01-17 13:50:13.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:13.327 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 17 03 26 01 63 25 B4 27
2021-01-17 13:50:13.327 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 23: Sending REQUEST Message = 01 0A 00 13 17 03 26 01 63 25 B4 27
2021-01-17 13:50:13.328 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-17 13:50:13.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3447: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 180
2021-01-17 13:50:13.328 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling initialised at 60 seconds - start in 1500 milliseconds.
2021-01-17 13:50:13.332 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-17 13:50:13.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:13.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:13.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3447: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 180
2021-01-17 13:50:13.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-17 13:50:13.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:13.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:13.335 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-17 13:50:13.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:13.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:13.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3447: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 180
2021-01-17 13:50:13.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:13.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3447: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 180
2021-01-17 13:50:13.335 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:13.336 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: sentData successfully placed on stack.
2021-01-17 13:50:13.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 3447: Advanced to WAIT_REQUEST
2021-01-17 13:50:13.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3447: Transaction not completed
2021-01-17 13:50:13.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:13.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:13.359 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B4 00 00 02 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 9F
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=180, payload=B4 00 00 02 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=180, payload=B4 00 00 02 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3447: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 180
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3447: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 180
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 3447: (Callback 180)
2021-01-17 13:50:13.360 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-17 13:50:13.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 3447: callback 180
2021-01-17 13:50:13.360 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=180, payload=B4 00 00 02 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:13.360 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: SendData Request. CallBack ID = 180, Status = Transmission complete and ACK received(0)
2021-01-17 13:50:13.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: resetResendCount initComplete=true isDead=false
2021-01-17 13:50:13.361 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 3447: Transaction COMPLETED
2021-01-17 13:50:13.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Response processed after 33ms
2021-01-17 13:50:13.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3447: Transaction completed
2021-01-17 13:50:13.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: notifyTransactionResponse TID:3447 DONE
2021-01-17 13:50:13.361 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-17 13:50:13.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:13.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:14.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling…
2021-01-17 13:50:14.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling zwave:device:6b5203e5:node23:switch_dimmer
2021-01-17 13:50:14.828 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 23: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2021-01-17 13:50:14.829 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 23: Creating new message for command SWITCH_MULTILEVEL_GET
2021-01-17 13:50:14.829 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
2021-01-17 13:50:14.829 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2021-01-17 13:50:14.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling skipped for zwave:device:6b5203e5:node23:switch_dimmer on COMMAND_CLASS_BASIC
2021-01-17 13:50:14.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Adding to device queue
2021-01-17 13:50:14.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Added 3448 to queue - size 11
2021-01-17 13:50:14.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:14.829 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 17 02 26 02 25 B5 44
2021-01-17 13:50:14.829 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 23: Sending REQUEST Message = 01 09 00 13 17 02 26 02 25 B5 44
2021-01-17 13:50:14.830 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-17 13:50:14.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3448: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 181
2021-01-17 13:50:14.831 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-17 13:50:14.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:14.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:14.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3448: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 181
2021-01-17 13:50:14.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-17 13:50:14.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:14.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:14.837 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-17 13:50:14.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:14.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:14.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3448: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 181
2021-01-17 13:50:14.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:14.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3448: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 181
2021-01-17 13:50:14.837 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:14.838 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: sentData successfully placed on stack.
2021-01-17 13:50:14.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 3448: Advanced to WAIT_REQUEST
2021-01-17 13:50:14.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3448: Transaction not completed
2021-01-17 13:50:14.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:14.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:14.891 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B5 00 00 05 00 D7 7F 7F 7F 7F 01 01 03 00 00 00 00 02 02 00 00 90
2021-01-17 13:50:14.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=181, payload=B5 00 00 05 00 D7 7F 7F 7F 7F 01 01 03 00 00 00 00 02 02 00 00
2021-01-17 13:50:14.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=181, payload=B5 00 00 05 00 D7 7F 7F 7F 7F 01 01 03 00 00 00 00 02 02 00 00
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3448: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 181
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3448: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 181
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 3448: (Callback 181)
2021-01-17 13:50:14.892 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 3448: callback 181
2021-01-17 13:50:14.892 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=181, payload=B5 00 00 05 00 D7 7F 7F 7F 7F 01 01 03 00 00 00 00 02 02 00 00
2021-01-17 13:50:14.892 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: SendData Request. CallBack ID = 181, Status = Transmission complete and ACK received(0)
2021-01-17 13:50:14.892 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: resetResendCount initComplete=true isDead=false
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 3448: Advanced to WAIT_DATA
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3448: Transaction not completed
2021-01-17 13:50:14.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:14.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:14.936 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 17 03 26 03 63 D0 00 72
2021-01-17 13:50:14.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=23, callback=0, payload=00 17 03 26 03 63 D0 00
2021-01-17 13:50:14.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=23, callback=0, payload=00 17 03 26 03 63 D0 00
2021-01-17 13:50:14.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-01-17 13:50:14.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Application Command Request (ALIVE:DONE)
2021-01-17 13:50:14.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: resetResendCount initComplete=true isDead=false
2021-01-17 13:50:14.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2021-01-17 13:50:14.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
2021-01-17 13:50:14.937 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 23: Received COMMAND_CLASS_SWITCH_MULTILEVEL V1 SWITCH_MULTILEVEL_REPORT
2021-01-17 13:50:14.937 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 23: Switch Multi Level report, value = 99
2021-01-17 13:50:14.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2021-01-17 13:50:14.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=99
2021-01-17 13:50:14.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Updating channel state zwave:device:6b5203e5:node23:switch_dimmer to 100 [PercentType]
2021-01-17 13:50:14.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Commands processed 1.
2021-01-17 13:50:14.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b27a538.
2021-01-17 13:50:14.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b27a538.
2021-01-17 13:50:14.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: notifyTransactionResponse TID:3448 DONE
2021-01-17 13:50:14.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-17 13:50:14.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-01-17 13:50:14.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-01-17 13:50:14.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:14.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:15.063 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Command received zwave:device:6b5203e5:node23:switch_dimmer → OFF [OnOffType]
2021-01-17 13:50:15.063 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 23: Creating new message for command SWITCH_MULTILEVEL_SET
2021-01-17 13:50:15.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
2021-01-17 13:50:15.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2021-01-17 13:50:15.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Adding to device queue
2021-01-17 13:50:15.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Added 3449 to queue - size 11
2021-01-17 13:50:15.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:15.064 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 17 03 26 01 00 25 B6 46
2021-01-17 13:50:15.064 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 23: Sending REQUEST Message = 01 0A 00 13 17 03 26 01 00 25 B6 46
2021-01-17 13:50:15.064 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-17 13:50:15.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3449: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182
2021-01-17 13:50:15.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling initialised at 60 seconds - start in 1500 milliseconds.
2021-01-17 13:50:15.066 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-17 13:50:15.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:15.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:15.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3449: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182
2021-01-17 13:50:15.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-17 13:50:15.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:15.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:15.072 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-17 13:50:15.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:15.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:15.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3449: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182
2021-01-17 13:50:15.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:15.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3449: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182
2021-01-17 13:50:15.073 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:15.073 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: sentData successfully placed on stack.
2021-01-17 13:50:15.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 3449: Advanced to WAIT_REQUEST
2021-01-17 13:50:15.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3449: Transaction not completed
2021-01-17 13:50:15.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:15.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:15.096 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B6 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 9C
2021-01-17 13:50:15.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=182, payload=B6 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:15.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=182, payload=B6 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3449: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 182
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3449: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 182
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 3449: (Callback 182)
2021-01-17 13:50:15.097 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 3449: callback 182
2021-01-17 13:50:15.097 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=182, payload=B6 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:15.097 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: SendData Request. CallBack ID = 182, Status = Transmission complete and ACK received(0)
2021-01-17 13:50:15.097 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: resetResendCount initComplete=true isDead=false
2021-01-17 13:50:15.097 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 3449: Transaction COMPLETED
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Response processed after 33ms
2021-01-17 13:50:15.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3449: Transaction completed
2021-01-17 13:50:15.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: notifyTransactionResponse TID:3449 DONE
2021-01-17 13:50:15.098 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-17 13:50:15.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:15.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:16.564 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling…
2021-01-17 13:50:16.565 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling zwave:device:6b5203e5:node23:switch_dimmer
2021-01-17 13:50:16.565 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 23: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2021-01-17 13:50:16.565 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 23: Creating new message for command SWITCH_MULTILEVEL_GET
2021-01-17 13:50:16.565 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
2021-01-17 13:50:16.565 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2021-01-17 13:50:16.565 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Polling skipped for zwave:device:6b5203e5:node23:switch_dimmer on COMMAND_CLASS_BASIC
2021-01-17 13:50:16.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Adding to device queue
2021-01-17 13:50:16.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Added 3450 to queue - size 11
2021-01-17 13:50:16.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-17 13:50:16.566 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 17 02 26 02 25 B7 46
2021-01-17 13:50:16.566 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 23: Sending REQUEST Message = 01 09 00 13 17 02 26 02 25 B7 46
2021-01-17 13:50:16.566 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-17 13:50:16.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 3450: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183
2021-01-17 13:50:16.567 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-17 13:50:16.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:16.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-17 13:50:16.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3450: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183
2021-01-17 13:50:16.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-17 13:50:16.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:16.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:16.573 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3450: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3450: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183
2021-01-17 13:50:16.574 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-17 13:50:16.574 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: sentData successfully placed on stack.
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 3450: Advanced to WAIT_REQUEST
2021-01-17 13:50:16.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3450: Transaction not completed
2021-01-17 13:50:16.575 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:16.575 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:16.598 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 B7 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00 9D
2021-01-17 13:50:16.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=183, payload=B7 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:16.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=183, payload=B7 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:16.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 3450: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 183
2021-01-17 13:50:16.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 3450: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 183
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 3450: (Callback 183)
2021-01-17 13:50:16.599 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 3450: callback 183
2021-01-17 13:50:16.599 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=183, payload=B7 00 00 03 00 DD 7F 7F 7F 7F 01 01 03 00 00 00 00 02 01 00 00
2021-01-17 13:50:16.599 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 23: SendData Request. CallBack ID = 183, Status = Transmission complete and ACK received(0)
2021-01-17 13:50:16.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: resetResendCount initComplete=true isDead=false
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 3450: Advanced to WAIT_DATA
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: TID 3450: Transaction not completed
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:16.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-17 13:50:16.638 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 17 03 26 03 31 7E 00 8E
2021-01-17 13:50:16.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=23, callback=0, payload=00 17 03 26 03 31 7E 00
2021-01-17 13:50:16.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=23, callback=0, payload=00 17 03 26 03 31 7E 00
2021-01-17 13:50:16.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-01-17 13:50:16.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Application Command Request (ALIVE:DONE)
2021-01-17 13:50:16.639 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: resetResendCount initComplete=true isDead=false
2021-01-17 13:50:16.639 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2021-01-17 13:50:16.639 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
2021-01-17 13:50:16.639 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 23: Received COMMAND_CLASS_SWITCH_MULTILEVEL V1 SWITCH_MULTILEVEL_REPORT
2021-01-17 13:50:16.639 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 23: Switch Multi Level report, value = 49
2021-01-17 13:50:16.639 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2021-01-17 13:50:16.639 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=49
2021-01-17 13:50:16.640 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Updating channel state zwave:device:6b5203e5:node23:switch_dimmer to 49 [PercentType]
2021-01-17 13:50:16.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Commands processed 1.
2021-01-17 13:50:16.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@33b49b6d.
2021-01-17 13:50:16.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@33b49b6d.
2021-01-17 13:50:16.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 23: notifyTransactionResponse TID:3450 DONE
2021-01-17 13:50:16.641 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 23: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-17 13:50:16.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-01-17 13:50:16.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-01-17 13:50:16.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-17 13:50:16.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.