Sending ON from Fibaro keyfob to Fibaro FGD-212 dimmer2, endpoint 1: Result: Light does not turn on, nothing at all in log. Sending on from Fibaro keyfob to Fibaro FGD-212 dimmer2, endpoint 2: Result: Light does not turn on, nothing at all in log. Sending ON from openhab app to Fibaro FGD-212 dimmer2, endpoint 1 in openhab code (...node8:switch_binary1): Result: turns on, information in log. 2019-02-15 20:51:57.734 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Command received zwave:device:9a59f25e:node8:switch_dimmer1 --> 100 [PercentType] 2019-02-15 20:51:57.734 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Creating new message for command SWITCH_MULTILEVEL_SET 2019-02-15 20:51:57.735 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Encapsulating message, instance / endpoint 1 2019-02-15 20:51:57.735 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 8: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1 2019-02-15 20:51:57.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY required on COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:51:57.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL is required to be secured 2019-02-15 20:51:57.738 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue 2019-02-15 20:51:57.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 2622 to queue - size 13 2019-02-15 20:51:57.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:57.740 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: isNonceAvailable = null 2019-02-15 20:51:57.742 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1 2019-02-15 20:51:57.743 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 98 40 25 92 80 2019-02-15 20:51:57.744 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 98 40 25 92 80 2019-02-15 20:51:57.746 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:51:57.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2623: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 146 2019-02-15 20:51:57.748 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling intialised at 86400 seconds - start in 1500 milliseconds. 2019-02-15 20:51:57.748 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:51:57.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:57.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:57.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2623: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 146 2019-02-15 20:51:57.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:51:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:57.754 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:51:57.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:57.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:57.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2623: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 146 2019-02-15 20:51:57.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:51:57.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2623: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 146 2019-02-15 20:51:57.758 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:57.759 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:51:57.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2623: Advanced to WAIT_REQUEST 2019-02-15 20:51:57.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2623: Transaction not completed 2019-02-15 20:51:57.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:57.773 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 92 00 00 02 7B 2019-02-15 20:51:57.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=146, payload=92 00 00 02 2019-02-15 20:51:57.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=146, payload=92 00 00 02 2019-02-15 20:51:57.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2623: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 146 2019-02-15 20:51:57.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:51:57.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2623: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 146 2019-02-15 20:51:57.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2623: (Callback 146) 2019-02-15 20:51:57.781 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:51:57.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2623: callback 146 2019-02-15 20:51:57.781 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=146, payload=92 00 00 02 2019-02-15 20:51:57.782 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 146, Status = Transmission complete and ACK received(0) 2019-02-15 20:51:57.782 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:51:57.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2623: Advanced to WAIT_DATA 2019-02-15 20:51:57.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2623: Transaction not completed 2019-02-15 20:51:57.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:57.786 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 08 0A 98 80 0E A5 64 0F 99 B5 F5 58 B0 2019-02-15 20:51:57.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 98 80 0E A5 64 0F 99 B5 F5 58 2019-02-15 20:51:57.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 98 80 0E A5 64 0F 99 B5 F5 58 2019-02-15 20:51:57.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:51:57.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:51:57.790 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:51:57.790 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2019-02-15 20:51:57.791 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY check internal 2019-02-15 20:51:57.791 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT 2019-02-15 20:51:57.791 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received start... 2019-02-15 20:51:57.792 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received start null 2019-02-15 20:51:57.792 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received ZWaveNonce [nonceBytes=(0E A5 64 0F 99 B5 F5 58 ), timer=65541, valid=true] 2019-02-15 20:51:57.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:51:57.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@74be1a3c. 2019-02-15 20:51:57.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@74be1a3c. 2019-02-15 20:51:57.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2623 DONE 2019-02-15 20:51:57.794 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:51:57.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-02-15 20:51:57.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:51:57.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:57.796 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1 2019-02-15 20:51:57.797 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: SECURITY_TXD 60 0D 01 01 26 01 63 2019-02-15 20:51:57.797 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 22 00 13 08 1B 98 81 BF C1 8A D2 5F E8 10 3B 68 01 B3 00 DA CC 11 47 0E D2 5F 41 4C 8E 92 C8 D5 25 93 DD 2019-02-15 20:51:57.798 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 22 00 13 08 1B 98 81 BF C1 8A D2 5F E8 10 3B 68 01 B3 00 DA CC 11 47 0E D2 5F 41 4C 8E 92 C8 D5 25 93 DD 2019-02-15 20:51:57.799 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:51:57.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2622: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 147 2019-02-15 20:51:57.805 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:51:57.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:57.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:57.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2622: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 147 2019-02-15 20:51:57.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:51:57.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:57.808 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:51:57.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:57.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:57.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2622: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 147 2019-02-15 20:51:57.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:51:57.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2622: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 147 2019-02-15 20:51:57.813 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:57.813 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:51:57.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2622: Advanced to WAIT_REQUEST 2019-02-15 20:51:57.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2622: Transaction not completed 2019-02-15 20:51:57.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:57.830 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 93 00 00 03 7B 2019-02-15 20:51:57.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=147, payload=93 00 00 03 2019-02-15 20:51:57.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=147, payload=93 00 00 03 2019-02-15 20:51:57.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2622: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 147 2019-02-15 20:51:57.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:51:57.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2622: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 147 2019-02-15 20:51:57.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2622: (Callback 147) 2019-02-15 20:51:57.837 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:51:57.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2622: callback 147 2019-02-15 20:51:57.838 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=147, payload=93 00 00 03 2019-02-15 20:51:57.839 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 147, Status = Transmission complete and ACK received(0) 2019-02-15 20:51:57.839 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:51:57.840 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2622: Transaction COMPLETED 2019-02-15 20:51:57.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 41ms 2019-02-15 20:51:57.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2622: Transaction completed 2019-02-15 20:51:57.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2622 DONE 2019-02-15 20:51:57.842 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:51:57.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:57.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:58.843 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 08 02 98 40 21 2019-02-15 20:51:58.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 02 98 40 2019-02-15 20:51:58.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 02 98 40 2019-02-15 20:51:58.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:51:58.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:51:58.849 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:51:58.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2019-02-15 20:51:58.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY check internal 2019-02-15 20:51:58.851 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2019-02-15 20:51:58.851 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2019-02-15 20:51:58.851 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added to secure queue - size 1 2019-02-15 20:51:58.851 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:51:58.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3e7cf932. 2019-02-15 20:51:58.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:51:58.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:51:58.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:58.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:58.853 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 08 0A 98 80 80 E2 28 B9 57 0D FB 8D 25 94 89 2019-02-15 20:51:58.853 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 11 00 13 08 0A 98 80 80 E2 28 B9 57 0D FB 8D 25 94 89 2019-02-15 20:51:58.854 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:51:58.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2624: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 148 2019-02-15 20:51:58.855 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:51:58.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:58.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:58.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2624: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 148 2019-02-15 20:51:58.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:51:58.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:58.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:58.863 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:51:58.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:58.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:58.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2624: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 148 2019-02-15 20:51:58.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:51:58.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2624: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 148 2019-02-15 20:51:58.868 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:51:58.869 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:51:58.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2624: Advanced to WAIT_REQUEST 2019-02-15 20:51:58.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2624: Transaction not completed 2019-02-15 20:51:58.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:58.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:51:58.882 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 94 00 00 02 7D 2019-02-15 20:51:58.885 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=148, payload=94 00 00 02 2019-02-15 20:51:58.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=148, payload=94 00 00 02 2019-02-15 20:51:58.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2624: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 148 2019-02-15 20:51:58.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:51:58.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2624: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 148 2019-02-15 20:51:58.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2624: (Callback 148) 2019-02-15 20:51:58.888 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:51:58.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2624: callback 148 2019-02-15 20:51:58.888 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=148, payload=94 00 00 02 2019-02-15 20:51:58.889 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 148, Status = Transmission complete and ACK received(0) 2019-02-15 20:51:58.889 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:51:58.890 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2624: Transaction COMPLETED 2019-02-15 20:51:58.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 36ms 2019-02-15 20:51:58.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2624: Transaction completed 2019-02-15 20:51:58.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2624 DONE 2019-02-15 20:51:58.891 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:51:58.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:58.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:58.900 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 21 00 04 00 08 1B 98 81 16 BE 4F BF 5D A0 FE 2E D5 0F 43 A1 86 14 78 0E 80 2D 52 67 93 BB 39 B2 4D 0F 2019-02-15 20:51:58.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 1B 98 81 16 BE 4F BF 5D A0 FE 2E D5 0F 43 A1 86 14 78 0E 80 2D 52 67 93 BB 39 B2 4D 2019-02-15 20:51:58.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 1B 98 81 16 BE 4F BF 5D A0 FE 2E D5 0F 43 A1 86 14 78 0E 80 2D 52 67 93 BB 39 B2 4D 2019-02-15 20:51:58.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:51:58.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:51:58.905 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:51:58.906 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Decapsulating COMMAND_CLASS_SECURITY 2019-02-15 20:51:58.907 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: SECURITY_RXD 60 0D 01 01 26 03 63 2019-02-15 20:51:58.907 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:51:58.908 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1 2019-02-15 20:51:58.908 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 2019-02-15 20:51:58.909 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Switch Multi Level report, value = 99 2019-02-15 20:51:58.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 20:51:58.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 99 2019-02-15 20:51:58.911 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:9a59f25e:node8:switch_dimmer1 to 100 [PercentType] 2019-02-15 20:51:58.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:51:58.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@24c06214. 2019-02-15 20:51:58.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:51:58.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:51:58.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:58.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:59.248 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling... 2019-02-15 20:51:59.249 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling zwave:device:9a59f25e:node8:switch_dimmer1 2019-02-15 20:51:59.250 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 8: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1 2019-02-15 20:51:59.251 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Creating new message for command SWITCH_MULTILEVEL_GET 2019-02-15 20:51:59.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Encapsulating message, instance / endpoint 1 2019-02-15 20:51:59.253 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 8: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1 2019-02-15 20:51:59.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY required on COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:51:59.254 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL is required to be secured 2019-02-15 20:51:59.255 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling skipped for zwave:device:9a59f25e:node8:switch_dimmer1 on COMMAND_CLASS_BASIC 2019-02-15 20:51:59.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue 2019-02-15 20:51:59.256 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 2625 to queue - size 13 2019-02-15 20:51:59.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:51:59.258 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: isNonceAvailable = null 2019-02-15 20:51:59.259 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1 2019-02-15 20:51:59.259 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 98 40 25 95 87 2019-02-15 20:51:59.259 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 98 40 25 95 87 2019-02-15 20:51:59.260 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:51:59.261 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:51:59.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:59.261 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2626: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 149 2019-02-15 20:51:59.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:51:59.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2626: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 149 2019-02-15 20:51:59.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:51:59.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:51:59.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. Sending ON from openhab app to Fibaro FGD-212 dimmer2, endpoint 2 in openhab code (...node8:switch_binary2): Result: Light turns does not turn on, information in log. 2019-02-15 20:57:56.281 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Command received zwave:device:9a59f25e:node8:switch_dimmer2 --> 100 [PercentType] 2019-02-15 20:57:56.284 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Creating new message for command SWITCH_MULTILEVEL_SET 2019-02-15 20:57:56.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Encapsulating message, instance / endpoint 2 2019-02-15 20:57:56.285 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 8: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2 2019-02-15 20:57:56.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY required on COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:57:56.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL is required to be secured 2019-02-15 20:57:56.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue 2019-02-15 20:57:56.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 2680 to queue - size 16 2019-02-15 20:57:56.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:56.287 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: isNonceAvailable = null 2019-02-15 20:57:56.287 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1 2019-02-15 20:57:56.288 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 98 40 25 C5 D7 2019-02-15 20:57:56.289 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 98 40 25 C5 D7 2019-02-15 20:57:56.290 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:57:56.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2681: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 197 2019-02-15 20:57:56.292 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:57:56.293 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:56.293 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:56.294 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2681: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 197 2019-02-15 20:57:56.294 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:57:56.294 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.295 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling intialised at 86400 seconds - start in 1500 milliseconds. 2019-02-15 20:57:56.297 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:57:56.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2681: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 197 2019-02-15 20:57:56.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:56.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2681: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 197 2019-02-15 20:57:56.301 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.302 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:57:56.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2681: Advanced to WAIT_REQUEST 2019-02-15 20:57:56.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2681: Transaction not completed 2019-02-15 20:57:56.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.315 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C5 00 00 02 2C 2019-02-15 20:57:56.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=197, payload=C5 00 00 02 2019-02-15 20:57:56.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=197, payload=C5 00 00 02 2019-02-15 20:57:56.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2681: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 197 2019-02-15 20:57:56.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:56.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2681: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 197 2019-02-15 20:57:56.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2681: (Callback 197) 2019-02-15 20:57:56.321 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:57:56.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2681: callback 197 2019-02-15 20:57:56.322 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=197, payload=C5 00 00 02 2019-02-15 20:57:56.322 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 197, Status = Transmission complete and ACK received(0) 2019-02-15 20:57:56.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:56.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2681: Advanced to WAIT_DATA 2019-02-15 20:57:56.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2681: Transaction not completed 2019-02-15 20:57:56.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.329 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 08 0A 98 80 14 5E F2 22 1C 95 05 88 6F 2019-02-15 20:57:56.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 98 80 14 5E F2 22 1C 95 05 88 2019-02-15 20:57:56.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 98 80 14 5E F2 22 1C 95 05 88 2019-02-15 20:57:56.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:56.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:57:56.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:56.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2019-02-15 20:57:56.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY check internal 2019-02-15 20:57:56.333 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT 2019-02-15 20:57:56.333 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received start... 2019-02-15 20:57:56.334 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received start null 2019-02-15 20:57:56.334 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received ZWaveNonce [nonceBytes=(14 5E F2 22 1C 95 05 88 ), timer=51002, valid=true] 2019-02-15 20:57:56.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:57:56.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6ee86f19. 2019-02-15 20:57:56.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6ee86f19. 2019-02-15 20:57:56.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2681 DONE 2019-02-15 20:57:56.336 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:57:56.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-02-15 20:57:56.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:56.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:56.339 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1 2019-02-15 20:57:56.340 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: SECURITY_TXD 60 0D 01 02 26 01 63 2019-02-15 20:57:56.340 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 22 00 13 08 1B 98 81 D1 C3 98 39 CF AA EA FB 13 EA BB B9 67 AC AC 80 14 15 1D 80 ED 55 97 41 CD 25 C6 C3 2019-02-15 20:57:56.341 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 22 00 13 08 1B 98 81 D1 C3 98 39 CF AA EA FB 13 EA BB B9 67 AC AC 80 14 15 1D 80 ED 55 97 41 CD 25 C6 C3 2019-02-15 20:57:56.343 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:57:56.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2680: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 198 2019-02-15 20:57:56.345 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:57:56.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:56.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:56.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2680: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 198 2019-02-15 20:57:56.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:57:56.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.353 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:57:56.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2680: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 198 2019-02-15 20:57:56.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:56.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2680: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 198 2019-02-15 20:57:56.356 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.356 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:57:56.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2680: Advanced to WAIT_REQUEST 2019-02-15 20:57:56.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2680: Transaction not completed 2019-02-15 20:57:56.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.372 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C6 00 00 02 2F 2019-02-15 20:57:56.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=198, payload=C6 00 00 02 2019-02-15 20:57:56.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=198, payload=C6 00 00 02 2019-02-15 20:57:56.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2680: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 198 2019-02-15 20:57:56.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:56.377 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2680: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 198 2019-02-15 20:57:56.377 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2680: (Callback 198) 2019-02-15 20:57:56.377 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:57:56.378 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2680: callback 198 2019-02-15 20:57:56.378 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=198, payload=C6 00 00 02 2019-02-15 20:57:56.379 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 198, Status = Transmission complete and ACK received(0) 2019-02-15 20:57:56.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:56.380 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2680: Transaction COMPLETED 2019-02-15 20:57:56.380 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 36ms 2019-02-15 20:57:56.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2680: Transaction completed 2019-02-15 20:57:56.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2680 DONE 2019-02-15 20:57:56.382 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:57:56.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:56.454 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 08 02 98 40 21 2019-02-15 20:57:56.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 02 98 40 2019-02-15 20:57:56.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 02 98 40 2019-02-15 20:57:56.458 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:56.458 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:57:56.459 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:56.460 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2019-02-15 20:57:56.460 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY check internal 2019-02-15 20:57:56.461 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2019-02-15 20:57:56.461 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2019-02-15 20:57:56.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added to secure queue - size 1 2019-02-15 20:57:56.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:57:56.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@62c1eacc. 2019-02-15 20:57:56.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:56.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:56.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:56.464 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 08 0A 98 80 8D 6C 44 01 99 B0 43 6A 25 C7 A1 2019-02-15 20:57:56.464 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 11 00 13 08 0A 98 80 8D 6C 44 01 99 B0 43 6A 25 C7 A1 2019-02-15 20:57:56.465 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:57:56.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2682: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 199 2019-02-15 20:57:56.467 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:57:56.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:56.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:56.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2682: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 199 2019-02-15 20:57:56.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:57:56.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.473 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:57:56.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2682: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 199 2019-02-15 20:57:56.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:56.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2682: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 199 2019-02-15 20:57:56.477 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:56.477 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:57:56.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2682: Advanced to WAIT_REQUEST 2019-02-15 20:57:56.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2682: Transaction not completed 2019-02-15 20:57:56.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:56.491 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C7 00 00 02 2E 2019-02-15 20:57:56.493 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=199, payload=C7 00 00 02 2019-02-15 20:57:56.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=199, payload=C7 00 00 02 2019-02-15 20:57:56.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2682: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 199 2019-02-15 20:57:56.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:56.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2682: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 199 2019-02-15 20:57:56.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2682: (Callback 199) 2019-02-15 20:57:56.496 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:57:56.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2682: callback 199 2019-02-15 20:57:56.496 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=199, payload=C7 00 00 02 2019-02-15 20:57:56.497 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 199, Status = Transmission complete and ACK received(0) 2019-02-15 20:57:56.497 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:56.498 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2682: Transaction COMPLETED 2019-02-15 20:57:56.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 33ms 2019-02-15 20:57:56.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2682: Transaction completed 2019-02-15 20:57:56.499 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2682 DONE 2019-02-15 20:57:56.499 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:57:56.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:56.511 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 21 00 04 00 08 1B 98 81 57 E0 2C D2 72 E2 A6 EE 8C 1D 8F 70 ED 42 39 26 8D AB AA C8 16 AA 55 2E 3B 27 2019-02-15 20:57:56.512 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 1B 98 81 57 E0 2C D2 72 E2 A6 EE 8C 1D 8F 70 ED 42 39 26 8D AB AA C8 16 AA 55 2E 3B 2019-02-15 20:57:56.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 1B 98 81 57 E0 2C D2 72 E2 A6 EE 8C 1D 8F 70 ED 42 39 26 8D AB AA C8 16 AA 55 2E 3B 2019-02-15 20:57:56.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:56.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:57:56.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:56.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Decapsulating COMMAND_CLASS_SECURITY 2019-02-15 20:57:56.517 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: SECURITY_RXD 60 0D 02 02 26 03 63 2019-02-15 20:57:56.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:57:56.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 2 2019-02-15 20:57:56.518 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 2019-02-15 20:57:56.518 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Switch Multi Level report, value = 99 2019-02-15 20:57:56.518 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 20:57:56.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint = 2, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 99 2019-02-15 20:57:56.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:9a59f25e:node8:switch_dimmer2 to 100 [PercentType] 2019-02-15 20:57:56.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:57:56.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4cf4b421. 2019-02-15 20:57:56.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:56.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:56.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:56.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:57.792 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling... 2019-02-15 20:57:57.793 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling zwave:device:9a59f25e:node8:switch_dimmer2 2019-02-15 20:57:57.793 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 8: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 2 2019-02-15 20:57:57.794 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Creating new message for command SWITCH_MULTILEVEL_GET 2019-02-15 20:57:57.794 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Encapsulating message, instance / endpoint 2 2019-02-15 20:57:57.794 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 8: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2 2019-02-15 20:57:57.795 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY required on COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:57:57.795 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL is required to be secured 2019-02-15 20:57:57.795 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling skipped for zwave:device:9a59f25e:node8:switch_dimmer2 on COMMAND_CLASS_BASIC 2019-02-15 20:57:57.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue 2019-02-15 20:57:57.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 2683 to queue - size 16 2019-02-15 20:57:57.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:57.797 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: isNonceAvailable = null 2019-02-15 20:57:57.797 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_GET version 1 2019-02-15 20:57:57.797 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 98 40 25 C8 DA 2019-02-15 20:57:57.798 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 98 40 25 C8 DA 2019-02-15 20:57:57.799 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:57:57.800 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:57:57.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:57.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:57.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:57.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:57:57.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2684: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 200 2019-02-15 20:57:57.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.807 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:57:57.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2684: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 200 2019-02-15 20:57:57.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:57.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2684: [WAIT_RESPONSE] priority=High, requiresResponse=true, callback: 200 2019-02-15 20:57:57.810 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.810 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:57:57.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2684: Advanced to WAIT_REQUEST 2019-02-15 20:57:57.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2684: Transaction not completed 2019-02-15 20:57:57.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.824 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C8 00 00 02 21 2019-02-15 20:57:57.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=200, payload=C8 00 00 02 2019-02-15 20:57:57.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=200, payload=C8 00 00 02 2019-02-15 20:57:57.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2684: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 200 2019-02-15 20:57:57.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:57.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2684: [WAIT_REQUEST] priority=High, requiresResponse=true, callback: 200 2019-02-15 20:57:57.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2684: (Callback 200) 2019-02-15 20:57:57.830 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:57:57.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2684: callback 200 2019-02-15 20:57:57.831 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=200, payload=C8 00 00 02 2019-02-15 20:57:57.832 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 200, Status = Transmission complete and ACK received(0) 2019-02-15 20:57:57.832 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:57.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2684: Advanced to WAIT_DATA 2019-02-15 20:57:57.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2684: Transaction not completed 2019-02-15 20:57:57.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.838 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 08 0A 98 80 26 1C E8 37 FF 69 29 3E 95 2019-02-15 20:57:57.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 98 80 26 1C E8 37 FF 69 29 3E 2019-02-15 20:57:57.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 98 80 26 1C E8 37 FF 69 29 3E 2019-02-15 20:57:57.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:57.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:57:57.843 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:57.843 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2019-02-15 20:57:57.844 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY check internal 2019-02-15 20:57:57.844 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_REPORT 2019-02-15 20:57:57.844 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received start... 2019-02-15 20:57:57.845 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received start null 2019-02-15 20:57:57.845 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: NONCE Received ZWaveNonce [nonceBytes=(26 1C E8 37 FF 69 29 3E ), timer=61799, valid=true] 2019-02-15 20:57:57.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:57:57.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5eda0f51. 2019-02-15 20:57:57.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5eda0f51. 2019-02-15 20:57:57.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2684 DONE 2019-02-15 20:57:57.847 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:57:57.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-02-15 20:57:57.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:57.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 20:57:57.849 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_MESSAGE_ENCAPSULATION version 1 2019-02-15 20:57:57.850 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: SECURITY_TXD 60 0D 01 02 26 02 2019-02-15 20:57:57.851 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 21 00 13 08 1A 98 81 04 45 6F FE 82 CC C1 42 44 EF 1F 39 FE B5 D6 26 A2 A1 7D 9E 0D 32 77 A5 25 C9 0C 2019-02-15 20:57:57.851 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 21 00 13 08 1A 98 81 04 45 6F FE 82 CC C1 42 44 EF 1F 39 FE B5 D6 26 A2 A1 7D 9E 0D 32 77 A5 25 C9 0C 2019-02-15 20:57:57.852 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:57:57.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2683: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 201 2019-02-15 20:57:57.855 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:57:57.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:57.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:57.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2683: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 201 2019-02-15 20:57:57.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:57:57.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.863 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:57:57.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2683: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 201 2019-02-15 20:57:57.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:57.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2683: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 201 2019-02-15 20:57:57.866 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.866 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:57:57.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2683: Advanced to WAIT_REQUEST 2019-02-15 20:57:57.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2683: Transaction not completed 2019-02-15 20:57:57.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.882 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C9 00 00 02 20 2019-02-15 20:57:57.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=201, payload=C9 00 00 02 2019-02-15 20:57:57.885 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=201, payload=C9 00 00 02 2019-02-15 20:57:57.885 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2683: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 201 2019-02-15 20:57:57.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-02-15 20:57:57.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2683: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 201 2019-02-15 20:57:57.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2683: (Callback 201) 2019-02-15 20:57:57.888 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:57:57.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2683: callback 201 2019-02-15 20:57:57.888 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=201, payload=C9 00 00 02 2019-02-15 20:57:57.889 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 201, Status = Transmission complete and ACK received(0) 2019-02-15 20:57:57.889 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:57.889 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2683: Advanced to WAIT_DATA 2019-02-15 20:57:57.889 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2683: Transaction not completed 2019-02-15 20:57:57.889 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.889 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.894 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 08 02 98 40 21 2019-02-15 20:57:57.896 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 02 98 40 2019-02-15 20:57:57.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 02 98 40 2019-02-15 20:57:57.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:57.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:57:57.897 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:57.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2019-02-15 20:57:57.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY check internal 2019-02-15 20:57:57.898 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2019-02-15 20:57:57.898 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2019-02-15 20:57:57.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added to secure queue - size 1 2019-02-15 20:57:57.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:57:57.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@73672334. 2019-02-15 20:57:57.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@73672334. 2019-02-15 20:57:57.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-02-15 20:57:57.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-02-15 20:57:57.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.900 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 08 0A 98 80 24 4F FB 7A D2 F8 9D B7 25 CA E2 2019-02-15 20:57:57.901 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 11 00 13 08 0A 98 80 24 4F FB 7A D2 F8 9D B7 25 CA E2 2019-02-15 20:57:57.902 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-02-15 20:57:57.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 2685: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 202 2019-02-15 20:57:57.905 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-02-15 20:57:57.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:57.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-02-15 20:57:57.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2685: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 202 2019-02-15 20:57:57.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-02-15 20:57:57.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false. 2019-02-15 20:57:57.910 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2019-02-15 20:57:57.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2685: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 202 2019-02-15 20:57:57.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2 2019-02-15 20:57:57.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2685: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 202 2019-02-15 20:57:57.915 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2019-02-15 20:57:57.915 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack. 2019-02-15 20:57:57.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 2685: Advanced to WAIT_REQUEST 2019-02-15 20:57:57.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2685: Transaction not completed 2019-02-15 20:57:57.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 2 out at start. Holdoff false. 2019-02-15 20:57:57.929 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 CA 00 00 03 22 2019-02-15 20:57:57.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=202, payload=CA 00 00 03 2019-02-15 20:57:57.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=202, payload=CA 00 00 03 2019-02-15 20:57:57.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 2685: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 202 2019-02-15 20:57:57.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 2 2019-02-15 20:57:57.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 2685: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 202 2019-02-15 20:57:57.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2683: (Callback 201) 2019-02-15 20:57:57.937 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - NO callback match! (201 <> 202) 2019-02-15 20:57:57.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 2685: (Callback 202) 2019-02-15 20:57:57.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-02-15 20:57:57.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 2685: callback 202 2019-02-15 20:57:57.940 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=202, payload=CA 00 00 03 2019-02-15 20:57:57.940 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 202, Status = Transmission complete and ACK received(0) 2019-02-15 20:57:57.941 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:57.941 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 2685: Transaction COMPLETED 2019-02-15 20:57:57.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 39ms 2019-02-15 20:57:57.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 2685: Transaction completed 2019-02-15 20:57:57.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2685 DONE 2019-02-15 20:57:57.943 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:57:57.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-02-15 20:57:57.947 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 21 00 04 00 08 1B 98 81 CB D9 2C 7B 59 46 5D BC 53 D4 D0 7A C9 20 91 2D 24 C4 CF C7 B3 7B 84 5A B0 5D 2019-02-15 20:57:57.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 1B 98 81 CB D9 2C 7B 59 46 5D BC 53 D4 D0 7A C9 20 91 2D 24 C4 CF C7 B3 7B 84 5A B0 2019-02-15 20:57:57.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 1B 98 81 CB D9 2C 7B 59 46 5D BC 53 D4 D0 7A C9 20 91 2D 24 C4 CF C7 B3 7B 84 5A B0 2019-02-15 20:57:57.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 20:57:57.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE) 2019-02-15 20:57:57.952 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false 2019-02-15 20:57:57.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Decapsulating COMMAND_CLASS_SECURITY 2019-02-15 20:57:57.953 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: SECURITY_RXD 60 0D 02 01 26 03 63 2019-02-15 20:57:57.954 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-02-15 20:57:57.954 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 2 2019-02-15 20:57:57.955 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_SWITCH_MULTILEVEL V3 SWITCH_MULTILEVEL_REPORT 2019-02-15 20:57:57.955 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 8: Switch Multi Level report, value = 99 2019-02-15 20:57:57.956 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 20:57:57.956 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint = 2, command class = COMMAND_CLASS_SWITCH_MULTILEVEL, value = 99 2019-02-15 20:57:57.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:9a59f25e:node8:switch_dimmer2 to 100 [PercentType] 2019-02-15 20:57:57.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1. 2019-02-15 20:57:57.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e1d201e. 2019-02-15 20:57:57.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e1d201e. 2019-02-15 20:57:57.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:2683 DONE 2019-02-15 20:57:57.959 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-02-15 20:57:57.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-02-15 20:57:57.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 20:57:57.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 20:57:57.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. Sending ON from Fibaro keyfob to Fibaro FGS-222 double relay switch, Node 27, endpoint 1 (association set in Habmin): Result: Relay 1 is turning on, relay 2 remains off, information in log. 2019-02-15 21:01:27.800 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 1B 03 25 03 FF 33 2019-02-15 21:01:27.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 03 25 03 FF 2019-02-15 21:01:27.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 03 25 03 FF 2019-02-15 21:01:27.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 21:01:27.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE) 2019-02-15 21:01:27.806 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false 2019-02-15 21:01:27.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 2019-02-15 21:01:27.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY not supported 2019-02-15 21:01:27.807 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 2019-02-15 21:01:27.807 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 27: Switch Binary report, value = 255 2019-02-15 21:01:27.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 21:01:27.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255 2019-02-15 21:01:27.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:9a59f25e:node27:switch_binary to ON [OnOffType] 2019-02-15 21:01:27.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1. 2019-02-15 21:01:27.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@798fe372. 2019-02-15 21:01:27.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 21:01:27.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 21:01:27.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 21:01:27.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. Sending ON from Fibaro keyfob to Fibaro FGS-222 double relay switch, Node 27, endpoint 2 (association set in Habmin): Result: Relay 1 is turning on, relay 2 remains off, information in log. 2019-02-15 21:03:05.227 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 1B 03 25 03 FF 33 2019-02-15 21:03:05.230 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 03 25 03 FF 2019-02-15 21:03:05.232 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=27, callback=0, payload=00 1B 03 25 03 FF 2019-02-15 21:03:05.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 21:03:05.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Application Command Request (ALIVE:DONE) 2019-02-15 21:03:05.234 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: resetResendCount initComplete=true isDead=false 2019-02-15 21:03:05.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 2019-02-15 21:03:05.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY not supported 2019-02-15 21:03:05.236 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 27: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 2019-02-15 21:03:05.236 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 27: Switch Binary report, value = 255 2019-02-15 21:03:05.237 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 21:03:05.238 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255 2019-02-15 21:03:05.238 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Updating channel state zwave:device:9a59f25e:node27:switch_binary to ON [OnOffType] 2019-02-15 21:03:05.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Commands processed 1. 2019-02-15 21:03:05.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@44d1ca5d. 2019-02-15 21:03:05.240 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 21:03:05.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 21:03:05.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 21:03:05.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. Trying a different model of actuator, just to see if that works with the new remote: Sending ON from Fibaro keyfob to Telldus on/off-switch, configured with association group in habmin; Node 2,(no endpoints presented for that kind of device) : Result: Telldus switch turns on. 2019-02-15 17:33:28.839 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 25 03 FF 2A 2019-02-15 17:33:28.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 25 03 FF 2019-02-15 17:33:28.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 25 03 FF 2019-02-15 17:33:28.844 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 17:33:28.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE) 2019-02-15 17:33:28.846 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false 2019-02-15 17:33:28.846 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 2019-02-15 17:33:28.846 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported 2019-02-15 17:33:28.847 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 2019-02-15 17:33:28.847 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 2: Switch Binary report, value = 255 2019-02-15 17:33:28.847 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 17:33:28.848 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255 2019-02-15 17:33:28.848 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:9a59f25e:node2:switch_binary to ON [OnOffType] 2019-02-15 17:33:28.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1. 2019-02-15 17:33:28.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19a5ce2b. 2019-02-15 17:33:28.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 17:33:28.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 17:33:28.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 17:33:28.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-02-15 17:33:28.896 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 25 03 FF 2A 2019-02-15 17:33:28.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 25 03 FF 2019-02-15 17:33:28.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=2, callback=0, payload=00 02 03 25 03 FF 2019-02-15 17:33:28.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-02-15 17:33:28.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Application Command Request (ALIVE:DONE) 2019-02-15 17:33:28.902 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false 2019-02-15 17:33:28.903 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0 2019-02-15 17:33:28.903 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported 2019-02-15 17:33:28.903 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 2: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT 2019-02-15 17:33:28.904 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 2: Switch Binary report, value = 255 2019-02-15 17:33:28.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-02-15 17:33:28.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_SWITCH_BINARY, value = 255 2019-02-15 17:33:28.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:9a59f25e:node2:switch_binary to ON [OnOffType] 2019-02-15 17:33:28.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Commands processed 1. 2019-02-15 17:33:28.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d9e64bb. 2019-02-15 17:33:28.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 17:33:28.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-02-15 17:33:28.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-02-15 17:33:28.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.