2020-06-10 17:15:20.426 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 08 00 13 0D 01 00 25 C7 0A 2020-06-10 17:15:20.431 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:20.432 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.435 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:20.450 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:20.451 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32337: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 199 2020-06-10 17:15:20.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32337: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 199 2020-06-10 17:15:20.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:20.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32337: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 199 2020-06-10 17:15:20.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.484 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 C7 00 00 01 00 C6 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F5 2020-06-10 17:15:20.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32337: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 199 2020-06-10 17:15:20.487 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.488 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:20.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32337: Advanced to WAIT_REQUEST 2020-06-10 17:15:20.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32337: Transaction not completed 2020-06-10 17:15:20.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.493 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=199, payload=C7 00 00 01 00 C6 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=199, payload=C7 00 00 01 00 C6 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.512 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32337: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 199 2020-06-10 17:15:20.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32337: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 199 2020-06-10 17:15:20.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32337: (Callback 199) 2020-06-10 17:15:20.516 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:20.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32337: callback 199 2020-06-10 17:15:20.519 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=199, payload=C7 00 00 01 00 C6 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.520 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 199, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:20.521 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 32337: Transaction COMPLETED 2020-06-10 17:15:20.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Response processed after 72ms 2020-06-10 17:15:20.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32337: Transaction completed 2020-06-10 17:15:20.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32337 DONE 2020-06-10 17:15:20.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:20.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32337: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:20.526 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1b7ea73 2020-06-10 17:15:20.526 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:20.527 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to REQUEST_NIF 2020-06-10 17:15:20.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:20.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@5d9141 2020-06-10 17:15:20.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.531 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:20.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32338 to queue - size 28 2020-06-10 17:15:20.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:20.535 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 0D 96 2020-06-10 17:15:20.535 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 0D 96 2020-06-10 17:15:20.538 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:20.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.542 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 2020-06-10 17:15:20.570 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:20.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32338: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.575 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32338: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:20.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.602 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 49 84 0D 0A 04 31 01 5E 86 72 98 56 EF 5A C0 2020-06-10 17:15:20.603 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32338: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.604 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.604 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32338: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.607 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.608 [DEBUG] [lmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack. 2020-06-10 17:15:20.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32338: Advanced to WAIT_DATA 2020-06-10 17:15:20.610 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32338: Transaction not completed 2020-06-10 17:15:20.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.612 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=13, callback=132, payload=84 0D 0A 04 31 01 5E 86 72 98 56 EF 5A 2020-06-10 17:15:20.631 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=13, callback=132, payload=84 0D 0A 04 31 01 5E 86 72 98 56 EF 5A 2020-06-10 17:15:20.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32338: [WAIT_DATA] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32338: [WAIT_DATA] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32338: (Callback 0) 2020-06-10 17:15:20.635 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32338: callback 0 2020-06-10 17:15:20.638 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=13, callback=132, payload=84 0D 0A 04 31 01 5E 86 72 98 56 EF 5A 2020-06-10 17:15:20.639 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update request. Node information received. Transaction TID 32338: [WAIT_DATA] priority=Controller, requiresResponse=true, callback: 0 2020-06-10 17:15:20.640 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:20.642 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created 2020-06-10 17:15:20.643 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO. 2020-06-10 17:15:20.644 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes. 2020-06-10 17:15:20.645 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Creating new instance of command class COMMAND_CLASS_VERSION 2020-06-10 17:15:20.650 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Command class COMMAND_CLASS_VERSION, endpoint 0 created 2020-06-10 17:15:20.651 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update is adding command class COMMAND_CLASS_VERSION. 2020-06-10 17:15:20.652 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes. 2020-06-10 17:15:20.653 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:20.655 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created 2020-06-10 17:15:20.656 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC. 2020-06-10 17:15:20.657 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes. 2020-06-10 17:15:20.658 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Creating new instance of command class COMMAND_CLASS_SECURITY 2020-06-10 17:15:20.662 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Command class COMMAND_CLASS_SECURITY, endpoint 0 created 2020-06-10 17:15:20.664 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update is adding command class COMMAND_CLASS_SECURITY. 2020-06-10 17:15:20.664 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Adding command class COMMAND_CLASS_SECURITY to the list of supported command classes. 2020-06-10 17:15:20.665 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Creating new instance of command class COMMAND_CLASS_CRC_16_ENCAP 2020-06-10 17:15:20.667 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Command class COMMAND_CLASS_CRC_16_ENCAP, endpoint 0 created 2020-06-10 17:15:20.667 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update is adding command class COMMAND_CLASS_CRC_16_ENCAP. 2020-06-10 17:15:20.668 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Adding command class COMMAND_CLASS_CRC_16_ENCAP to the list of supported command classes. 2020-06-10 17:15:20.669 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2020-06-10 17:15:20.670 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created 2020-06-10 17:15:20.671 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 13: Application update is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY. 2020-06-10 17:15:20.672 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes. 2020-06-10 17:15:20.673 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 32338: Transaction COMPLETED 2020-06-10 17:15:20.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Response processed after 103ms 2020-06-10 17:15:20.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32338: Transaction completed 2020-06-10 17:15:20.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32338 DONE 2020-06-10 17:15:20.676 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:20.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32338: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:20.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.677 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@14ecc6f 2020-06-10 17:15:20.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:20.678 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:20.679 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to SECURITY_REPORT 2020-06-10 17:15:20.680 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:20.682 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 13: Updated networkKey 2020-06-10 17:15:20.684 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 13: setupNetworkKey useSchemeZero=false 2020-06-10 17:15:20.686 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Skipping secure inclusion 2020-06-10 17:15:20.687 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to MANUFACTURER 2020-06-10 17:15:20.687 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:20.689 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: MANUFACTURER - send ManufacturerSpecific 2020-06-10 17:15:20.689 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 13: Creating new message for command MANUFACTURER_SPECIFIC_GET 2020-06-10 17:15:20.690 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:20.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:20.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2020-06-10 17:15:20.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@16116ab 2020-06-10 17:15:20.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:20.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32339 to queue - size 28 2020-06-10 17:15:20.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:20.696 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0D 02 72 04 25 C8 71 2020-06-10 17:15:20.697 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 09 00 13 0D 02 72 04 25 C8 71 2020-06-10 17:15:20.704 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:20.706 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:20.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:20.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.714 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:20.720 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:20.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32339: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 200 2020-06-10 17:15:20.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.752 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 C8 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F8 2020-06-10 17:15:20.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32339: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 200 2020-06-10 17:15:20.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32339: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 200 2020-06-10 17:15:20.775 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.776 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:20.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32339: Advanced to WAIT_REQUEST 2020-06-10 17:15:20.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32339: Transaction not completed 2020-06-10 17:15:20.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=200, payload=C8 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.782 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 0D 08 72 05 00 86 00 02 00 5F C4 00 00 87 2020-06-10 17:15:20.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=200, payload=C8 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32339: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 200 2020-06-10 17:15:20.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32339: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 200 2020-06-10 17:15:20.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32339: (Callback 200) 2020-06-10 17:15:20.790 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:20.792 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32339: callback 200 2020-06-10 17:15:20.793 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=200, payload=C8 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.794 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 200, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:20.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32339: Advanced to WAIT_DATA 2020-06-10 17:15:20.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32339: Transaction not completed 2020-06-10 17:15:20.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 08 72 05 00 86 00 02 00 5F C4 00 00 2020-06-10 17:15:20.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 08 72 05 00 86 00 02 00 5F C4 00 00 2020-06-10 17:15:20.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:20.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:MANUFACTURER) 2020-06-10 17:15:20.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 2020-06-10 17:15:20.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:20.816 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_MANUFACTURER_SPECIFIC V0 MANUFACTURER_SPECIFIC_REPORT 2020-06-10 17:15:20.817 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 13: Manufacturer ID = 0x86 2020-06-10 17:15:20.818 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 13: Device Type = 0x2 2020-06-10 17:15:20.819 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 13: Device ID = 0x5f 2020-06-10 17:15:20.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:20.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@96d9a2. 2020-06-10 17:15:20.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@96d9a2. 2020-06-10 17:15:20.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32339 DONE 2020-06-10 17:15:20.824 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:20.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32339: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:20.825 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1559786 2020-06-10 17:15:20.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:20.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:20.826 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:20.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.827 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to APP_VERSION 2020-06-10 17:15:20.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:20.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:20.830 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: APP_VERSION - send VersionMessage 2020-06-10 17:15:20.830 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for command VERSION_GET 2020-06-10 17:15:20.831 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:20.831 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:20.832 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:20.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@186d474 2020-06-10 17:15:20.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:20.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32340 to queue - size 28 2020-06-10 17:15:20.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:20.889 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0D 02 86 11 25 C9 91 2020-06-10 17:15:20.890 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 09 00 13 0D 02 86 11 25 C9 91 2020-06-10 17:15:20.893 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:20.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.896 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:20.897 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:20.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:20.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:20.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.920 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:20.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32340: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 201 2020-06-10 17:15:20.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32340: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 201 2020-06-10 17:15:20.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.954 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 C9 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 FA 2020-06-10 17:15:20.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32340: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 201 2020-06-10 17:15:20.957 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:20.959 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:20.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32340: Advanced to WAIT_REQUEST 2020-06-10 17:15:20.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32340: Transaction not completed 2020-06-10 17:15:20.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:20.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:20.990 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=201, payload=C9 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.992 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 0D 09 86 12 03 04 36 01 23 5F 00 C4 00 00 F1 2020-06-10 17:15:20.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=201, payload=C9 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:20.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32340: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 201 2020-06-10 17:15:20.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:20.999 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32340: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 201 2020-06-10 17:15:21.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32340: (Callback 201) 2020-06-10 17:15:21.001 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32340: callback 201 2020-06-10 17:15:21.003 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=201, payload=C9 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.005 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 201, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32340: Advanced to WAIT_DATA 2020-06-10 17:15:21.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32340: Transaction not completed 2020-06-10 17:15:21.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.008 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 09 86 12 03 04 36 01 23 5F 00 C4 00 00 2020-06-10 17:15:21.024 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 17 00 04 00 12 0E 56 01 32 02 21 44 00 00 01 52 00 00 43 F0 C3 00 00 D1 2020-06-10 17:15:21.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 09 86 12 03 04 36 01 23 5F 00 C4 00 00 2020-06-10 17:15:21.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:APP_VERSION) 2020-06-10 17:15:21.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.030 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.031 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V0 VERSION_REPORT 2020-06-10 17:15:21.032 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Report 2020-06-10 17:15:21.033 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Library Type = 3 (Slave Enhanced) 2020-06-10 17:15:21.034 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Protocol Version = 4.54 2020-06-10 17:15:21.035 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Application Version = 1.35 2020-06-10 17:15:21.036 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Hardware Version = 95 2020-06-10 17:15:21.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@119febe. 2020-06-10 17:15:21.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@119febe. 2020-06-10 17:15:21.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32340 DONE 2020-06-10 17:15:21.042 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32340: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.048 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@4a401f 2020-06-10 17:15:21.048 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.049 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to DISCOVERY_COMPLETE 2020-06-10 17:15:21.050 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.050 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 13: Device discovery completed 2020-06-10 17:15:21.050 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 56 01 32 02 21 44 00 00 01 52 00 00 43 F0 C3 00 00 2020-06-10 17:15:21.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 56 01 32 02 21 44 00 00 01 52 00 00 43 F0 C3 00 00 2020-06-10 17:15:21.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE) 2020-06-10 17:15:21.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false 2020-06-10 17:15:21.054 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_CRC_16_ENCAP 2020-06-10 17:15:21.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0 2020-06-10 17:15:21.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER 2020-06-10 17:15:21.057 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V2 METER_REPORT 2020-06-10 17:15:21.058 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=kWh(0), Value=3.38 2020-06-10 17:15:21.058 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 13: Device discovery resolved to thingType zwave:aeon_zw095_00_000 2020-06-10 17:15:21.059 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent 2020-06-10 17:15:21.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3.38 2020-06-10 17:15:21.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:a54065e4:node18:meter_kwh to 3.38 [DecimalType] 2020-06-10 17:15:21.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1. 2020-06-10 17:15:21.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18ecfac. 2020-06-10 17:15:21.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:21.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.069 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 13: Serialise aborted as static stages not complete 2020-06-10 17:15:21.072 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to VERSION 2020-06-10 17:15:21.074 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:21.081 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_DEVICE_RESET_LOCALLY, version is 0 2020-06-10 17:15:21.082 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_DEVICE_RESET_LOCALLY 2020-06-10 17:15:21.083 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2020-06-10 17:15:21.084 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.084 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@12fcb73 2020-06-10 17:15:21.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32341 to queue - size 28 2020-06-10 17:15:21.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.089 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 5A 25 CA C8 2020-06-10 17:15:21.090 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 5A 25 CA C8 2020-06-10 17:15:21.094 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.100 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.120 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32341: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 202 2020-06-10 17:15:21.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.124 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.125 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32341: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 202 2020-06-10 17:15:21.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32341: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 202 2020-06-10 17:15:21.128 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.131 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32341: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32341: Transaction not completed 2020-06-10 17:15:21.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.130 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 CA 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F9 2020-06-10 17:15:21.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=202, payload=CA 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=202, payload=CA 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32341: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 202 2020-06-10 17:15:21.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32341: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 202 2020-06-10 17:15:21.174 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32341: (Callback 202) 2020-06-10 17:15:21.175 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32341: callback 202 2020-06-10 17:15:21.176 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=202, payload=CA 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.177 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 202, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32341: Advanced to WAIT_DATA 2020-06-10 17:15:21.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32341: Transaction not completed 2020-06-10 17:15:21.179 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 5A 01 C4 00 00 F2 2020-06-10 17:15:21.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 5A 01 C4 00 00 2020-06-10 17:15:21.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 5A 01 C4 00 00 2020-06-10 17:15:21.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:21.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.213 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:21.214 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:21.214 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_DEVICE_RESET_LOCALLY, Version = 1 2020-06-10 17:15:21.215 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 1, version set. Enabling extra functionality. 2020-06-10 17:15:21.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@132d123. 2020-06-10 17:15:21.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@132d123. 2020-06-10 17:15:21.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32341 DONE 2020-06-10 17:15:21.219 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32341: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.220 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@382f7 2020-06-10 17:15:21.221 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.220 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.221 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_SECURITY, version is 0 2020-06-10 17:15:21.222 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_SECURITY 2020-06-10 17:15:21.222 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.222 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_SECURITY 2020-06-10 17:15:21.223 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.223 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.224 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@17dccf7 2020-06-10 17:15:21.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32342 to queue - size 28 2020-06-10 17:15:21.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.227 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 98 25 CB 0B 2020-06-10 17:15:21.228 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 98 25 CB 0B 2020-06-10 17:15:21.231 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.237 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.250 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32342: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 203 2020-06-10 17:15:21.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32342: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 203 2020-06-10 17:15:21.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.283 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 CB 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F8 2020-06-10 17:15:21.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32342: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 203 2020-06-10 17:15:21.285 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.285 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32342: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32342: Transaction not completed 2020-06-10 17:15:21.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=203, payload=CB 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=203, payload=CB 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32342: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 203 2020-06-10 17:15:21.312 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 98 01 C4 00 00 30 2020-06-10 17:15:21.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32342: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 203 2020-06-10 17:15:21.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32342: (Callback 203) 2020-06-10 17:15:21.315 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32342: callback 203 2020-06-10 17:15:21.316 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=203, payload=CB 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.317 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 203, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32342: Advanced to WAIT_DATA 2020-06-10 17:15:21.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32342: Transaction not completed 2020-06-10 17:15:21.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 98 01 C4 00 00 2020-06-10 17:15:21.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 98 01 C4 00 00 2020-06-10 17:15:21.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:21.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.343 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.343 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:21.344 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:21.344 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_SECURITY, Version = 1 2020-06-10 17:15:21.345 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 1, version set. Enabling extra functionality. 2020-06-10 17:15:21.345 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c9442e. 2020-06-10 17:15:21.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c9442e. 2020-06-10 17:15:21.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32342 DONE 2020-06-10 17:15:21.348 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32342: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.348 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1e92a3f 2020-06-10 17:15:21.349 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.349 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_CRC_16_ENCAP, version is 0 2020-06-10 17:15:21.350 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_CRC_16_ENCAP 2020-06-10 17:15:21.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.350 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_CRC_16_ENCAP 2020-06-10 17:15:21.351 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.353 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@de02a2 2020-06-10 17:15:21.353 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32343 to queue - size 28 2020-06-10 17:15:21.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.355 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 56 25 CC C2 2020-06-10 17:15:21.356 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 56 25 CC C2 2020-06-10 17:15:21.359 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.363 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.364 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.365 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.380 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32343: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 204 2020-06-10 17:15:21.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.410 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32343: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 204 2020-06-10 17:15:21.412 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 CC 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 FC 2020-06-10 17:15:21.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.415 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32343: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 204 2020-06-10 17:15:21.417 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.417 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32343: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32343: Transaction not completed 2020-06-10 17:15:21.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=204, payload=CC 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=204, payload=CC 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32343: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 204 2020-06-10 17:15:21.442 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 56 01 C4 00 00 FE 2020-06-10 17:15:21.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.444 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32343: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 204 2020-06-10 17:15:21.445 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32343: (Callback 204) 2020-06-10 17:15:21.446 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.446 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32343: callback 204 2020-06-10 17:15:21.447 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=204, payload=CC 00 00 01 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.448 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 204, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32343: Advanced to WAIT_DATA 2020-06-10 17:15:21.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32343: Transaction not completed 2020-06-10 17:15:21.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.451 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 56 01 C4 00 00 2020-06-10 17:15:21.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 56 01 C4 00 00 2020-06-10 17:15:21.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:21.473 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.474 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.474 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:21.475 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:21.476 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_CRC_16_ENCAP, Version = 1 2020-06-10 17:15:21.476 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 1, version set. Enabling extra functionality. 2020-06-10 17:15:21.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@181b5e7. 2020-06-10 17:15:21.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@181b5e7. 2020-06-10 17:15:21.479 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32343 DONE 2020-06-10 17:15:21.480 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32343: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.481 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@2e2bb4 2020-06-10 17:15:21.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.481 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.482 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_BASIC, version is 0 2020-06-10 17:15:21.482 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_BASIC 2020-06-10 17:15:21.482 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_BASIC 2020-06-10 17:15:21.483 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.484 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1795e72 2020-06-10 17:15:21.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32344 to queue - size 28 2020-06-10 17:15:21.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.488 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 20 25 CD B5 2020-06-10 17:15:21.488 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 20 25 CD B5 2020-06-10 17:15:21.491 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.493 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.497 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.510 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32344: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 205 2020-06-10 17:15:21.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.540 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.543 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 CD 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 FE 2020-06-10 17:15:21.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32344: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 205 2020-06-10 17:15:21.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32344: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 205 2020-06-10 17:15:21.546 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.547 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32344: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32344: Transaction not completed 2020-06-10 17:15:21.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=205, payload=CD 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=205, payload=CD 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.572 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 20 01 C4 00 00 88 2020-06-10 17:15:21.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32344: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 205 2020-06-10 17:15:21.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.575 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32344: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 205 2020-06-10 17:15:21.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32344: (Callback 205) 2020-06-10 17:15:21.577 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32344: callback 205 2020-06-10 17:15:21.578 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=205, payload=CD 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.579 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 205, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32344: Advanced to WAIT_DATA 2020-06-10 17:15:21.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32344: Transaction not completed 2020-06-10 17:15:21.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 20 01 C4 00 00 2020-06-10 17:15:21.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 20 01 C4 00 00 2020-06-10 17:15:21.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:21.602 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.603 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.604 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:21.604 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:21.605 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_BASIC, Version = 1 2020-06-10 17:15:21.606 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 1, version set. Enabling extra functionality. 2020-06-10 17:15:21.606 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@59ee14. 2020-06-10 17:15:21.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@59ee14. 2020-06-10 17:15:21.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32344 DONE 2020-06-10 17:15:21.609 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32344: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.609 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1f52670 2020-06-10 17:15:21.610 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.610 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.610 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_VERSION, version is 0 2020-06-10 17:15:21.611 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_VERSION 2020-06-10 17:15:21.612 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_VERSION 2020-06-10 17:15:21.612 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.613 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.614 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1f0ebbe 2020-06-10 17:15:21.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.616 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32345 to queue - size 28 2020-06-10 17:15:21.616 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.617 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 86 25 CE 10 2020-06-10 17:15:21.618 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 86 25 CE 10 2020-06-10 17:15:21.621 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.621 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.625 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.650 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32345: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 206 2020-06-10 17:15:21.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32345: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 206 2020-06-10 17:15:21.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.680 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.681 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.681 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32345: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 206 2020-06-10 17:15:21.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.682 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 CE 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 FD 2020-06-10 17:15:21.683 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32345: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 206 2020-06-10 17:15:21.683 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.684 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.684 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32345: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32345: Transaction not completed 2020-06-10 17:15:21.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=206, payload=CE 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=206, payload=CE 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32345: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 206 2020-06-10 17:15:21.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.712 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 86 02 C4 00 00 2D 2020-06-10 17:15:21.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32345: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 206 2020-06-10 17:15:21.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32345: (Callback 206) 2020-06-10 17:15:21.716 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32345: callback 206 2020-06-10 17:15:21.718 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=206, payload=CE 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.718 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 206, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32345: Advanced to WAIT_DATA 2020-06-10 17:15:21.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32345: Transaction not completed 2020-06-10 17:15:21.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 86 02 C4 00 00 2020-06-10 17:15:21.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 86 02 C4 00 00 2020-06-10 17:15:21.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:21.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.743 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.744 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:21.744 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:21.745 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_VERSION, Version = 2 2020-06-10 17:15:21.745 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 2, version set. Enabling extra functionality. 2020-06-10 17:15:21.746 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14f9e75. 2020-06-10 17:15:21.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14f9e75. 2020-06-10 17:15:21.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32345 DONE 2020-06-10 17:15:21.749 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32345: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.750 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@281e3f 2020-06-10 17:15:21.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.750 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.753 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC, version is 0 2020-06-10 17:15:21.753 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:21.754 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:21.754 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.755 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.755 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1ff4246 2020-06-10 17:15:21.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32346 to queue - size 28 2020-06-10 17:15:21.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.758 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 72 25 CF E5 2020-06-10 17:15:21.759 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 72 25 CF E5 2020-06-10 17:15:21.765 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.765 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.770 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.780 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32346: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 207 2020-06-10 17:15:21.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32346: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 207 2020-06-10 17:15:21.813 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 CF 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 FC 2020-06-10 17:15:21.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32346: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 207 2020-06-10 17:15:21.817 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.818 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.819 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32346: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.819 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32346: Transaction not completed 2020-06-10 17:15:21.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=207, payload=CF 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=207, payload=CF 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32346: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 207 2020-06-10 17:15:21.842 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 72 02 C4 00 00 D9 2020-06-10 17:15:21.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32346: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 207 2020-06-10 17:15:21.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32346: (Callback 207) 2020-06-10 17:15:21.847 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32346: callback 207 2020-06-10 17:15:21.848 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=207, payload=CF 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.849 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 207, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32346: Advanced to WAIT_DATA 2020-06-10 17:15:21.851 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32346: Transaction not completed 2020-06-10 17:15:21.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 72 02 C4 00 00 2020-06-10 17:15:21.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 72 02 C4 00 00 2020-06-10 17:15:21.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:21.873 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:21.873 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.874 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V2 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:21.875 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:21.875 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_MANUFACTURER_SPECIFIC, Version = 2 2020-06-10 17:15:21.876 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 1, version set to maximum supported by the binding. Enabling extra functionality. 2020-06-10 17:15:21.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:21.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@765546. 2020-06-10 17:15:21.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@765546. 2020-06-10 17:15:21.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32346 DONE 2020-06-10 17:15:21.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:21.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32346: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:21.880 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@13331ef 2020-06-10 17:15:21.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:21.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:21.880 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:21.881 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_NO_OPERATION, version is 1 2020-06-10 17:15:21.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.881 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - checking COMMAND_CLASS_ZWAVEPLUS_INFO, version is 0 2020-06-10 17:15:21.882 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: VERSION - queued COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:21.883 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:21.883 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:21.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:21.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2020-06-10 17:15:21.885 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@174956c 2020-06-10 17:15:21.885 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:21.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32347 to queue - size 28 2020-06-10 17:15:21.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:21.887 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0D 03 86 13 5E 25 D0 D6 2020-06-10 17:15:21.888 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 0A 00 13 0D 03 86 13 5E 25 D0 D6 2020-06-10 17:15:21.890 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:21.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:21.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:21.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:21.895 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:21.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.910 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:21.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32347: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 208 2020-06-10 17:15:21.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.940 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32347: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 208 2020-06-10 17:15:21.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32347: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 208 2020-06-10 17:15:21.943 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 D0 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 E3 2020-06-10 17:15:21.943 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:21.945 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:21.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32347: Advanced to WAIT_REQUEST 2020-06-10 17:15:21.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32347: Transaction not completed 2020-06-10 17:15:21.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:21.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=208, payload=D0 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=208, payload=D0 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32347: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 208 2020-06-10 17:15:21.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:21.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32347: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 208 2020-06-10 17:15:21.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32347: (Callback 208) 2020-06-10 17:15:21.974 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 0D 04 86 14 5E 02 C4 00 00 F5 2020-06-10 17:15:21.974 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:21.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32347: callback 208 2020-06-10 17:15:21.976 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=208, payload=D0 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:21.976 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 208, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:21.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32347: Advanced to WAIT_DATA 2020-06-10 17:15:21.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32347: Transaction not completed 2020-06-10 17:15:21.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:21.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:22.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 5E 02 C4 00 00 2020-06-10 17:15:22.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 04 86 14 5E 02 C4 00 00 2020-06-10 17:15:22.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:22.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:VERSION) 2020-06-10 17:15:22.013 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2020-06-10 17:15:22.014 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_VERSION 2020-06-10 17:15:22.015 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_VERSION V2 VERSION_COMMAND_CLASS_REPORT 2020-06-10 17:15:22.015 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Process Version Command Class Report 2020-06-10 17:15:22.016 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 13: Requested Command Class = COMMAND_CLASS_ZWAVEPLUS_INFO, Version = 2 2020-06-10 17:15:22.017 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Version = 1, version set to maximum supported by the binding. Enabling extra functionality. 2020-06-10 17:15:22.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:22.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6afb22. 2020-06-10 17:15:22.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6afb22. 2020-06-10 17:15:22.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32347 DONE 2020-06-10 17:15:22.021 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:22.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:22.023 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:22.023 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32347: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:22.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:22.024 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@12ba5db 2020-06-10 17:15:22.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:22.025 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:22.026 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to ENDPOINTS 2020-06-10 17:15:22.026 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.027 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: ENDPOINTS - MultiInstance not supported. 2020-06-10 17:15:22.030 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to UPDATE_DATABASE 2020-06-10 17:15:22.030 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.032 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE 2020-06-10 17:15:22.038 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - check properties 2020-06-10 17:15:22.039 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - property modelId == ZW095 2020-06-10 17:15:22.040 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - property vendor == AEON Labs 2020-06-10 17:15:22.040 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - property defaultAssociations == 1 2020-06-10 17:15:22.041 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - property manufacturerId == 0086 2020-06-10 17:15:22.042 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - property manufacturerRef == 0002:005F,0102:005F 2020-06-10 17:15:22.042 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: UPDATE_DATABASE - property dbReference == 375 2020-06-10 17:15:22.043 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to STATIC_VALUES 2020-06-10 17:15:22.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.045 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_SECURITY 2020-06-10 17:15:22.047 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_CRC_16_ENCAP 2020-06-10 17:15:22.048 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_BASIC 2020-06-10 17:15:22.049 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_VERSION 2020-06-10 17:15:22.049 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:22.050 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Found 1 instances of COMMAND_CLASS_MANUFACTURER_SPECIFIC for endpoint 0 2020-06-10 17:15:22.050 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_NO_OPERATION 2020-06-10 17:15:22.051 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:22.052 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Found 1 instances of COMMAND_CLASS_ZWAVEPLUS_INFO for endpoint 0 2020-06-10 17:15:22.052 [DEBUG] [ass.impl.CommandClassZwaveplusInfoV1] - Creating command message ZWAVEPLUS_INFO_GET version 1 2020-06-10 17:15:22.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:22.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured 2020-06-10 17:15:22.054 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: ZWaveCommandClassTransactionPayload - send to node 2020-06-10 17:15:22.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:22.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured 2020-06-10 17:15:22.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@a7254a 2020-06-10 17:15:22.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2020-06-10 17:15:22.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Added 32348 to queue - size 28 2020-06-10 17:15:22.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:22.060 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0D 02 5E 01 25 D1 41 2020-06-10 17:15:22.061 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 13: Sending REQUEST Message = 01 09 00 13 0D 02 5E 01 25 D1 41 2020-06-10 17:15:22.065 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-06-10 17:15:22.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:22.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-06-10 17:15:22.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:22.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-06-10 17:15:22.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:22.078 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-06-10 17:15:22.090 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-06-10 17:15:22.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 32348: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 209 2020-06-10 17:15:22.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:22.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:22.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:22.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32348: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 209 2020-06-10 17:15:22.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:22.095 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32348: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 209 2020-06-10 17:15:22.095 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-06-10 17:15:22.096 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: sentData successfully placed on stack. 2020-06-10 17:15:22.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32348: Advanced to WAIT_REQUEST 2020-06-10 17:15:22.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32348: Transaction not completed 2020-06-10 17:15:22.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:22.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:22.099 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 D1 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 E2 2020-06-10 17:15:22.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=209, payload=D1 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:22.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=209, payload=D1 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:22.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 32348: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 209 2020-06-10 17:15:22.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-06-10 17:15:22.133 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 0D 09 5E 02 01 05 00 10 00 10 00 C6 00 00 73 2020-06-10 17:15:22.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 32348: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 209 2020-06-10 17:15:22.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 32348: (Callback 209) 2020-06-10 17:15:22.135 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-06-10 17:15:22.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 32348: callback 209 2020-06-10 17:15:22.136 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=209, payload=D1 00 00 02 00 C4 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 2020-06-10 17:15:22.137 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 13: SendData Request. CallBack ID = 209, Status = Transmission complete and ACK received(0) 2020-06-10 17:15:22.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32348: Advanced to WAIT_DATA 2020-06-10 17:15:22.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: TID 32348: Transaction not completed 2020-06-10 17:15:22.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:22.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-06-10 17:15:22.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 09 5E 02 01 05 00 10 00 10 00 C6 00 00 2020-06-10 17:15:22.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=13, callback=0, payload=00 0D 09 5E 02 01 05 00 10 00 10 00 C6 00 00 2020-06-10 17:15:22.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:22.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Application Command Request (ALIVE:STATIC_VALUES) 2020-06-10 17:15:22.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: Incoming command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 2020-06-10 17:15:22.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 13: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:22.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 13: Received COMMAND_CLASS_ZWAVEPLUS_INFO V1 ZWAVEPLUS_INFO_REPORT 2020-06-10 17:15:22.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Commands processed 1. 2020-06-10 17:15:22.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1989735. 2020-06-10 17:15:22.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1989735. 2020-06-10 17:15:22.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:32348 DONE 2020-06-10 17:15:22.167 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-06-10 17:15:22.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-06-10 17:15:22.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 32348: Transaction event listener: DONE: DONE -> 2020-06-10 17:15:22.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:22.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:22.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@13c378 2020-06-10 17:15:22.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:22.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init transaction completed with response COMPLETE 2020-06-10 17:15:22.171 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to ASSOCIATIONS 2020-06-10 17:15:22.172 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.173 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to SET_WAKEUP 2020-06-10 17:15:22.174 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.175 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to SET_ASSOCIATION 2020-06-10 17:15:22.176 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.177 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: SET_ASSOCIATION - ASSOCIATION class not supported 2020-06-10 17:15:22.177 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to SET_LIFELINE 2020-06-10 17:15:22.178 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.179 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: SET_LIFELINE - ASSOCIATION class not supported 2020-06-10 17:15:22.179 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to GET_CONFIGURATION 2020-06-10 17:15:22.184 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.185 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: GET_CONFIGURATION - CONFIGURATION class not supported 2020-06-10 17:15:22.186 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to STATIC_END 2020-06-10 17:15:22.187 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.187 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Updating node properties. 2020-06-10 17:15:22.188 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Updating node properties. MAN=134 2020-06-10 17:15:22.190 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Updating node properties. MAN=134. SET. Was 134 2020-06-10 17:15:22.192 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Properties synchronised 2020-06-10 17:15:22.195 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising Thing Node... 2020-06-10 17:15:22.196 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_current for DecimalType 2020-06-10 17:15:22.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_current for DecimalType 2020-06-10 17:15:22.199 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_watts for DecimalType 2020-06-10 17:15:22.200 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_watts for DecimalType 2020-06-10 17:15:22.201 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_kwh for DecimalType 2020-06-10 17:15:22.201 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_kwh for DecimalType 2020-06-10 17:15:22.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_voltage for DecimalType 2020-06-10 17:15:22.203 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_voltage for DecimalType 2020-06-10 17:15:22.203 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_reset for OnOffType 2020-06-10 17:15:22.204 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_reset for OnOffType 2020-06-10 17:15:22.205 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_current1 for DecimalType 2020-06-10 17:15:22.205 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_current1 for DecimalType 2020-06-10 17:15:22.206 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_watts1 for DecimalType 2020-06-10 17:15:22.207 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_watts1 for DecimalType 2020-06-10 17:15:22.208 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_kwh1 for DecimalType 2020-06-10 17:15:22.208 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_kwh1 for DecimalType 2020-06-10 17:15:22.209 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_voltage1 for DecimalType 2020-06-10 17:15:22.210 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_voltage1 for DecimalType 2020-06-10 17:15:22.211 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_current2 for DecimalType 2020-06-10 17:15:22.211 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_current2 for DecimalType 2020-06-10 17:15:22.212 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_watts2 for DecimalType 2020-06-10 17:15:22.213 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_watts2 for DecimalType 2020-06-10 17:15:22.213 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_kwh2 for DecimalType 2020-06-10 17:15:22.214 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_kwh2 for DecimalType 2020-06-10 17:15:22.215 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_voltage2 for DecimalType 2020-06-10 17:15:22.215 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_voltage2 for DecimalType 2020-06-10 17:15:22.216 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_current3 for DecimalType 2020-06-10 17:15:22.217 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_current3 for DecimalType 2020-06-10 17:15:22.218 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_watts3 for DecimalType 2020-06-10 17:15:22.218 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_watts3 for DecimalType 2020-06-10 17:15:22.221 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_kwh3 for DecimalType 2020-06-10 17:15:22.221 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_kwh3 for DecimalType 2020-06-10 17:15:22.222 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising cmd channel zwave:device:a54065e4:node13:meter_voltage3 for DecimalType 2020-06-10 17:15:22.223 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Initialising state channel zwave:device:a54065e4:node13:meter_voltage3 for DecimalType 2020-06-10 17:15:22.224 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Polling initialised at 86400 seconds - start in 4492800 milliseconds. 2020-06-10 17:15:22.224 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 13: Serialise aborted as static stages not complete 2020-06-10 17:15:22.225 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to DYNAMIC_VALUES 2020-06-10 17:15:22.226 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.226 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SECURITY 2020-06-10 17:15:22.227 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_CRC_16_ENCAP 2020-06-10 17:15:22.227 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_BASIC 2020-06-10 17:15:22.227 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_VERSION 2020-06-10 17:15:22.228 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC 2020-06-10 17:15:22.228 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_NO_OPERATION 2020-06-10 17:15:22.229 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO 2020-06-10 17:15:22.229 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer: Initialisation complete! 2020-06-10 17:15:22.230 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to DYNAMIC_END 2020-06-10 17:15:22.231 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.231 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 13: Serializing to file /var/lib/openhab2/zwave/network_eeeed13d__node_13.xml 2020-06-10 17:15:22.248 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 13: Node advancer - advancing to DONE 2020-06-10 17:15:22.249 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2020-06-10 17:15:22.251 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 13: Serializing to file /var/lib/openhab2/zwave/network_eeeed13d__node_13.xml 2020-06-10 17:15:23.390 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 17 00 04 00 0C 0E 60 0D 01 01 32 02 21 54 00 00 00 00 00 00 C6 00 00 00 2020-06-10 17:15:23.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0E 60 0D 01 01 32 02 21 54 00 00 00 00 00 00 C6 00 00 2020-06-10 17:15:23.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0E 60 0D 01 01 32 02 21 54 00 00 00 00 00 00 C6 00 00 2020-06-10 17:15:23.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-06-10 17:15:23.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE) 2020-06-10 17:15:23.425 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-06-10 17:15:23.426 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 17 00 04 00 0C 0E 60 0D 01 00 32 02 21 54 00 00 00 00 00 00 C6 00 00 01 2020-06-10 17:15:23.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2020-06-10 17:15:23.432 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_METER, endpoint 1 2020-06-10 17:15:23.433 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY not supported 2020-06-10 17:15:23.434 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_METER V3 METER_REPORT 2020-06-10 17:15:23.435 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 12: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 2020-06-10 17:15:23.436 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveMeterValueEvent 2020-06-10 17:15:23.437 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1 2020-06-10 17:15:23.438 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:a54065e4:node12:meter_watts1 to 0 [DecimalType] 2020-06-10 17:15:23.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2020-06-10 17:15:23.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e10d45. 2020-06-10 17:15:23.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:23.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-06-10 17:15:23.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-06-10 17:15:23.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-06-10 17:15:23.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0E 60 0D 01 00 32 02 21 54 00 00 00 00 00 00 C6 00 00 2020-06-10 17:15:23.452 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0E 60 0D 01 00 32 02 21 54 00 00 00 00 00 00 C6 00 00