2019-10-28 22:03:00.670 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling... 2019-10-28 22:03:00.670 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:400e534b:node7:switch_dimmer 2019-10-28 22:03:00.670 [DEBUG] [.internal.converter.ZWaveMultiLevelSwitchConverter] - NODE 7: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2019-10-28 22:03:00.671 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 7: Creating new message for command SWITCH_MULTILEVEL_GET 2019-10-28 22:03:00.671 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY not supported 2019-10-28 22:03:00.671 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 7: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2019-10-28 22:03:00.671 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling skipped for zwave:device:400e534b:node7:switch_dimmer on COMMAND_CLASS_BASIC 2019-10-28 22:03:00.671 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue 2019-10-28 22:03:00.671 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Added 2376 to queue - size 11 2019-10-28 22:03:00.671 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:00.672 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 26 02 25 23 C2 2019-10-28 22:03:00.672 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 26 02 25 23 C2 2019-10-28 22:03:00.673 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:00.673 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2376: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 35 2019-10-28 22:03:00.673 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2376: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 35 2019-10-28 22:03:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.680 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:00.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:00.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:00.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2376: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 35 2019-10-28 22:03:00.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:00.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2376: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 35 2019-10-28 22:03:00.681 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:00.682 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 7: sentData successfully placed on stack. 2019-10-28 22:03:00.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2376: Advanced to WAIT_REQUEST 2019-10-28 22:03:00.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: TID 2376: Transaction not completed 2019-10-28 22:03:00.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.696 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 23 00 00 03 CB 2019-10-28 22:03:00.697 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=35, payload=23 00 00 03 2019-10-28 22:03:00.697 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=35, payload=23 00 00 03 2019-10-28 22:03:00.697 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2376: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 35 2019-10-28 22:03:00.697 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:00.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2376: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 35 2019-10-28 22:03:00.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2376: (Callback 35) 2019-10-28 22:03:00.698 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:00.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2376: callback 35 2019-10-28 22:03:00.698 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=35, payload=23 00 00 03 2019-10-28 22:03:00.698 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 7: SendData Request. CallBack ID = 35, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:00.698 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false 2019-10-28 22:03:00.699 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2376: Advanced to WAIT_DATA 2019-10-28 22:03:00.699 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling... 2019-10-28 22:03:00.699 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: TID 2376: Transaction not completed 2019-10-28 22:03:00.699 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling zwave:device:400e534b:node5:switch_dimmer 2019-10-28 22:03:00.699 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.699 [DEBUG] [.internal.converter.ZWaveMultiLevelSwitchConverter] - NODE 5: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2019-10-28 22:03:00.699 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.699 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Creating new message for command SWITCH_MULTILEVEL_GET 2019-10-28 22:03:00.699 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported 2019-10-28 22:03:00.699 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2019-10-28 22:03:00.699 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling skipped for zwave:device:400e534b:node5:switch_dimmer on COMMAND_CLASS_BASIC 2019-10-28 22:03:00.700 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 2019-10-28 22:03:00.700 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Added 2377 to queue - size 11 2019-10-28 22:03:00.700 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.708 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 07 05 26 03 63 63 00 D7 2019-10-28 22:03:00.709 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 05 26 03 63 63 00 2019-10-28 22:03:00.709 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 05 26 03 63 63 00 2019-10-28 22:03:00.709 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:00.709 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE) 2019-10-28 22:03:00.710 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false 2019-10-28 22:03:00.710 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2019-10-28 22:03:00.710 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY not supported 2019-10-28 22:03:00.710 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 2019-10-28 22:03:00.710 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 7: Switch Multi Level report, value = 99 2019-10-28 22:03:00.710 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-10-28 22:03:00.710 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 99 2019-10-28 22:03:00.711 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:400e534b:node7:switch_dimmer to 100 [PercentType] 2019-10-28 22:03:00.711 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1. 2019-10-28 22:03:00.711 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11cc3ade. 2019-10-28 22:03:00.711 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11cc3ade. 2019-10-28 22:03:00.712 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:2376 DONE 2019-10-28 22:03:00.712 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:00.712 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-10-28 22:03:00.712 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:00.713 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.713 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:00.713 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 05 02 26 02 25 24 C7 2019-10-28 22:03:00.713 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 09 00 13 05 02 26 02 25 24 C7 2019-10-28 22:03:00.714 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:00.714 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2377: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 36 2019-10-28 22:03:00.715 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:00.715 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:00.715 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:00.715 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2377: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 36 2019-10-28 22:03:00.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:00.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.716 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.722 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:00.722 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:00.723 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:00.723 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2377: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 36 2019-10-28 22:03:00.723 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:00.723 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2377: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 36 2019-10-28 22:03:00.723 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:00.724 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 2019-10-28 22:03:00.724 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2377: Advanced to WAIT_REQUEST 2019-10-28 22:03:00.724 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 2377: Transaction not completed 2019-10-28 22:03:00.724 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.724 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.738 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 24 00 00 02 CD 2019-10-28 22:03:00.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=36, payload=24 00 00 02 2019-10-28 22:03:00.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=36, payload=24 00 00 02 2019-10-28 22:03:00.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2377: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 36 2019-10-28 22:03:00.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:00.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2377: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 36 2019-10-28 22:03:00.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2377: (Callback 36) 2019-10-28 22:03:00.739 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:00.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2377: callback 36 2019-10-28 22:03:00.739 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=36, payload=24 00 00 02 2019-10-28 22:03:00.739 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 36, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:00.739 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2019-10-28 22:03:00.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2377: Advanced to WAIT_DATA 2019-10-28 22:03:00.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: TID 2377: Transaction not completed 2019-10-28 22:03:00.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:00.750 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 26 03 63 63 00 D5 2019-10-28 22:03:00.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 26 03 63 63 00 2019-10-28 22:03:00.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 26 03 63 63 00 2019-10-28 22:03:00.751 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:00.751 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 2019-10-28 22:03:00.751 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 2019-10-28 22:03:00.751 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2019-10-28 22:03:00.751 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY not supported 2019-10-28 22:03:00.751 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 2019-10-28 22:03:00.751 [DEBUG] [col.commandclass.ZWaveMultiLevelSwitchCommandClass] - NODE 5: Switch Multi Level report, value = 99 2019-10-28 22:03:00.751 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-10-28 22:03:00.752 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 99 2019-10-28 22:03:00.752 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:400e534b:node5:switch_dimmer to 100 [PercentType] 2019-10-28 22:03:00.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 2019-10-28 22:03:00.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6ebf4be5. 2019-10-28 22:03:00.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6ebf4be5. 2019-10-28 22:03:00.752 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:2377 DONE 2019-10-28 22:03:00.753 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:00.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-10-28 22:03:00.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:00.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:00.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:42.780 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0C 06 31 05 03 0A 00 00 C0 2019-10-28 22:03:42.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 06 31 05 03 0A 00 00 2019-10-28 22:03:42.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 06 31 05 03 0A 00 00 2019-10-28 22:03:42.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:42.781 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:42.781 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-10-28 22:03:42.781 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-10-28 22:03:42.782 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SENSOR_MULTILEVEL V7 SENSOR_MULTILEVEL_REPORT 2019-10-28 22:03:42.782 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 12: Sensor Type = Luminance(3), Scale = 1 2019-10-28 22:03:42.782 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 12: Sensor Value = 0E+1 2019-10-28 22:03:42.782 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-10-28 22:03:42.782 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 0E+1 2019-10-28 22:03:42.783 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:400e534b:node12:sensor_luminance to 0E+1 % [QuantityType] 2019-10-28 22:03:42.783 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:42.783 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6ced658d. 2019-10-28 22:03:42.784 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:42.784 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:42.784 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:42.784 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:42.810 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0C 06 31 05 01 2A 02 A9 49 2019-10-28 22:03:42.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 06 31 05 01 2A 02 A9 2019-10-28 22:03:42.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 06 31 05 01 2A 02 A9 2019-10-28 22:03:42.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:42.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:42.812 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-10-28 22:03:42.812 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL 2019-10-28 22:03:42.812 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SENSOR_MULTILEVEL V7 SENSOR_MULTILEVEL_REPORT 2019-10-28 22:03:42.813 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 12: Sensor Type = Temperature(1), Scale = 1 2019-10-28 22:03:42.813 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 12: Sensor Value = 68.1 2019-10-28 22:03:42.814 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-10-28 22:03:42.814 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SENSOR_MULTILEVEL, value = 68.1 2019-10-28 22:03:42.814 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:400e534b:node12:sensor_temperature to 68.1 °F [QuantityType] 2019-10-28 22:03:42.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:42.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6846f686. 2019-10-28 22:03:42.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:42.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:42.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:42.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:42.835 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 0C 03 80 03 57 29 2019-10-28 22:03:42.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 03 80 03 57 2019-10-28 22:03:42.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 03 80 03 57 2019-10-28 22:03:42.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:42.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:42.836 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0 2019-10-28 22:03:42.836 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_BATTERY 2019-10-28 22:03:42.836 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT 2019-10-28 22:03:42.837 [DEBUG] [nal.protocol.commandclass.ZWaveBatteryCommandClass] - NODE 12: Battery report value = 87 2019-10-28 22:03:42.837 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-10-28 22:03:42.837 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_BATTERY, value = 87 2019-10-28 22:03:42.837 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:400e534b:node12:battery-level to 87 [DecimalType] 2019-10-28 22:03:42.837 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:42.837 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18ac2b9a. 2019-10-28 22:03:42.838 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:42.838 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:42.838 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:42.838 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.069 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0C 02 84 07 7E 2019-10-28 22:03:43.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 02 84 07 2019-10-28 22:03:43.071 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 02 84 07 2019-10-28 22:03:43.071 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:43.071 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:43.071 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Is awake with 10 messages in the queue 2019-10-28 22:03:43.071 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Start sleep timer at 2500ms 2019-10-28 22:03:43.071 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-10-28 22:03:43.074 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 12: Node Status event - Node is AWAKE 2019-10-28 22:03:43.074 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:43.074 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@cd8bb09. 2019-10-28 22:03:43.075 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:43.075 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:43.075 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.075 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.075 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 85 04 01 25 25 69 2019-10-28 22:03:43.076 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 85 04 01 25 25 69 2019-10-28 22:03:43.076 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.076 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2165: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 37 2019-10-28 22:03:43.077 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2165: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 37 2019-10-28 22:03:43.078 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.078 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.078 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.084 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.084 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2165: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 37 2019-10-28 22:03:43.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2165: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 37 2019-10-28 22:03:43.085 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.085 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2165: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2165: Transaction not completed 2019-10-28 22:03:43.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.100 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 25 00 00 02 CC 2019-10-28 22:03:43.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=37, payload=25 00 00 02 2019-10-28 22:03:43.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=37, payload=25 00 00 02 2019-10-28 22:03:43.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2165: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 37 2019-10-28 22:03:43.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2165: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 37 2019-10-28 22:03:43.101 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2165: (Callback 37) 2019-10-28 22:03:43.102 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2165: callback 37 2019-10-28 22:03:43.102 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=37, payload=25 00 00 02 2019-10-28 22:03:43.102 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 37, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.102 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 2165: Transaction COMPLETED 2019-10-28 22:03:43.102 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 26ms 2019-10-28 22:03:43.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2165: Transaction completed 2019-10-28 22:03:43.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2165 DONE 2019-10-28 22:03:43.103 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.104 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 0C 05 70 04 03 01 04 25 26 98 2019-10-28 22:03:43.104 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0C 00 13 0C 05 70 04 03 01 04 25 26 98 2019-10-28 22:03:43.105 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2171: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 38 2019-10-28 22:03:43.106 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2171: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 38 2019-10-28 22:03:43.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.112 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2171: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 38 2019-10-28 22:03:43.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2171: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 38 2019-10-28 22:03:43.114 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.114 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2171: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2171: Transaction not completed 2019-10-28 22:03:43.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.130 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 26 00 00 02 CF 2019-10-28 22:03:43.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=38, payload=26 00 00 02 2019-10-28 22:03:43.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=38, payload=26 00 00 02 2019-10-28 22:03:43.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2171: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 38 2019-10-28 22:03:43.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2171: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 38 2019-10-28 22:03:43.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2171: (Callback 38) 2019-10-28 22:03:43.132 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2171: callback 38 2019-10-28 22:03:43.132 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=38, payload=26 00 00 02 2019-10-28 22:03:43.132 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 38, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.132 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 2171: Transaction COMPLETED 2019-10-28 22:03:43.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 27ms 2019-10-28 22:03:43.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2171: Transaction completed 2019-10-28 22:03:43.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2171 DONE 2019-10-28 22:03:43.133 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.133 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 70 05 03 25 27 9D 2019-10-28 22:03:43.134 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 70 05 03 25 27 9D 2019-10-28 22:03:43.134 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 39 2019-10-28 22:03:43.135 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 39 2019-10-28 22:03:43.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.142 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 39 2019-10-28 22:03:43.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 39 2019-10-28 22:03:43.143 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.143 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2172: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2172: Transaction not completed 2019-10-28 22:03:43.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.158 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 27 00 00 03 CF 2019-10-28 22:03:43.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=39, payload=27 00 00 03 2019-10-28 22:03:43.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=39, payload=27 00 00 03 2019-10-28 22:03:43.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2172: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 39 2019-10-28 22:03:43.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.159 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2172: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 39 2019-10-28 22:03:43.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2172: (Callback 39) 2019-10-28 22:03:43.160 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2172: callback 39 2019-10-28 22:03:43.160 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=39, payload=27 00 00 03 2019-10-28 22:03:43.160 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 39, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2172: Advanced to WAIT_DATA 2019-10-28 22:03:43.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2172: Transaction not completed 2019-10-28 22:03:43.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.174 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 0C 05 70 06 03 01 04 89 2019-10-28 22:03:43.175 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 70 06 03 01 04 2019-10-28 22:03:43.175 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 70 06 03 01 04 2019-10-28 22:03:43.176 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:43.176 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:43.176 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_CONFIGURATION, endpoint 0 2019-10-28 22:03:43.176 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_CONFIGURATION 2019-10-28 22:03:43.176 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_CONFIGURATION V0 CONFIGURATIONCMD_REPORT 2019-10-28 22:03:43.176 [DEBUG] [otocol.commandclass.ZWaveConfigurationCommandClass] - NODE 12: Node configuration report, parameter = 3, value = 4, size = 1 2019-10-28 22:03:43.177 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent 2019-10-28 22:03:43.177 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@b1e7612 2019-10-28 22:03:43.177 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Update CONFIGURATION 3/1 to 4 2019-10-28 22:03:43.177 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:43.178 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@54b34331. 2019-10-28 22:03:43.178 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@54b34331. 2019-10-28 22:03:43.178 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2172 DONE 2019-10-28 22:03:43.178 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.178 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-10-28 22:03:43.179 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:43.179 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.179 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.179 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 0C 04 85 04 01 01 25 28 63 2019-10-28 22:03:43.179 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0B 00 13 0C 04 85 04 01 01 25 28 63 2019-10-28 22:03:43.180 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.180 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 40 2019-10-28 22:03:43.181 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.181 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.181 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.181 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 40 2019-10-28 22:03:43.181 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.182 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.182 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.187 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 40 2019-10-28 22:03:43.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2191: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 40 2019-10-28 22:03:43.189 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.189 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2191: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2191: Transaction not completed 2019-10-28 22:03:43.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.203 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 28 00 00 02 C1 2019-10-28 22:03:43.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=40, payload=28 00 00 02 2019-10-28 22:03:43.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=40, payload=28 00 00 02 2019-10-28 22:03:43.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2191: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 40 2019-10-28 22:03:43.204 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.205 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2191: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 40 2019-10-28 22:03:43.205 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2191: (Callback 40) 2019-10-28 22:03:43.205 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.205 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2191: callback 40 2019-10-28 22:03:43.205 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=40, payload=28 00 00 02 2019-10-28 22:03:43.205 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 40, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.205 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 2191: Transaction COMPLETED 2019-10-28 22:03:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 26ms 2019-10-28 22:03:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2191: Transaction completed 2019-10-28 22:03:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2191 DONE 2019-10-28 22:03:43.206 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.207 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 0C 04 85 01 01 01 25 29 67 2019-10-28 22:03:43.207 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0B 00 13 0C 04 85 01 01 01 25 29 67 2019-10-28 22:03:43.208 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.208 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 41 2019-10-28 22:03:43.209 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.209 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.209 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.209 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 41 2019-10-28 22:03:43.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.216 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 41 2019-10-28 22:03:43.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2192: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 41 2019-10-28 22:03:43.217 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.217 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2192: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2192: Transaction not completed 2019-10-28 22:03:43.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.233 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 29 00 00 02 C0 2019-10-28 22:03:43.233 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=41, payload=29 00 00 02 2019-10-28 22:03:43.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=41, payload=29 00 00 02 2019-10-28 22:03:43.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2192: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 41 2019-10-28 22:03:43.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2192: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 41 2019-10-28 22:03:43.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2192: (Callback 41) 2019-10-28 22:03:43.234 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2192: callback 41 2019-10-28 22:03:43.235 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=41, payload=29 00 00 02 2019-10-28 22:03:43.235 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 41, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.235 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 2192: Transaction COMPLETED 2019-10-28 22:03:43.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 27ms 2019-10-28 22:03:43.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2192: Transaction completed 2019-10-28 22:03:43.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2192 DONE 2019-10-28 22:03:43.236 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.236 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 85 02 01 25 2A 60 2019-10-28 22:03:43.236 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 85 02 01 25 2A 60 2019-10-28 22:03:43.237 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2193: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 42 2019-10-28 22:03:43.238 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2193: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 42 2019-10-28 22:03:43.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.244 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2193: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 42 2019-10-28 22:03:43.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2193: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 42 2019-10-28 22:03:43.246 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.246 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2193: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2193: Transaction not completed 2019-10-28 22:03:43.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.260 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2A 00 00 02 C3 2019-10-28 22:03:43.261 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=42, payload=2A 00 00 02 2019-10-28 22:03:43.261 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=42, payload=2A 00 00 02 2019-10-28 22:03:43.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2193: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 42 2019-10-28 22:03:43.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2193: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 42 2019-10-28 22:03:43.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2193: (Callback 42) 2019-10-28 22:03:43.262 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2193: callback 42 2019-10-28 22:03:43.262 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=42, payload=2A 00 00 02 2019-10-28 22:03:43.262 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 42, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.263 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2193: Advanced to WAIT_DATA 2019-10-28 22:03:43.263 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2193: Transaction not completed 2019-10-28 22:03:43.263 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.263 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.277 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0C 06 85 03 01 05 00 01 7E 2019-10-28 22:03:43.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 06 85 03 01 05 00 01 2019-10-28 22:03:43.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 06 85 03 01 05 00 01 2019-10-28 22:03:43.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:43.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:43.278 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 2019-10-28 22:03:43.279 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 2019-10-28 22:03:43.279 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 2019-10-28 22:03:43.279 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - NODE 12: association group 1 has max associations 5 2019-10-28 22:03:43.279 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - NODE 12: association group 1 includes the following nodes: 2019-10-28 22:03:43.279 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - Node 1 2019-10-28 22:03:43.279 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAssociationEvent 2019-10-28 22:03:43.279 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 2019-10-28 22:03:43.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:43.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2dba42c6. 2019-10-28 22:03:43.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2dba42c6. 2019-10-28 22:03:43.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2193 DONE 2019-10-28 22:03:43.281 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.281 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-10-28 22:03:43.281 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:43.281 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.281 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.282 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 85 04 03 25 2B 65 2019-10-28 22:03:43.282 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 85 04 03 25 2B 65 2019-10-28 22:03:43.282 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.282 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2194: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 43 2019-10-28 22:03:43.283 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.283 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2194: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 43 2019-10-28 22:03:43.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.290 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2194: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 43 2019-10-28 22:03:43.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2194: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 43 2019-10-28 22:03:43.291 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.292 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2194: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2194: Transaction not completed 2019-10-28 22:03:43.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.306 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2B 00 00 02 C2 2019-10-28 22:03:43.306 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=43, payload=2B 00 00 02 2019-10-28 22:03:43.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=43, payload=2B 00 00 02 2019-10-28 22:03:43.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2194: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 43 2019-10-28 22:03:43.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2194: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 43 2019-10-28 22:03:43.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2194: (Callback 43) 2019-10-28 22:03:43.307 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.307 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2194: callback 43 2019-10-28 22:03:43.308 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=43, payload=2B 00 00 02 2019-10-28 22:03:43.308 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 43, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.308 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 2194: Transaction COMPLETED 2019-10-28 22:03:43.308 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 26ms 2019-10-28 22:03:43.308 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2194: Transaction completed 2019-10-28 22:03:43.308 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2194 DONE 2019-10-28 22:03:43.309 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.309 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.309 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.309 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 85 02 03 25 2C 64 2019-10-28 22:03:43.309 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 85 02 03 25 2C 64 2019-10-28 22:03:43.310 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.310 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2195: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 44 2019-10-28 22:03:43.311 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2195: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 44 2019-10-28 22:03:43.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.317 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2195: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 44 2019-10-28 22:03:43.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.318 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2195: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 44 2019-10-28 22:03:43.319 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.319 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2195: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2195: Transaction not completed 2019-10-28 22:03:43.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.334 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2C 00 00 02 C5 2019-10-28 22:03:43.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=44, payload=2C 00 00 02 2019-10-28 22:03:43.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=44, payload=2C 00 00 02 2019-10-28 22:03:43.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2195: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 44 2019-10-28 22:03:43.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2195: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 44 2019-10-28 22:03:43.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2195: (Callback 44) 2019-10-28 22:03:43.336 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2195: callback 44 2019-10-28 22:03:43.336 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=44, payload=2C 00 00 02 2019-10-28 22:03:43.336 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 44, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2195: Advanced to WAIT_DATA 2019-10-28 22:03:43.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2195: Transaction not completed 2019-10-28 22:03:43.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.351 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 0C 05 85 03 03 05 00 79 2019-10-28 22:03:43.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 85 03 03 05 00 2019-10-28 22:03:43.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 85 03 03 05 00 2019-10-28 22:03:43.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:43.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:43.352 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 2019-10-28 22:03:43.353 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 2019-10-28 22:03:43.353 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 2019-10-28 22:03:43.353 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - NODE 12: association group 3 has max associations 5 2019-10-28 22:03:43.353 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - NODE 12: association group 3 includes the following nodes: 2019-10-28 22:03:43.353 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAssociationEvent 2019-10-28 22:03:43.353 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 2019-10-28 22:03:43.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:43.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6473a48b. 2019-10-28 22:03:43.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6473a48b. 2019-10-28 22:03:43.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2195 DONE 2019-10-28 22:03:43.354 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-10-28 22:03:43.354 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:43.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.355 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 85 04 02 25 2D 62 2019-10-28 22:03:43.355 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 85 04 02 25 2D 62 2019-10-28 22:03:43.356 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45 2019-10-28 22:03:43.357 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45 2019-10-28 22:03:43.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.357 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.363 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45 2019-10-28 22:03:43.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 45 2019-10-28 22:03:43.364 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.365 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2196: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2196: Transaction not completed 2019-10-28 22:03:43.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.379 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2D 00 00 02 C4 2019-10-28 22:03:43.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=45, payload=2D 00 00 02 2019-10-28 22:03:43.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=45, payload=2D 00 00 02 2019-10-28 22:03:43.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2196: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 45 2019-10-28 22:03:43.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2196: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 45 2019-10-28 22:03:43.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2196: (Callback 45) 2019-10-28 22:03:43.381 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2196: callback 45 2019-10-28 22:03:43.381 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=45, payload=2D 00 00 02 2019-10-28 22:03:43.381 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 45, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.381 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 2196: Transaction COMPLETED 2019-10-28 22:03:43.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 26ms 2019-10-28 22:03:43.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2196: Transaction completed 2019-10-28 22:03:43.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2196 DONE 2019-10-28 22:03:43.382 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-10-28 22:03:43.383 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 85 02 02 25 2E 67 2019-10-28 22:03:43.383 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 85 02 02 25 2E 67 2019-10-28 22:03:43.384 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-10-28 22:03:43.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2197: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 46 2019-10-28 22:03:43.385 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-10-28 22:03:43.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-10-28 22:03:43.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2197: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 46 2019-10-28 22:03:43.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-10-28 22:03:43.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.386 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.391 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-10-28 22:03:43.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2197: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 46 2019-10-28 22:03:43.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.392 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2197: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 46 2019-10-28 22:03:43.393 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-10-28 22:03:43.393 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2019-10-28 22:03:43.393 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2197: Advanced to WAIT_REQUEST 2019-10-28 22:03:43.393 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2197: Transaction not completed 2019-10-28 22:03:43.393 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.393 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.407 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2E 00 00 03 C6 2019-10-28 22:03:43.408 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=46, payload=2E 00 00 03 2019-10-28 22:03:43.408 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=46, payload=2E 00 00 03 2019-10-28 22:03:43.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 2197: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 46 2019-10-28 22:03:43.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-10-28 22:03:43.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 2197: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 46 2019-10-28 22:03:43.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 2197: (Callback 46) 2019-10-28 22:03:43.409 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-10-28 22:03:43.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 2197: callback 46 2019-10-28 22:03:43.409 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=46, payload=2E 00 00 03 2019-10-28 22:03:43.409 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 46, Status = Transmission complete and ACK received(0) 2019-10-28 22:03:43.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 2197: Advanced to WAIT_DATA 2019-10-28 22:03:43.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: TID 2197: Transaction not completed 2019-10-28 22:03:43.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-10-28 22:03:43.424 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 0C 05 85 03 02 05 00 78 2019-10-28 22:03:43.424 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 85 03 02 05 00 2019-10-28 22:03:43.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 05 85 03 02 05 00 2019-10-28 22:03:43.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-10-28 22:03:43.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:GET_CONFIGURATION) 2019-10-28 22:03:43.425 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 2019-10-28 22:03:43.425 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 2019-10-28 22:03:43.425 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 2019-10-28 22:03:43.426 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - NODE 12: association group 2 has max associations 5 2019-10-28 22:03:43.426 [DEBUG] [protocol.commandclass.ZWaveAssociationCommandClass] - NODE 12: association group 2 includes the following nodes: 2019-10-28 22:03:43.426 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAssociationEvent 2019-10-28 22:03:43.426 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 2019-10-28 22:03:43.426 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2019-10-28 22:03:43.426 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c25e0f1. 2019-10-28 22:03:43.426 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c25e0f1. 2019-10-28 22:03:43.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:2197 DONE 2019-10-28 22:03:43.427 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-10-28 22:03:43.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-10-28 22:03:43.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-10-28 22:03:43.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-10-28 22:03:43.428 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.