2021-02-01 06:02:55.636 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 40 02 98 40 69 2021-02-01 06:02:55.638 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2021-02-01 06:02:55.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY check internal 2021-02-01 06:02:55.643 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2021-02-01 06:02:55.643 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2021-02-01 06:02:55.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added to secure queue - size 1 2021-02-01 06:02:55.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@c4a7c3. 2021-02-01 06:02:55.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.649 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 40 0A 98 80 51 8A EA BF 77 23 8F 45 25 CD 57 2021-02-01 06:02:55.650 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 11 00 13 40 0A 98 80 51 8A EA BF 77 23 8F 45 25 CD 57 2021-02-01 06:02:55.651 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:02:55.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 993: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 205 2021-02-01 06:02:55.653 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:02:55.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 993: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 205 2021-02-01 06:02:55.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:02:55.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.660 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:02:55.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 993: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 205 2021-02-01 06:02:55.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 993: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 205 2021-02-01 06:02:55.665 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.666 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:02:55.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 993: Advanced to WAIT_REQUEST 2021-02-01 06:02:55.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 993: Transaction not completed 2021-02-01 06:02:55.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.676 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 CD 00 00 03 25 2021-02-01 06:02:55.678 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=205, payload=CD 00 00 03 2021-02-01 06:02:55.679 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=205, payload=CD 00 00 03 2021-02-01 06:02:55.680 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 993: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 205 2021-02-01 06:02:55.680 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.681 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 993: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 205 2021-02-01 06:02:55.681 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 993: (Callback 205) 2021-02-01 06:02:55.682 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:02:55.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 993: callback 205 2021-02-01 06:02:55.683 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=205, payload=CD 00 00 03 2021-02-01 06:02:55.684 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 205, Status = Transmission complete and ACK received(0) 2021-02-01 06:02:55.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.685 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 993: Transaction COMPLETED 2021-02-01 06:02:55.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Response processed after 34ms 2021-02-01 06:02:55.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 993: Transaction completed 2021-02-01 06:02:55.686 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:993 DONE 2021-02-01 06:02:55.687 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:02:55.688 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.688 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.699 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 28 00 04 00 40 22 98 81 12 0B 68 16 FE BF 80 03 FC D4 06 D3 24 E5 B4 F8 5F AA DE D9 4E A4 61 51 34 48 7E 78 A2 C7 48 A9 AB 2021-02-01 06:02:55.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 12 0B 68 16 FE BF 80 03 FC D4 06 D3 24 E5 B4 F8 5F AA DE D9 4E A4 61 51 34 48 7E 78 A2 C7 48 A9 2021-02-01 06:02:55.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 12 0B 68 16 FE BF 80 03 FC D4 06 D3 24 E5 B4 F8 5F AA DE D9 4E A4 61 51 34 48 7E 78 A2 C7 48 A9 2021-02-01 06:02:55.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.704 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.705 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Decapsulating COMMAND_CLASS_SECURITY 2021-02-01 06:02:55.706 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 64: SECURITY_RXD 32 02 A1 64 00 03 9C F3 00 00 00 00 00 00 2021-02-01 06:02:55.707 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:02:55.708 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_METER V0 METER_REPORT 2021-02-01 06:02:55.709 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 64: Meter: Type=Electric(1), Scale=kWh(0), Value=236.787 2021-02-01 06:02:55.709 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:02:55.710 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=236.787 2021-02-01 06:02:55.710 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating channel state zwave:device:5d681cf1:node64:meter_kwh to 236.787 [DecimalType] 2021-02-01 06:02:55.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fe1133. 2021-02-01 06:02:55.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.735 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 40 02 98 40 69 2021-02-01 06:02:55.737 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.738 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.740 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2021-02-01 06:02:55.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY check internal 2021-02-01 06:02:55.741 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2021-02-01 06:02:55.742 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2021-02-01 06:02:55.743 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added to secure queue - size 1 2021-02-01 06:02:55.743 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.744 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@a54c4a. 2021-02-01 06:02:55.744 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.745 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.745 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.746 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.746 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 40 0A 98 80 81 67 F0 7D 55 12 1E 9E 25 CE E8 2021-02-01 06:02:55.747 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 11 00 13 40 0A 98 80 81 67 F0 7D 55 12 1E 9E 25 CE E8 2021-02-01 06:02:55.748 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:02:55.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 994: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 206 2021-02-01 06:02:55.750 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:02:55.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 994: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 206 2021-02-01 06:02:55.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:02:55.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.756 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:02:55.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 994: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 206 2021-02-01 06:02:55.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 994: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 206 2021-02-01 06:02:55.761 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.761 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:02:55.762 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 994: Advanced to WAIT_REQUEST 2021-02-01 06:02:55.762 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 994: Transaction not completed 2021-02-01 06:02:55.762 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.773 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 CE 00 00 02 27 2021-02-01 06:02:55.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=206, payload=CE 00 00 02 2021-02-01 06:02:55.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=206, payload=CE 00 00 02 2021-02-01 06:02:55.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 994: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 206 2021-02-01 06:02:55.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 994: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 206 2021-02-01 06:02:55.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 994: (Callback 206) 2021-02-01 06:02:55.777 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:02:55.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 994: callback 206 2021-02-01 06:02:55.778 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=206, payload=CE 00 00 02 2021-02-01 06:02:55.779 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 206, Status = Transmission complete and ACK received(0) 2021-02-01 06:02:55.779 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.779 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 994: Transaction COMPLETED 2021-02-01 06:02:55.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Response processed after 32ms 2021-02-01 06:02:55.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 994: Transaction completed 2021-02-01 06:02:55.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:994 DONE 2021-02-01 06:02:55.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:02:55.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.795 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 28 00 04 00 40 22 98 81 76 59 0B 8A A7 F0 EA DA D3 D4 E4 6C 89 C3 26 8C DB D0 1C 8E 24 63 5B 81 2F 8C EE DC EA A1 7D CA 67 2021-02-01 06:02:55.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 76 59 0B 8A A7 F0 EA DA D3 D4 E4 6C 89 C3 26 8C DB D0 1C 8E 24 63 5B 81 2F 8C EE DC EA A1 7D CA 2021-02-01 06:02:55.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 76 59 0B 8A A7 F0 EA DA D3 D4 E4 6C 89 C3 26 8C DB D0 1C 8E 24 63 5B 81 2F 8C EE DC EA A1 7D CA 2021-02-01 06:02:55.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.799 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.799 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Decapsulating COMMAND_CLASS_SECURITY 2021-02-01 06:02:55.801 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 64: SECURITY_RXD 32 02 A1 6C 00 00 00 00 00 00 00 00 00 00 2021-02-01 06:02:55.802 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:02:55.802 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_METER V0 METER_REPORT 2021-02-01 06:02:55.803 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 64: Meter: Type=Electric(1), Scale=kVAh(1), Value=0E+1 2021-02-01 06:02:55.803 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:02:55.804 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1 2021-02-01 06:02:55.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@a5b947. 2021-02-01 06:02:55.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.835 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 40 02 98 40 69 2021-02-01 06:02:55.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.839 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.839 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2021-02-01 06:02:55.839 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY check internal 2021-02-01 06:02:55.840 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2021-02-01 06:02:55.840 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2021-02-01 06:02:55.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added to secure queue - size 1 2021-02-01 06:02:55.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11aef2f. 2021-02-01 06:02:55.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.844 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 40 0A 98 80 AD F5 54 B8 C4 05 14 02 25 CF 26 2021-02-01 06:02:55.844 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 11 00 13 40 0A 98 80 AD F5 54 B8 C4 05 14 02 25 CF 26 2021-02-01 06:02:55.845 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:02:55.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 995: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 207 2021-02-01 06:02:55.847 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:02:55.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 995: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 207 2021-02-01 06:02:55.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:02:55.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.853 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:02:55.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 995: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 207 2021-02-01 06:02:55.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 995: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 207 2021-02-01 06:02:55.857 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.858 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:02:55.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 995: Advanced to WAIT_REQUEST 2021-02-01 06:02:55.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 995: Transaction not completed 2021-02-01 06:02:55.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.870 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 CF 00 00 02 26 2021-02-01 06:02:55.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=207, payload=CF 00 00 02 2021-02-01 06:02:55.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=207, payload=CF 00 00 02 2021-02-01 06:02:55.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 995: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 207 2021-02-01 06:02:55.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 995: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 207 2021-02-01 06:02:55.874 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 995: (Callback 207) 2021-02-01 06:02:55.874 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:02:55.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 995: callback 207 2021-02-01 06:02:55.875 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=207, payload=CF 00 00 02 2021-02-01 06:02:55.876 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 207, Status = Transmission complete and ACK received(0) 2021-02-01 06:02:55.876 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.877 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 995: Transaction COMPLETED 2021-02-01 06:02:55.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Response processed after 32ms 2021-02-01 06:02:55.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 995: Transaction completed 2021-02-01 06:02:55.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:995 DONE 2021-02-01 06:02:55.878 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:02:55.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.892 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 28 00 04 00 40 22 98 81 5A B4 D8 C6 1D 9A CB 75 C5 A7 16 65 D3 5D C0 CE E8 FF 55 49 77 B9 F4 AD 85 AC B9 51 9B 12 5B B9 C6 2021-02-01 06:02:55.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 5A B4 D8 C6 1D 9A CB 75 C5 A7 16 65 D3 5D C0 CE E8 FF 55 49 77 B9 F4 AD 85 AC B9 51 9B 12 5B B9 2021-02-01 06:02:55.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 5A B4 D8 C6 1D 9A CB 75 C5 A7 16 65 D3 5D C0 CE E8 FF 55 49 77 B9 F4 AD 85 AC B9 51 9B 12 5B B9 2021-02-01 06:02:55.896 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.896 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.896 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.897 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Decapsulating COMMAND_CLASS_SECURITY 2021-02-01 06:02:55.898 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 64: SECURITY_RXD 32 02 21 64 00 01 44 BD 04 B0 00 01 44 BD 2021-02-01 06:02:55.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:02:55.899 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_METER V0 METER_REPORT 2021-02-01 06:02:55.900 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 64: Meter: Type=Electric(1), Scale=kWh(0), Value=83.133 2021-02-01 06:02:55.900 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:02:55.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=83.133 2021-02-01 06:02:55.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating channel state zwave:device:5d681cf1:node64:meter_kwh to 83.133 [DecimalType] 2021-02-01 06:02:55.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f40a36. 2021-02-01 06:02:55.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.935 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 40 02 98 40 69 2021-02-01 06:02:55.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 02 98 40 2021-02-01 06:02:55.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0 2021-02-01 06:02:55.940 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY check internal 2021-02-01 06:02:55.940 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_SECURITY V1 SECURITY_NONCE_GET 2021-02-01 06:02:55.941 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1 2021-02-01 06:02:55.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added to secure queue - size 1 2021-02-01 06:02:55.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:55.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@bbd9fe. 2021-02-01 06:02:55.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:55.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.945 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 40 0A 98 80 19 8E 97 73 37 F3 B6 FE 25 D0 A5 2021-02-01 06:02:55.946 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 11 00 13 40 0A 98 80 19 8E 97 73 37 F3 B6 FE 25 D0 A5 2021-02-01 06:02:55.946 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:02:55.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 996: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 208 2021-02-01 06:02:55.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:02:55.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:02:55.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 996: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 208 2021-02-01 06:02:55.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:02:55.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.953 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.955 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:02:55.956 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 996: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 208 2021-02-01 06:02:55.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 996: [WAIT_RESPONSE] priority=NonceResponse, requiresResponse=true, callback: 208 2021-02-01 06:02:55.959 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:02:55.960 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:02:55.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 996: Advanced to WAIT_REQUEST 2021-02-01 06:02:55.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 996: Transaction not completed 2021-02-01 06:02:55.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:02:55.972 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D0 00 00 02 39 2021-02-01 06:02:55.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=208, payload=D0 00 00 02 2021-02-01 06:02:55.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=208, payload=D0 00 00 02 2021-02-01 06:02:55.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 996: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 208 2021-02-01 06:02:55.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:02:55.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 996: [WAIT_REQUEST] priority=NonceResponse, requiresResponse=true, callback: 208 2021-02-01 06:02:55.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 996: (Callback 208) 2021-02-01 06:02:55.976 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:02:55.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 996: callback 208 2021-02-01 06:02:55.977 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=208, payload=D0 00 00 02 2021-02-01 06:02:55.978 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 208, Status = Transmission complete and ACK received(0) 2021-02-01 06:02:55.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.978 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 996: Transaction COMPLETED 2021-02-01 06:02:55.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Response processed after 32ms 2021-02-01 06:02:55.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 996: Transaction completed 2021-02-01 06:02:55.980 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:996 DONE 2021-02-01 06:02:55.980 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:02:55.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:55.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:02:55.994 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 28 00 04 00 40 22 98 81 91 AA B0 6F 38 FB C2 3D BA 36 55 25 6E C7 11 52 FB 71 68 6A 77 0B E4 19 2F 91 F1 53 F9 B8 BB DF 56 2021-02-01 06:02:55.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 91 AA B0 6F 38 FB C2 3D BA 36 55 25 6E C7 11 52 FB 71 68 6A 77 0B E4 19 2F 91 F1 53 F9 B8 BB DF 2021-02-01 06:02:55.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 22 98 81 91 AA B0 6F 38 FB C2 3D BA 36 55 25 6E C7 11 52 FB 71 68 6A 77 0B E4 19 2F 91 F1 53 F9 B8 BB DF 2021-02-01 06:02:55.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:02:55.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:DONE) 2021-02-01 06:02:55.998 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: resetResendCount initComplete=true isDead=false 2021-02-01 06:02:55.999 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Decapsulating COMMAND_CLASS_SECURITY 2021-02-01 06:02:56.000 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 64: SECURITY_RXD 32 02 21 74 00 00 00 00 00 00 00 00 00 00 2021-02-01 06:02:56.001 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:02:56.001 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_METER V0 METER_REPORT 2021-02-01 06:02:56.002 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 64: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 2021-02-01 06:02:56.002 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:02:56.003 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1 2021-02-01 06:02:56.003 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating channel state zwave:device:5d681cf1:node64:meter_watts to 0 [DecimalType] 2021-02-01 06:02:56.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:02:56.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1612eaf. 2021-02-01 06:02:56.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:56.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:02:56.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:02:56.008 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:03:39.558 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 15 0A 32 02 21 34 00 00 00 B1 00 00 60 2021-02-01 06:03:39.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=21, callback=0, payload=00 15 0A 32 02 21 34 00 00 00 B1 00 00 2021-02-01 06:03:39.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=21, callback=0, payload=00 15 0A 32 02 21 34 00 00 00 B1 00 00 2021-02-01 06:03:39.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:03:39.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Application Command Request (ALIVE:DONE) 2021-02-01 06:03:39.569 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 21: resetResendCount initComplete=true isDead=false 2021-02-01 06:03:39.570 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 21: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:03:39.570 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 21: SECURITY not supported 2021-02-01 06:03:39.571 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 21: Received COMMAND_CLASS_METER V3 METER_REPORT 2021-02-01 06:03:39.572 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 21: Meter: Type=Electric(1), Scale=W(2), Value=17.7 2021-02-01 06:03:39.574 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:03:39.575 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=17.7 2021-02-01 06:03:39.576 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Updating channel state zwave:device:5d681cf1:node21:meter_watts to 17.7 [DecimalType] 2021-02-01 06:03:39.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Commands processed 1. 2021-02-01 06:03:39.578 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@108d53b. 2021-02-01 06:03:39.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:03:39.580 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:03:39.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:03:39.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:16.253 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 13 0A 32 02 21 34 00 4E 98 82 00 00 83 2021-02-01 06:04:16.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 34 00 4E 98 82 00 00 2021-02-01 06:04:16.256 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 34 00 4E 98 82 00 00 2021-02-01 06:04:16.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:04:16.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE) 2021-02-01 06:04:16.258 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2021-02-01 06:04:16.258 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:04:16.259 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2021-02-01 06:04:16.259 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT 2021-02-01 06:04:16.260 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=W(2), Value=515085 2021-02-01 06:04:16.260 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:04:16.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=515085 2021-02-01 06:04:16.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:5d681cf1:node19:meter_watts to 515085 [DecimalType] 2021-02-01 06:04:16.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2021-02-01 06:04:16.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@149c4d6. 2021-02-01 06:04:16.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:16.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:16.266 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:16.266 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:17.258 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 13 0A 32 02 21 34 00 00 01 60 00 00 B6 2021-02-01 06:04:17.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 34 00 00 01 60 00 00 2021-02-01 06:04:17.261 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 0A 32 02 21 34 00 00 01 60 00 00 2021-02-01 06:04:17.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:04:17.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE) 2021-02-01 06:04:17.264 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2021-02-01 06:04:17.264 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_METER, endpoint 0 2021-02-01 06:04:17.265 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2021-02-01 06:04:17.266 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_METER V3 METER_REPORT 2021-02-01 06:04:17.267 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 19: Meter: Type=Electric(1), Scale=W(2), Value=35.2 2021-02-01 06:04:17.268 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMeterValueEvent 2021-02-01 06:04:17.269 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=35.2 2021-02-01 06:04:17.270 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:5d681cf1:node19:meter_watts to 35.2 [DecimalType] 2021-02-01 06:04:17.271 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2021-02-01 06:04:17.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12f8be8. 2021-02-01 06:04:17.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:17.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:17.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:17.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:39.296 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 1F 06 31 05 01 22 00 1D E4 2021-02-01 06:04:39.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=31, callback=0, payload=00 1F 06 31 05 01 22 00 1D 2021-02-01 06:04:39.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=31, callback=0, payload=00 1F 06 31 05 01 22 00 1D 2021-02-01 06:04:39.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:04:39.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 31: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2021-02-01 06:04:39.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 31: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2021-02-01 06:04:39.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 31: SECURITY not supported 2021-02-01 06:04:39.302 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 31: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2021-02-01 06:04:39.303 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 31: Sensor Type = Temperature(1), Scale = 0 2021-02-01 06:04:39.304 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 31: Sensor Value = 2.9 2021-02-01 06:04:39.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 31: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2021-02-01 06:04:39.306 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 31: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2.9 2021-02-01 06:04:39.307 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 31: Updating channel state zwave:device:5d681cf1:node31:sensor_temperature to 2.9 °C [QuantityType] 2021-02-01 06:04:39.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 31: Commands processed 1. 2021-02-01 06:04:39.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 31: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14bd9b4. 2021-02-01 06:04:39.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:39.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:39.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:39.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:42.973 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 3F 05 31 05 05 01 31 CB 2021-02-01 06:04:42.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 05 31 05 05 01 31 2021-02-01 06:04:42.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 05 31 05 05 01 31 2021-02-01 06:04:42.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:04:42.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Application Command Request (ALIVE:DONE) 2021-02-01 06:04:42.979 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false 2021-02-01 06:04:42.979 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2021-02-01 06:04:42.980 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported 2021-02-01 06:04:42.981 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 63: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2021-02-01 06:04:42.981 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 63: Sensor Type = RelativeHumidity(5), Scale = 0 2021-02-01 06:04:42.982 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 63: Sensor Value = 49 2021-02-01 06:04:42.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2021-02-01 06:04:42.984 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=49 2021-02-01 06:04:42.985 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 63: Sensor conversion not performed for RELATIVE_HUMIDITY. 2021-02-01 06:04:42.986 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Updating channel state zwave:device:5d681cf1:node63:sensor_relhumidity to 49 [DecimalType] 2021-02-01 06:04:42.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Commands processed 1. 2021-02-01 06:04:42.989 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@df6b2b. 2021-02-01 06:04:42.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:42.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:42.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:42.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:45.135 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 3F 03 80 03 FF B2 2021-02-01 06:04:45.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 03 80 03 FF 2021-02-01 06:04:45.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 03 80 03 FF 2021-02-01 06:04:45.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:04:45.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Application Command Request (ALIVE:DONE) 2021-02-01 06:04:45.141 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false 2021-02-01 06:04:45.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0 2021-02-01 06:04:45.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported 2021-02-01 06:04:45.143 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 63: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT 2021-02-01 06:04:45.144 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 63: Battery report value = 255 2021-02-01 06:04:45.145 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 63: BATTERY LOW! 2021-02-01 06:04:45.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2021-02-01 06:04:45.147 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=0 2021-02-01 06:04:45.148 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Updating channel state zwave:device:5d681cf1:node63:battery-level to 0 [DecimalType] 2021-02-01 06:04:45.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Commands processed 1. 2021-02-01 06:04:45.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1696892. 2021-02-01 06:04:45.152 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:45.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:45.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:45.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:49.193 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 3F 02 84 07 4D 2021-02-01 06:04:49.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 02 84 07 2021-02-01 06:04:49.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 02 84 07 2021-02-01 06:04:49.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:04:49.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Application Command Request (ALIVE:DONE) 2021-02-01 06:04:49.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false 2021-02-01 06:04:49.199 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Is awake with 0 messages in the queue 2021-02-01 06:04:49.199 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Start sleep timer at 1000ms 2021-02-01 06:04:49.200 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2021-02-01 06:04:49.215 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 63: Node Status event - Node is AWAKE 2021-02-01 06:04:49.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Commands processed 1. 2021-02-01 06:04:49.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e0d09c. 2021-02-01 06:04:49.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:49.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:04:49.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:49.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:49.700 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: WakeupTimerTask 0 Messages waiting, state DONE 2021-02-01 06:04:50.200 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: WakeupTimerTask 0 Messages waiting, state DONE 2021-02-01 06:04:50.201 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: No more messages, go back to sleep 2021-02-01 06:04:50.201 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 63: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2021-02-01 06:04:50.202 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported 2021-02-01 06:04:50.202 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2021-02-01 06:04:50.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@182afeb 2021-02-01 06:04:50.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Bump transaction 997 priority from Immediate to Immediate 2021-02-01 06:04:50.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Adding to device queue 2021-02-01 06:04:50.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Added 997 to queue - size 6 2021-02-01 06:04:50.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:50.205 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 3F 02 84 08 25 D1 A0 2021-02-01 06:04:50.206 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 63: Sending REQUEST Message = 01 09 00 13 3F 02 84 08 25 D1 A0 2021-02-01 06:04:50.206 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:04:50.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 997: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 209 2021-02-01 06:04:50.208 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:04:50.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:04:50.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:04:50.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 997: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 209 2021-02-01 06:04:50.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:04:50.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:50.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:04:50.214 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:04:50.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:04:50.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:04:50.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 997: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 209 2021-02-01 06:04:50.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:04:50.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 997: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 209 2021-02-01 06:04:50.217 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:04:50.217 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: sentData successfully placed on stack. 2021-02-01 06:04:50.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 997: Advanced to WAIT_REQUEST 2021-02-01 06:04:50.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 997: Transaction not completed 2021-02-01 06:04:50.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:50.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:04:50.501 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D1 00 00 1D 27 2021-02-01 06:04:50.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=209, payload=D1 00 00 1D 2021-02-01 06:04:50.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=209, payload=D1 00 00 1D 2021-02-01 06:04:50.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 997: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 209 2021-02-01 06:04:50.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:04:50.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 997: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 209 2021-02-01 06:04:50.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 997: (Callback 209) 2021-02-01 06:04:50.505 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:04:50.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 997: callback 209 2021-02-01 06:04:50.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=209, payload=D1 00 00 1D 2021-02-01 06:04:50.506 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: SendData Request. CallBack ID = 209, Status = Transmission complete and ACK received(0) 2021-02-01 06:04:50.506 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false 2021-02-01 06:04:50.506 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 997: Transaction COMPLETED 2021-02-01 06:04:50.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Response processed after 300ms 2021-02-01 06:04:50.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 997: Transaction completed 2021-02-01 06:04:50.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: notifyTransactionResponse TID:997 DONE 2021-02-01 06:04:50.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:04:50.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 997: Transaction event listener: DONE: DONE -> 2021-02-01 06:04:50.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:04:50.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:04:50.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Went to sleep COMPLETE 2021-02-01 06:05:08.183 [INFO ] [penhab.core.model.script.org.openhab] - getDeltaTimeForNextWakeup delta1: [1440] 2021-02-01 06:05:08.189 [INFO ] [penhab.core.model.script.org.openhab] - WeckerSL: timeForNextWakeupAfterTrigger: [1440] 2021-02-01 06:05:08.196 [INFO ] [penhab.core.model.script.org.openhab] - WeckerSL: neuer Timer aus Callback [org.openhab.core.model.script.internal.actions.TimerImpl@b76a23] 2021-02-01 06:05:08.214 [INFO ] [penhab.core.model.script.org.openhab] - WeckerSL: nicht gestartet, weil timeSensorChanged: 2021-02-01T05:52:48.884859+01:00[Europe/Berlin] 2021-02-01 06:06:48.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@17e0774 2021-02-01 06:06:48.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: Adding to device queue 2021-02-01 06:06:48.892 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: Added 998 to queue - size 6 2021-02-01 06:06:48.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:06:48.894 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 51 38 D2 41 2021-02-01 06:06:48.895 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 51 38 D2 41 2021-02-01 06:06:48.896 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:06:48.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 998: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 210 2021-02-01 06:06:48.898 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:06:48.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:06:48.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:06:48.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 998: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 210 2021-02-01 06:06:48.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:06:48.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:06:48.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:06:49.862 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 51 01 AA 2021-02-01 06:06:49.863 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:06:49.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:06:49.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 998: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 210 2021-02-01 06:06:49.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:06:49.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 998: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 210 2021-02-01 06:06:49.867 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:06:49.868 [DEBUG] [age.AssignSucReturnRouteMessageClass] - NODE 56: Got AssignSucReturnRoute response. 2021-02-01 06:06:49.869 [DEBUG] [age.AssignSucReturnRouteMessageClass] - NODE 56: AssignSucReturnRoute operation started. 2021-02-01 06:06:49.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 998: Advanced to WAIT_REQUEST 2021-02-01 06:06:49.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: TID 998: Transaction not completed 2021-02-01 06:06:49.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:06:49.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:06:54.870 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 56: TID 998: Timeout at state WAIT_REQUEST. 3 retries remaining. 2021-02-01 06:06:54.872 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 998: Transaction is current transaction, so clearing!!!!! 2021-02-01 06:06:54.873 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 998: Transaction CANCELLED 2021-02-01 06:06:54.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 56: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:06:54.874 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 56: notifyTransactionResponse TID:998 CANCELLED 2021-02-01 06:06:54.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:06:54.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 998: Transaction event listener: DONE: CANCELLED -> 2021-02-01 06:06:54.877 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 56: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@44a12b 2021-02-01 06:06:57.767 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 06 03 20 01 63 B5 2021-02-01 06:06:57.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 03 20 01 63 2021-02-01 06:06:57.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 03 20 01 63 2021-02-01 06:06:57.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:06:57.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE) 2021-02-01 06:06:57.772 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false 2021-02-01 06:06:57.773 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_BASIC, endpoint 0 2021-02-01 06:06:57.773 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported 2021-02-01 06:06:57.774 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_BASIC V1 BASIC_SET 2021-02-01 06:06:57.774 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 6: Basic report, value = 99 2021-02-01 06:06:57.776 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2021-02-01 06:06:57.776 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=99 2021-02-01 06:06:57.777 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:5d681cf1:node6:switch_binary to ON [OnOffType] 2021-02-01 06:06:57.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1. 2021-02-01 06:06:57.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1cd334d. 2021-02-01 06:06:57.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:06:57.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:06:57.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:06:57.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:06:57.791 [INFO ] [penhab.core.model.script.org.openhab] - LichtschalterSchlafzimmer changed to ON 2021-02-01 06:06:58.744 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 51 D2 00 79 2021-02-01 06:06:58.746 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=210, payload=D2 00 2021-02-01 06:06:58.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=210, payload=D2 00 2021-02-01 06:06:58.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:06:58.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2021-02-01 06:06:58.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null 2021-02-01 06:06:58.750 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AssignSucReturnRoute[81], type=Request[0], dest=0, callback=210, payload=D2 00 2021-02-01 06:06:58.751 [DEBUG] [age.AssignSucReturnRouteMessageClass] - NODE {}: transaction not correlated for AssignSucReturnRouteMessageClass 2021-02-01 06:06:58.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:06:58.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:03.723 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Polling... 2021-02-01 06:07:16.707 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 59: Polling... 2021-02-01 06:07:16.708 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 59: Polling deferred until initialisation complete 2021-02-01 06:07:51.769 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update received 2021-02-01 06:07:51.780 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_84_3_0000FF00 to 50 (BigDecimal) 2021-02-01 06:07:51.782 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_100_1_wo to 0 (BigDecimal) 2021-02-01 06:07:51.783 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_91_2 to 25 (BigDecimal) 2021-02-01 06:07:51.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_92_1 to 9 (BigDecimal) 2021-02-01 06:07:51.785 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_90_1 to 1 (BigDecimal) 2021-02-01 06:07:51.787 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored group_1 to [controller] (ArrayList) 2021-02-01 06:07:51.788 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored group_2 to [] (EmptyList) 2021-02-01 06:07:51.789 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_255_1_wo to 0 (BigDecimal) 2021-02-01 06:07:51.790 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_83_3_0000FF00 to 20 (BigDecimal) 2021-02-01 06:07:51.791 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored switchall_mode to 255.0 (BigDecimal) 2021-02-01 06:07:51.792 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update set action_reinit to true (Boolean) 2021-02-01 06:07:51.792 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Re-initialising node! 2021-02-01 06:07:51.857 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:51.860 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_112_4 to 600 (BigDecimal) 2021-02-01 06:07:51.860 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 64: Init node thread start 2021-02-01 06:07:51.862 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_113_4 to 600 (BigDecimal) 2021-02-01 06:07:51.863 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_84_3_000000FF to 50 (BigDecimal) 2021-02-01 06:07:51.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_84_3_00FF0000 to 50 (BigDecimal) 2021-02-01 06:07:51.866 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_111_4 to 30 (BigDecimal) 2021-02-01 06:07:51.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_20_1 to 0 (BigDecimal) 2021-02-01 06:07:51.868 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_84_3 to 3289650 (BigDecimal) 2021-02-01 06:07:51.869 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_83_3 to 1774619 (BigDecimal) 2021-02-01 06:07:51.870 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_83_3_000000FF to 27 (BigDecimal) 2021-02-01 06:07:51.871 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_83_3_00FF0000 to 27 (BigDecimal) 2021-02-01 06:07:51.872 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_252_1 to 0 (BigDecimal) 2021-02-01 06:07:51.873 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_81_1 to 2 (BigDecimal) 2021-02-01 06:07:51.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_80_1 to 0 (BigDecimal) 2021-02-01 06:07:51.875 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_110_1_wo to 0 (BigDecimal) 2021-02-01 06:07:51.876 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored action_heal to false (Boolean) 2021-02-01 06:07:51.876 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_101_4 to 0 (BigDecimal) 2021-02-01 06:07:51.877 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_102_4 to 0 (BigDecimal) 2021-02-01 06:07:51.878 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_3_1 to 1 (BigDecimal) 2021-02-01 06:07:51.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_33_4_wo to 0 (BigDecimal) 2021-02-01 06:07:51.880 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored config_103_4 to 0 (BigDecimal) 2021-02-01 06:07:51.880 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Configuration update ignored node_id to 64 (BigDecimal) 2021-02-01 06:07:51.904 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 64: Serializing from file /var/lib/openhab/zwave/network_ffeb5ae7__node_64.xml 2021-02-01 06:07:51.915 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 64: Error serializing from file: file does not exist. 2021-02-01 06:07:51.916 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Starting initialisation from EMPTYNODE 2021-02-01 06:07:51.918 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 64: Init node thread finished 2021-02-01 06:07:51.919 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to IDENTIFY_NODE 2021-02-01 06:07:51.920 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:51.921 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: Initialisation starting 2021-02-01 06:07:51.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@126acc7 2021-02-01 06:07:51.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 999 to queue - size 1 2021-02-01 06:07:51.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:51.937 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 40 FA 2021-02-01 06:07:51.938 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 40 FA 2021-02-01 06:07:51.939 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:51.939 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:51.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:51.943 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 10 01 ED 2021-02-01 06:07:51.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:51.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 999: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:51.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 10 01 2021-02-01 06:07:51.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:51.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:51.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 10 01 2021-02-01 06:07:51.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 999: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:51.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:51.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 999: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:51.948 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=IdentifyNode[65], type=Response[1], dest=255, callback=0, payload=D3 9C 01 04 10 01 2021-02-01 06:07:51.948 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: ProtocolInfo 2021-02-01 06:07:51.949 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Listening = true 2021-02-01 06:07:51.949 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Routing = true 2021-02-01 06:07:51.950 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Beaming = true 2021-02-01 06:07:51.950 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Version = 4 2021-02-01 06:07:51.950 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: FLIRS = false 2021-02-01 06:07:51.951 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Security = false 2021-02-01 06:07:51.951 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Max Baud = 40000 2021-02-01 06:07:51.951 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Basic = BASIC_TYPE_ROUTING_SLAVE 2021-02-01 06:07:51.952 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Generic = GENERIC_TYPE_SWITCH_BINARY 2021-02-01 06:07:51.952 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 64: Specific = SPECIFIC_TYPE_POWER_SWITCH_BINARY 2021-02-01 06:07:51.952 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_NO_OPERATION 2021-02-01 06:07:51.953 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_NO_OPERATION, endpoint 0 created 2021-02-01 06:07:51.953 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set. Enabling extra functionality. 2021-02-01 06:07:51.958 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_NO_OPERATION to the list of supported command classes. 2021-02-01 06:07:51.958 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_BASIC 2021-02-01 06:07:51.959 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_BASIC, endpoint 0 created 2021-02-01 06:07:51.959 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_BASIC to the list of supported command classes. 2021-02-01 06:07:51.960 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 999: Transaction COMPLETED 2021-02-01 06:07:51.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 16ms 2021-02-01 06:07:51.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 999: Transaction completed 2021-02-01 06:07:51.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:999 DONE 2021-02-01 06:07:51.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:51.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 999: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:51.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:51.964 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1e158d3 2021-02-01 06:07:51.964 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:51.965 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to PING 2021-02-01 06:07:51.965 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:51.965 [DEBUG] [ndclass.ZWaveNoOperationCommandClass] - NODE 64: Creating new message for command NO_OPERATION_PING 2021-02-01 06:07:51.966 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:51.966 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY not supported 2021-02-01 06:07:51.967 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_NO_OPERATION is NOT required to be secured 2021-02-01 06:07:51.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1dec7b7 2021-02-01 06:07:51.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:51.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1000 to queue - size 6 2021-02-01 06:07:51.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:51.970 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 13 40 01 00 25 D3 53 2021-02-01 06:07:51.970 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 08 00 13 40 01 00 25 D3 53 2021-02-01 06:07:51.971 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:51.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1000: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 211 2021-02-01 06:07:51.972 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:51.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:51.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:51.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1000: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 211 2021-02-01 06:07:51.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:51.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:51.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:51.978 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:51.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:51.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:51.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1000: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 211 2021-02-01 06:07:51.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:51.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1000: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 211 2021-02-01 06:07:51.984 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:51.984 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:51.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1000: Advanced to WAIT_REQUEST 2021-02-01 06:07:51.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1000: Transaction not completed 2021-02-01 06:07:51.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:51.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:51.995 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D3 00 00 02 3A 2021-02-01 06:07:51.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=211, payload=D3 00 00 02 2021-02-01 06:07:51.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=211, payload=D3 00 00 02 2021-02-01 06:07:51.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1000: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 211 2021-02-01 06:07:51.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:51.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1000: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 211 2021-02-01 06:07:51.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1000: (Callback 211) 2021-02-01 06:07:51.999 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:51.999 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1000: callback 211 2021-02-01 06:07:51.999 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=211, payload=D3 00 00 02 2021-02-01 06:07:52.000 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 211, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.000 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1000: Transaction COMPLETED 2021-02-01 06:07:52.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Response processed after 30ms 2021-02-01 06:07:52.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1000: Transaction completed 2021-02-01 06:07:52.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1000 DONE 2021-02-01 06:07:52.002 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1000: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.003 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@dc52e8 2021-02-01 06:07:52.003 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.004 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to REQUEST_NIF 2021-02-01 06:07:52.004 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@fa9520 2021-02-01 06:07:52.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1001 to queue - size 6 2021-02-01 06:07:52.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.007 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 40 DB 2021-02-01 06:07:52.008 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 40 DB 2021-02-01 06:07:52.008 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1001: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.009 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1001: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.016 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 2021-02-01 06:07:52.016 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.017 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1001: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1001: [WAIT_RESPONSE] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.019 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.019 [DEBUG] [lmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack. 2021-02-01 06:07:52.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1001: Advanced to WAIT_DATA 2021-02-01 06:07:52.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1001: Transaction not completed 2021-02-01 06:07:52.021 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.021 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.046 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 49 84 40 0A 04 10 01 5E 86 72 98 EF 5A 82 78 2021-02-01 06:07:52.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=64, callback=132, payload=84 40 0A 04 10 01 5E 86 72 98 EF 5A 82 2021-02-01 06:07:52.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=64, callback=132, payload=84 40 0A 04 10 01 5E 86 72 98 EF 5A 82 2021-02-01 06:07:52.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1001: [WAIT_DATA] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1001: [WAIT_DATA] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1001: (Callback 0) 2021-02-01 06:07:52.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1001: callback 0 2021-02-01 06:07:52.050 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=64, callback=132, payload=84 40 0A 04 10 01 5E 86 72 98 EF 5A 82 2021-02-01 06:07:52.050 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update request. Node information received. Transaction TID 1001: [WAIT_DATA] priority=Controller, requiresResponse=true, callback: 0 2021-02-01 06:07:52.051 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.051 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created 2021-02-01 06:07:52.052 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO. 2021-02-01 06:07:52.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes. 2021-02-01 06:07:52.054 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_VERSION 2021-02-01 06:07:52.054 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_VERSION, endpoint 0 created 2021-02-01 06:07:52.055 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update is adding command class COMMAND_CLASS_VERSION. 2021-02-01 06:07:52.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes. 2021-02-01 06:07:52.056 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:52.056 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created 2021-02-01 06:07:52.057 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC. 2021-02-01 06:07:52.057 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes. 2021-02-01 06:07:52.057 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_SECURITY 2021-02-01 06:07:52.058 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_SECURITY, endpoint 0 created 2021-02-01 06:07:52.058 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update is adding command class COMMAND_CLASS_SECURITY. 2021-02-01 06:07:52.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_SECURITY to the list of supported command classes. 2021-02-01 06:07:52.059 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2021-02-01 06:07:52.059 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created 2021-02-01 06:07:52.060 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY. 2021-02-01 06:07:52.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes. 2021-02-01 06:07:52.060 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Creating new instance of command class COMMAND_CLASS_HAIL 2021-02-01 06:07:52.061 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Command class COMMAND_CLASS_HAIL, endpoint 0 created 2021-02-01 06:07:52.061 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 64: Application update is adding command class COMMAND_CLASS_HAIL. 2021-02-01 06:07:52.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Adding command class COMMAND_CLASS_HAIL to the list of supported command classes. 2021-02-01 06:07:52.062 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1001: Transaction COMPLETED 2021-02-01 06:07:52.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Response processed after 53ms 2021-02-01 06:07:52.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1001: Transaction completed 2021-02-01 06:07:52.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1001 DONE 2021-02-01 06:07:52.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1001: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.064 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@10a6d6c 2021-02-01 06:07:52.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.065 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.066 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to SECURITY_REPORT 2021-02-01 06:07:52.066 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.067 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 64: Updated networkKey 2021-02-01 06:07:52.067 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 64: setupNetworkKey useSchemeZero=false 2021-02-01 06:07:52.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Skipping secure inclusion 2021-02-01 06:07:52.070 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to MANUFACTURER 2021-02-01 06:07:52.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.071 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: MANUFACTURER - send ManufacturerSpecific 2021-02-01 06:07:52.072 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 64: Creating new message for command MANUFACTURER_SPECIFIC_GET 2021-02-01 06:07:52.072 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.073 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:52.073 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2021-02-01 06:07:52.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@c6f405 2021-02-01 06:07:52.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1002 to queue - size 6 2021-02-01 06:07:52.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.075 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 40 02 72 04 25 D4 20 2021-02-01 06:07:52.076 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 09 00 13 40 02 72 04 25 D4 20 2021-02-01 06:07:52.076 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1002: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 212 2021-02-01 06:07:52.078 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1002: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 212 2021-02-01 06:07:52.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.084 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1002: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 212 2021-02-01 06:07:52.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1002: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 212 2021-02-01 06:07:52.087 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.087 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1002: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1002: Transaction not completed 2021-02-01 06:07:52.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.100 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D4 00 00 02 3D 2021-02-01 06:07:52.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=212, payload=D4 00 00 02 2021-02-01 06:07:52.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=212, payload=D4 00 00 02 2021-02-01 06:07:52.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1002: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 212 2021-02-01 06:07:52.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1002: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 212 2021-02-01 06:07:52.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1002: (Callback 212) 2021-02-01 06:07:52.103 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1002: callback 212 2021-02-01 06:07:52.103 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=212, payload=D4 00 00 02 2021-02-01 06:07:52.104 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 212, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1002: Advanced to WAIT_DATA 2021-02-01 06:07:52.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1002: Transaction not completed 2021-02-01 06:07:52.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.112 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 40 08 72 05 00 86 00 03 00 60 2F 2021-02-01 06:07:52.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 08 72 05 00 86 00 03 00 60 2021-02-01 06:07:52.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 08 72 05 00 86 00 03 00 60 2021-02-01 06:07:52.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:MANUFACTURER) 2021-02-01 06:07:52.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 2021-02-01 06:07:52.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:52.115 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_MANUFACTURER_SPECIFIC V0 MANUFACTURER_SPECIFIC_REPORT 2021-02-01 06:07:52.116 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 64: Manufacturer ID = 0x86 2021-02-01 06:07:52.116 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 64: Device Type = 0x3 2021-02-01 06:07:52.116 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 64: Device ID = 0x60 2021-02-01 06:07:52.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@708f2c. 2021-02-01 06:07:52.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@708f2c. 2021-02-01 06:07:52.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1002 DONE 2021-02-01 06:07:52.118 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1002: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.118 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@f3ada7 2021-02-01 06:07:52.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.119 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.119 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to APP_VERSION 2021-02-01 06:07:52.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.119 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.120 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: APP_VERSION - send VersionMessage 2021-02-01 06:07:52.121 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for command VERSION_GET 2021-02-01 06:07:52.121 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@8e648c 2021-02-01 06:07:52.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1003 to queue - size 6 2021-02-01 06:07:52.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.124 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 40 02 86 11 25 D5 C0 2021-02-01 06:07:52.124 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 09 00 13 40 02 86 11 25 D5 C0 2021-02-01 06:07:52.125 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1003: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 213 2021-02-01 06:07:52.126 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1003: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 213 2021-02-01 06:07:52.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.133 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1003: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 213 2021-02-01 06:07:52.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1003: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 213 2021-02-01 06:07:52.136 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.136 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1003: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1003: Transaction not completed 2021-02-01 06:07:52.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.149 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D5 00 00 02 3C 2021-02-01 06:07:52.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=213, payload=D5 00 00 02 2021-02-01 06:07:52.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=213, payload=D5 00 00 02 2021-02-01 06:07:52.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1003: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 213 2021-02-01 06:07:52.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1003: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 213 2021-02-01 06:07:52.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1003: (Callback 213) 2021-02-01 06:07:52.152 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.152 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1003: callback 213 2021-02-01 06:07:52.152 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=213, payload=D5 00 00 02 2021-02-01 06:07:52.153 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 213, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1003: Advanced to WAIT_DATA 2021-02-01 06:07:52.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1003: Transaction not completed 2021-02-01 06:07:52.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.161 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 40 09 86 12 03 04 36 01 01 60 00 78 2021-02-01 06:07:52.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 09 86 12 03 04 36 01 01 60 00 2021-02-01 06:07:52.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 09 86 12 03 04 36 01 01 60 00 2021-02-01 06:07:52.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:APP_VERSION) 2021-02-01 06:07:52.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.164 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_REPORT 2021-02-01 06:07:52.165 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Report 2021-02-01 06:07:52.165 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Library Type = 3 (Slave Enhanced) 2021-02-01 06:07:52.165 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Protocol Version = 4.54 2021-02-01 06:07:52.166 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Application Version = 1.1 2021-02-01 06:07:52.166 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Hardware Version = 96 2021-02-01 06:07:52.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f5448. 2021-02-01 06:07:52.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f5448. 2021-02-01 06:07:52.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1003 DONE 2021-02-01 06:07:52.168 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1003: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.169 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@9102bc 2021-02-01 06:07:52.169 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.170 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to DISCOVERY_COMPLETE 2021-02-01 06:07:52.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.170 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 64: Device discovery completed 2021-02-01 06:07:52.177 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 64: Device discovery resolved to thingType zwave:aeon_zw096_00_000 2021-02-01 06:07:52.181 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.183 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 64: Serialise aborted as static stages not complete 2021-02-01 06:07:52.183 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to VERSION 2021-02-01 06:07:52.185 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.196 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_DEVICE_RESET_LOCALLY, version is 0 2021-02-01 06:07:52.196 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_DEVICE_RESET_LOCALLY 2021-02-01 06:07:52.197 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2021-02-01 06:07:52.197 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.198 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1c2bdf7 2021-02-01 06:07:52.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1004 to queue - size 6 2021-02-01 06:07:52.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.201 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 5A 25 D6 99 2021-02-01 06:07:52.201 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 5A 25 D6 99 2021-02-01 06:07:52.202 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1004: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 214 2021-02-01 06:07:52.203 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1004: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 214 2021-02-01 06:07:52.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.210 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1004: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 214 2021-02-01 06:07:52.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1004: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 214 2021-02-01 06:07:52.214 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.214 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1004: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1004: Transaction not completed 2021-02-01 06:07:52.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.226 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D6 00 00 03 3E 2021-02-01 06:07:52.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=214, payload=D6 00 00 03 2021-02-01 06:07:52.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=214, payload=D6 00 00 03 2021-02-01 06:07:52.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1004: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 214 2021-02-01 06:07:52.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1004: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 214 2021-02-01 06:07:52.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1004: (Callback 214) 2021-02-01 06:07:52.229 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.230 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1004: callback 214 2021-02-01 06:07:52.230 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=214, payload=D6 00 00 03 2021-02-01 06:07:52.230 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 214, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1004: Advanced to WAIT_DATA 2021-02-01 06:07:52.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1004: Transaction not completed 2021-02-01 06:07:52.232 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.232 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.235 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 5A 01 7C 2021-02-01 06:07:52.237 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 5A 01 2021-02-01 06:07:52.237 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 5A 01 2021-02-01 06:07:52.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.239 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.240 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.240 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_DEVICE_RESET_LOCALLY, Version = 1 2021-02-01 06:07:52.240 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set. Enabling extra functionality. 2021-02-01 06:07:52.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@8d04a5. 2021-02-01 06:07:52.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@8d04a5. 2021-02-01 06:07:52.242 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1004 DONE 2021-02-01 06:07:52.242 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.242 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1004: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.243 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.243 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@bdb5fd 2021-02-01 06:07:52.243 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.243 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.244 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_NO_OPERATION, version is 1 2021-02-01 06:07:52.244 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_HAIL, version is 0 2021-02-01 06:07:52.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.245 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_HAIL 2021-02-01 06:07:52.245 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_HAIL 2021-02-01 06:07:52.245 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.245 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.246 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1360462 2021-02-01 06:07:52.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1005 to queue - size 6 2021-02-01 06:07:52.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.248 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 82 25 D7 40 2021-02-01 06:07:52.248 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 82 25 D7 40 2021-02-01 06:07:52.248 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1005: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 215 2021-02-01 06:07:52.250 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1005: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 215 2021-02-01 06:07:52.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.256 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1005: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 215 2021-02-01 06:07:52.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1005: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 215 2021-02-01 06:07:52.259 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.259 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1005: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1005: Transaction not completed 2021-02-01 06:07:52.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.272 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D7 00 00 02 3E 2021-02-01 06:07:52.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=215, payload=D7 00 00 02 2021-02-01 06:07:52.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=215, payload=D7 00 00 02 2021-02-01 06:07:52.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1005: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 215 2021-02-01 06:07:52.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1005: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 215 2021-02-01 06:07:52.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1005: (Callback 215) 2021-02-01 06:07:52.276 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1005: callback 215 2021-02-01 06:07:52.277 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=215, payload=D7 00 00 02 2021-02-01 06:07:52.277 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 215, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1005: Advanced to WAIT_DATA 2021-02-01 06:07:52.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1005: Transaction not completed 2021-02-01 06:07:52.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.283 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 82 01 A4 2021-02-01 06:07:52.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 82 01 2021-02-01 06:07:52.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 82 01 2021-02-01 06:07:52.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.287 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.287 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.288 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_HAIL, Version = 1 2021-02-01 06:07:52.288 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set. Enabling extra functionality. 2021-02-01 06:07:52.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f81022. 2021-02-01 06:07:52.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f81022. 2021-02-01 06:07:52.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1005 DONE 2021-02-01 06:07:52.290 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1005: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.291 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@116791a 2021-02-01 06:07:52.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.292 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.292 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_SECURITY, version is 0 2021-02-01 06:07:52.293 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_SECURITY 2021-02-01 06:07:52.293 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_SECURITY 2021-02-01 06:07:52.294 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.295 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1375e4a 2021-02-01 06:07:52.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1006 to queue - size 6 2021-02-01 06:07:52.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.297 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 98 25 D8 55 2021-02-01 06:07:52.298 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 98 25 D8 55 2021-02-01 06:07:52.298 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1006: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 216 2021-02-01 06:07:52.300 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1006: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 216 2021-02-01 06:07:52.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.306 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1006: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 216 2021-02-01 06:07:52.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1006: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 216 2021-02-01 06:07:52.309 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.309 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1006: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1006: Transaction not completed 2021-02-01 06:07:52.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.322 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D8 00 00 02 31 2021-02-01 06:07:52.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=216, payload=D8 00 00 02 2021-02-01 06:07:52.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=216, payload=D8 00 00 02 2021-02-01 06:07:52.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1006: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 216 2021-02-01 06:07:52.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1006: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 216 2021-02-01 06:07:52.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1006: (Callback 216) 2021-02-01 06:07:52.325 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.326 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1006: callback 216 2021-02-01 06:07:52.326 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=216, payload=D8 00 00 02 2021-02-01 06:07:52.327 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 216, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1006: Advanced to WAIT_DATA 2021-02-01 06:07:52.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1006: Transaction not completed 2021-02-01 06:07:52.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.333 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 98 01 BE 2021-02-01 06:07:52.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 98 01 2021-02-01 06:07:52.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 98 01 2021-02-01 06:07:52.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.337 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.337 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.337 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_SECURITY, Version = 1 2021-02-01 06:07:52.338 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set. Enabling extra functionality. 2021-02-01 06:07:52.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5ea3e. 2021-02-01 06:07:52.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5ea3e. 2021-02-01 06:07:52.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1006 DONE 2021-02-01 06:07:52.339 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1006: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.340 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@301fcc 2021-02-01 06:07:52.340 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.341 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_BASIC, version is 0 2021-02-01 06:07:52.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.341 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_BASIC 2021-02-01 06:07:52.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.342 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_BASIC 2021-02-01 06:07:52.342 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.342 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@14c123c 2021-02-01 06:07:52.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1007 to queue - size 6 2021-02-01 06:07:52.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.344 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 20 25 D9 EC 2021-02-01 06:07:52.345 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 20 25 D9 EC 2021-02-01 06:07:52.345 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1007: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 217 2021-02-01 06:07:52.347 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1007: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 217 2021-02-01 06:07:52.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.353 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1007: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 217 2021-02-01 06:07:52.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1007: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 217 2021-02-01 06:07:52.356 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.357 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.357 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1007: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1007: Transaction not completed 2021-02-01 06:07:52.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.369 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 D9 00 00 02 30 2021-02-01 06:07:52.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=217, payload=D9 00 00 02 2021-02-01 06:07:52.371 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=217, payload=D9 00 00 02 2021-02-01 06:07:52.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1007: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 217 2021-02-01 06:07:52.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1007: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 217 2021-02-01 06:07:52.373 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1007: (Callback 217) 2021-02-01 06:07:52.373 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1007: callback 217 2021-02-01 06:07:52.374 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=217, payload=D9 00 00 02 2021-02-01 06:07:52.375 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 217, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1007: Advanced to WAIT_DATA 2021-02-01 06:07:52.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1007: Transaction not completed 2021-02-01 06:07:52.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.380 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 20 01 06 2021-02-01 06:07:52.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 20 01 2021-02-01 06:07:52.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 20 01 2021-02-01 06:07:52.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.385 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.385 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.386 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_BASIC, Version = 1 2021-02-01 06:07:52.386 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set. Enabling extra functionality. 2021-02-01 06:07:52.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f05122. 2021-02-01 06:07:52.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f05122. 2021-02-01 06:07:52.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1007 DONE 2021-02-01 06:07:52.389 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1007: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.391 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@15c6426 2021-02-01 06:07:52.391 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.392 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_ZWAVEPLUS_INFO, version is 0 2021-02-01 06:07:52.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.392 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.393 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.393 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.393 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.394 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.395 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@52b709 2021-02-01 06:07:52.395 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.396 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1008 to queue - size 6 2021-02-01 06:07:52.396 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.397 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 5E 25 DA 91 2021-02-01 06:07:52.398 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 5E 25 DA 91 2021-02-01 06:07:52.398 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1008: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 218 2021-02-01 06:07:52.400 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.400 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1008: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 218 2021-02-01 06:07:52.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.406 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1008: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 218 2021-02-01 06:07:52.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1008: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 218 2021-02-01 06:07:52.409 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.410 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.410 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1008: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.410 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1008: Transaction not completed 2021-02-01 06:07:52.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.422 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DA 00 00 02 33 2021-02-01 06:07:52.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=218, payload=DA 00 00 02 2021-02-01 06:07:52.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=218, payload=DA 00 00 02 2021-02-01 06:07:52.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1008: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 218 2021-02-01 06:07:52.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1008: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 218 2021-02-01 06:07:52.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1008: (Callback 218) 2021-02-01 06:07:52.425 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.426 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1008: callback 218 2021-02-01 06:07:52.426 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=218, payload=DA 00 00 02 2021-02-01 06:07:52.426 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 218, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.427 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1008: Advanced to WAIT_DATA 2021-02-01 06:07:52.427 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1008: Transaction not completed 2021-02-01 06:07:52.427 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.428 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.433 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 5E 02 7B 2021-02-01 06:07:52.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 5E 02 2021-02-01 06:07:52.435 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 5E 02 2021-02-01 06:07:52.435 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.436 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.436 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.437 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.437 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.437 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_ZWAVEPLUS_INFO, Version = 2 2021-02-01 06:07:52.438 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set to maximum supported by the binding. Enabling extra functionality. 2021-02-01 06:07:52.438 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.438 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f4dcf5. 2021-02-01 06:07:52.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f4dcf5. 2021-02-01 06:07:52.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1008 DONE 2021-02-01 06:07:52.440 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1008: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.440 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@7807b0 2021-02-01 06:07:52.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.441 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.441 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_VERSION, version is 0 2021-02-01 06:07:52.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.441 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_VERSION 2021-02-01 06:07:52.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.442 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_VERSION 2021-02-01 06:07:52.442 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.442 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.443 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1c835f1 2021-02-01 06:07:52.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.444 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1009 to queue - size 6 2021-02-01 06:07:52.444 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.445 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 86 25 DB 48 2021-02-01 06:07:52.445 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 86 25 DB 48 2021-02-01 06:07:52.445 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.446 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1009: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 219 2021-02-01 06:07:52.447 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1009: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 219 2021-02-01 06:07:52.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.453 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1009: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 219 2021-02-01 06:07:52.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.456 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1009: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 219 2021-02-01 06:07:52.456 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.456 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1009: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1009: Transaction not completed 2021-02-01 06:07:52.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.469 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DB 00 00 02 32 2021-02-01 06:07:52.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=219, payload=DB 00 00 02 2021-02-01 06:07:52.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=219, payload=DB 00 00 02 2021-02-01 06:07:52.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1009: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 219 2021-02-01 06:07:52.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1009: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 219 2021-02-01 06:07:52.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1009: (Callback 219) 2021-02-01 06:07:52.473 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1009: callback 219 2021-02-01 06:07:52.473 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=219, payload=DB 00 00 02 2021-02-01 06:07:52.474 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 219, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1009: Advanced to WAIT_DATA 2021-02-01 06:07:52.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1009: Transaction not completed 2021-02-01 06:07:52.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.480 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 86 02 A3 2021-02-01 06:07:52.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 86 02 2021-02-01 06:07:52.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 86 02 2021-02-01 06:07:52.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.483 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V0 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.484 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.484 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_VERSION, Version = 2 2021-02-01 06:07:52.484 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 2, version set. Enabling extra functionality. 2021-02-01 06:07:52.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@abb909. 2021-02-01 06:07:52.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@abb909. 2021-02-01 06:07:52.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1009 DONE 2021-02-01 06:07:52.486 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1009: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.487 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1950cba 2021-02-01 06:07:52.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.487 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.487 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC, version is 0 2021-02-01 06:07:52.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.488 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: VERSION - queued COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:52.488 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:52.488 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.489 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.489 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-01 06:07:52.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@e20d7e 2021-02-01 06:07:52.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1010 to queue - size 6 2021-02-01 06:07:52.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.491 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 40 03 86 13 72 25 DC BB 2021-02-01 06:07:52.491 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 0A 00 13 40 03 86 13 72 25 DC BB 2021-02-01 06:07:52.492 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1010: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220 2021-02-01 06:07:52.493 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1010: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220 2021-02-01 06:07:52.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.500 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1010: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220 2021-02-01 06:07:52.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1010: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 220 2021-02-01 06:07:52.504 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.505 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1010: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1010: Transaction not completed 2021-02-01 06:07:52.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.516 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DC 00 00 03 34 2021-02-01 06:07:52.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=220, payload=DC 00 00 03 2021-02-01 06:07:52.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=220, payload=DC 00 00 03 2021-02-01 06:07:52.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1010: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 220 2021-02-01 06:07:52.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1010: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 220 2021-02-01 06:07:52.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1010: (Callback 220) 2021-02-01 06:07:52.520 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1010: callback 220 2021-02-01 06:07:52.520 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=220, payload=DC 00 00 03 2021-02-01 06:07:52.520 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 220, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1010: Advanced to WAIT_DATA 2021-02-01 06:07:52.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1010: Transaction not completed 2021-02-01 06:07:52.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.528 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 72 02 57 2021-02-01 06:07:52.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 72 02 2021-02-01 06:07:52.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 72 02 2021-02-01 06:07:52.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:VERSION) 2021-02-01 06:07:52.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.531 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V2 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.531 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.531 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_MANUFACTURER_SPECIFIC, Version = 2 2021-02-01 06:07:52.532 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set to maximum supported by the binding. Enabling extra functionality. 2021-02-01 06:07:52.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c2ef38. 2021-02-01 06:07:52.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c2ef38. 2021-02-01 06:07:52.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1010 DONE 2021-02-01 06:07:52.533 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1010: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:52.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.534 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@8bc8d5 2021-02-01 06:07:52.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.534 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:52.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.535 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to ENDPOINTS 2021-02-01 06:07:52.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.535 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.536 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: ENDPOINTS - MultiInstance not supported. 2021-02-01 06:07:52.536 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to UPDATE_DATABASE 2021-02-01 06:07:52.537 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.538 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE 2021-02-01 06:07:52.543 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - check properties 2021-02-01 06:07:52.543 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - property modelId == ZW096 2021-02-01 06:07:52.544 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - property vendor == AEON Labs 2021-02-01 06:07:52.544 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - property defaultAssociations == 1 2021-02-01 06:07:52.544 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - property manufacturerId == 0086 2021-02-01 06:07:52.545 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - property manufacturerRef == 0003:0060,0103:0060,0203:0060,1D03:0060 2021-02-01 06:07:52.545 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: UPDATE_DATABASE - property dbReference == 63 2021-02-01 06:07:52.545 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to STATIC_VALUES 2021-02-01 06:07:52.546 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:52.546 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_NO_OPERATION 2021-02-01 06:07:52.547 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_SECURITY 2021-02-01 06:07:52.547 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_BASIC 2021-02-01 06:07:52.548 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.548 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Found 1 instances of COMMAND_CLASS_ZWAVEPLUS_INFO for endpoint 0 2021-02-01 06:07:52.549 [DEBUG] [ass.impl.CommandClassZwaveplusInfoV1] - Creating command message ZWAVEPLUS_INFO_GET version 1 2021-02-01 06:07:52.549 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.549 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured 2021-02-01 06:07:52.550 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:52.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured 2021-02-01 06:07:52.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@2c1088 2021-02-01 06:07:52.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1011 to queue - size 6 2021-02-01 06:07:52.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.552 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 40 02 5E 01 25 DD 00 2021-02-01 06:07:52.553 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 09 00 13 40 02 5E 01 25 DD 00 2021-02-01 06:07:52.553 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.558 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 40 04 86 14 72 02 57 2021-02-01 06:07:52.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 72 02 2021-02-01 06:07:52.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 04 86 14 72 02 2021-02-01 06:07:52.561 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18 2021-02-01 06:07:52.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:STATIC_VALUES) 2021-02-01 06:07:52.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 2021-02-01 06:07:52.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-01 06:07:52.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_VERSION 2021-02-01 06:07:52.562 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_VERSION V2 VERSION_COMMAND_CLASS_REPORT 2021-02-01 06:07:52.562 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Process Version Command Class Report 2021-02-01 06:07:52.563 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 64: Requested Command Class = COMMAND_CLASS_MANUFACTURER_SPECIFIC, Version = 2 2021-02-01 06:07:52.563 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Version = 1, version set to maximum supported by the binding. Enabling extra functionality. 2021-02-01 06:07:52.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@173c8e3. 2021-02-01 06:07:52.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload= 2021-02-01 06:07:52.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started... 2021-02-01 06:07:52.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1011: Resetting transaction 2021-02-01 06:07:52.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1011 WAIT_RESPONSE 2021-02-01 06:07:52.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:52.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1011: Transaction event listener: DONE: WAIT_RESPONSE -> 2021-02-01 06:07:52.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Completing UNINTIALIZED transaction 1011!!! How?!? 2021-02-01 06:07:52.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1011 to queue - size 6 2021-02-01 06:07:52.568 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1d526eb 2021-02-01 06:07:52.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 2021-02-01 06:07:52.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true. 2021-02-01 06:07:52.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:52.819 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 40 02 5E 01 25 DD 00 2021-02-01 06:07:52.820 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 09 00 13 40 02 5E 01 25 DD 00 2021-02-01 06:07:52.821 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:52.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.822 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:52.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:52.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:52.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.829 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:52.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1011: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.834 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:52.835 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:52.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1011: Advanced to WAIT_REQUEST 2021-02-01 06:07:52.836 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1011: Transaction not completed 2021-02-01 06:07:52.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.845 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DD 00 00 02 34 2021-02-01 06:07:52.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=221, payload=DD 00 00 02 2021-02-01 06:07:52.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=221, payload=DD 00 00 02 2021-02-01 06:07:52.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1011: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:52.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1011: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 221 2021-02-01 06:07:52.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1011: (Callback 221) 2021-02-01 06:07:52.851 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:52.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1011: callback 221 2021-02-01 06:07:52.852 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=221, payload=DD 00 00 02 2021-02-01 06:07:52.853 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 221, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:52.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1011: Advanced to WAIT_DATA 2021-02-01 06:07:52.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1011: Transaction not completed 2021-02-01 06:07:52.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:52.857 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 40 09 5E 02 01 05 00 07 00 07 00 E5 2021-02-01 06:07:52.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 09 5E 02 01 05 00 07 00 07 00 2021-02-01 06:07:52.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 09 5E 02 01 05 00 07 00 07 00 2021-02-01 06:07:52.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:52.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:STATIC_VALUES) 2021-02-01 06:07:52.862 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 2021-02-01 06:07:52.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:52.863 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_ZWAVEPLUS_INFO V1 ZWAVEPLUS_INFO_REPORT 2021-02-01 06:07:52.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:52.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@120a9cc. 2021-02-01 06:07:52.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@120a9cc. 2021-02-01 06:07:52.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1011 DONE 2021-02-01 06:07:52.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:52.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:52.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:52.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:52.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:53.503 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: ZWaveCommandClassTransactionPayload - send to node 2021-02-01 06:07:53.504 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:53.505 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Command Class COMMAND_CLASS_ZWAVEPLUS_INFO is NOT required to be secured 2021-02-01 06:07:53.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@2c1088 2021-02-01 06:07:53.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Adding to device queue 2021-02-01 06:07:53.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Added 1012 to queue - size 6 2021-02-01 06:07:53.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:53.509 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 40 02 5E 01 25 DE 03 2021-02-01 06:07:53.510 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 64: Sending REQUEST Message = 01 09 00 13 40 02 5E 01 25 DE 03 2021-02-01 06:07:53.510 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-01 06:07:53.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1012: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 222 2021-02-01 06:07:53.512 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-01 06:07:53.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:53.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-01 06:07:53.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1012: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 222 2021-02-01 06:07:53.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-01 06:07:53.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:53.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:53.519 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-01 06:07:53.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:53.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:53.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1012: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 222 2021-02-01 06:07:53.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:53.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1012: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 222 2021-02-01 06:07:53.524 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-01 06:07:53.524 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: sentData successfully placed on stack. 2021-02-01 06:07:53.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1012: Advanced to WAIT_REQUEST 2021-02-01 06:07:53.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1012: Transaction not completed 2021-02-01 06:07:53.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:53.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:53.540 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 DE 00 00 03 36 2021-02-01 06:07:53.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=222, payload=DE 00 00 03 2021-02-01 06:07:53.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=222, payload=DE 00 00 03 2021-02-01 06:07:53.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1012: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 222 2021-02-01 06:07:53.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-01 06:07:53.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1012: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 222 2021-02-01 06:07:53.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1012: (Callback 222) 2021-02-01 06:07:53.545 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-01 06:07:53.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1012: callback 222 2021-02-01 06:07:53.546 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=222, payload=DE 00 00 03 2021-02-01 06:07:53.547 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 64: SendData Request. CallBack ID = 222, Status = Transmission complete and ACK received(0) 2021-02-01 06:07:53.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1012: Advanced to WAIT_DATA 2021-02-01 06:07:53.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: TID 1012: Transaction not completed 2021-02-01 06:07:53.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:53.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-01 06:07:53.552 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 40 09 5E 02 01 05 00 07 00 07 00 E5 2021-02-01 06:07:53.554 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 09 5E 02 01 05 00 07 00 07 00 2021-02-01 06:07:53.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 09 5E 02 01 05 00 07 00 07 00 2021-02-01 06:07:53.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-01 06:07:53.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (ALIVE:STATIC_VALUES) 2021-02-01 06:07:53.556 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 2021-02-01 06:07:53.557 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY NOT required on COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:53.557 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_ZWAVEPLUS_INFO V1 ZWAVEPLUS_INFO_REPORT 2021-02-01 06:07:53.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1. 2021-02-01 06:07:53.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@de3590. 2021-02-01 06:07:53.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@de3590. 2021-02-01 06:07:53.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 64: notifyTransactionResponse TID:1012 DONE 2021-02-01 06:07:53.561 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-01 06:07:53.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1012: Transaction event listener: DONE: DONE -> 2021-02-01 06:07:53.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-01 06:07:53.562 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1704952 2021-02-01 06:07:53.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-01 06:07:53.563 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node Init transaction completed with response COMPLETE 2021-02-01 06:07:53.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-01 06:07:53.563 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_VERSION 2021-02-01 06:07:53.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-01 06:07:53.564 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: STATIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:53.564 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Found 1 instances of COMMAND_CLASS_MANUFACTURER_SPECIFIC for endpoint 0 2021-02-01 06:07:53.565 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to ASSOCIATIONS 2021-02-01 06:07:53.566 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.567 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to SET_WAKEUP 2021-02-01 06:07:53.571 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.572 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to SET_ASSOCIATION 2021-02-01 06:07:53.575 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.577 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: SET_ASSOCIATION - ASSOCIATION class not supported 2021-02-01 06:07:53.578 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to SET_LIFELINE 2021-02-01 06:07:53.579 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.581 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: SET_LIFELINE - ASSOCIATION class not supported 2021-02-01 06:07:53.582 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to GET_CONFIGURATION 2021-02-01 06:07:53.583 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.584 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: GET_CONFIGURATION - CONFIGURATION class not supported 2021-02-01 06:07:53.585 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to STATIC_END 2021-02-01 06:07:53.586 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.587 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating node properties. 2021-02-01 06:07:53.588 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating node properties. MAN=134 2021-02-01 06:07:53.589 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating node properties. MAN=134. SET. Was 134 2021-02-01 06:07:53.590 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Properties synchronised 2021-02-01 06:07:53.591 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising Thing Node... 2021-02-01 06:07:53.592 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:switch_binary for OnOffType 2021-02-01 06:07:53.593 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:switch_binary for OnOffType 2021-02-01 06:07:53.594 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:switch_binary for OnOffType 2021-02-01 06:07:53.595 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:meter_kwh for DecimalType 2021-02-01 06:07:53.595 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:meter_kwh for DecimalType 2021-02-01 06:07:53.596 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:meter_current for DecimalType 2021-02-01 06:07:53.597 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:meter_current for DecimalType 2021-02-01 06:07:53.598 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:meter_voltage for DecimalType 2021-02-01 06:07:53.599 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:meter_voltage for DecimalType 2021-02-01 06:07:53.600 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:meter_watts for DecimalType 2021-02-01 06:07:53.600 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:meter_watts for DecimalType 2021-02-01 06:07:53.601 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:meter_reset for OnOffType 2021-02-01 06:07:53.602 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:meter_reset for OnOffType 2021-02-01 06:07:53.603 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:color_color for PercentType 2021-02-01 06:07:53.603 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:color_color for PercentType 2021-02-01 06:07:53.604 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:color_color for OnOffType 2021-02-01 06:07:53.605 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising cmd channel zwave:device:5d681cf1:node64:color_color for HSBType 2021-02-01 06:07:53.606 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Initialising state channel zwave:device:5d681cf1:node64:color_color for HSBType 2021-02-01 06:07:53.607 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Polling initialised at 1800 seconds - start in 1458000 milliseconds. 2021-02-01 06:07:53.608 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 64: Serialise aborted as static stages not complete 2021-02-01 06:07:53.608 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to DYNAMIC_VALUES 2021-02-01 06:07:53.609 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.610 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_NO_OPERATION 2021-02-01 06:07:53.611 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SECURITY 2021-02-01 06:07:53.612 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_BASIC 2021-02-01 06:07:53.613 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-01 06:07:53.613 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_VERSION 2021-02-01 06:07:53.614 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-01 06:07:53.615 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer: Initialisation complete! 2021-02-01 06:07:53.615 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to DYNAMIC_END 2021-02-01 06:07:53.616 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.617 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 64: Serializing to file /var/lib/openhab/zwave/network_ffeb5ae7__node_64.xml 2021-02-01 06:07:53.632 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 64: Node advancer - advancing to DONE 2021-02-01 06:07:53.632 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-01 06:07:53.634 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 64: Serializing to file /var/lib/openhab/zwave/network_ffeb5ae7__node_64.xml