1	09.07.2020	14:11:26.367	100KBit/s	62	0	0	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 06 12 06 60 0D 01 02 25 01 FF 01 82
2	09.07.2020	14:11:26.396	100KBit/s	58	0	30	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 06 0B 01 20 D2
3	09.07.2020	14:11:26.477	100KBit/s	58	0	80	006	001	F9 E0 F2 20	Singlecast	Switch Binary Report	F9 E0 F2 20 06 41 05 0E 01 25 03 FF 83 AE
4	09.07.2020	14:11:26.488	100KBit/s	62	0	12	001	006	F9 E0 F2 20	Ack		F9 E0 F2 20 01 03 05 0B 06 6E B1
5	09.07.2020	14:11:27.495	100KBit/s	57	0	1005	006	001	F9 E0 F2 20	Singlecast	Meter Report	F9 E0 F2 20 06 41 06 15 01 32 02 21 34 00 00 00 54 00 00 25 CC
6	09.07.2020	14:11:27.507	100KBit/s	61	0	14	001	006	F9 E0 F2 20	Ack		F9 E0 F2 20 01 03 06 0B 06 37 E1
7	09.07.2020	14:11:27.885	100KBit/s	61	0	377	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 07 11 06 60 0D 01 01 25 02 7B 5C
8	09.07.2020	14:11:27.892	100KBit/s	57	0	8	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 07 0B 01 17 E2
9	09.07.2020	14:11:27.938	100KBit/s	57	0	46	006	001	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 06 41 07 12 01 60 0D 01 01 25 03 00 42 DA
10	09.07.2020	14:11:27.947	100KBit/s	62	0	9	001	006	F9 E0 F2 20	Ack		F9 E0 F2 20 01 03 07 0B 06 00 D1
11	09.07.2020	14:11:27.970	100KBit/s	61	0	23	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 08 12 06 60 0D 01 01 32 01 10 69 98
12	09.07.2020	14:11:27.993	100KBit/s	61	0	22	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 08 12 06 60 0D 01 01 32 01 10 69 98
13	09.07.2020	14:11:28.002	100KBit/s	57	0	10	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 08 0B 01 3B D3
14	09.07.2020	14:11:28.026	100KBit/s	57	0	25	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 08 0B 01 3B D3
15	09.07.2020	14:11:33.028	100KBit/s	62	0	5000	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 09 12 06 60 0D 01 01 32 01 00 14 EC
16	09.07.2020	14:11:33.036	100KBit/s	57	0	8	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 09 0B 01 0C E3
17	09.07.2020	14:11:33.116	100KBit/s	57	0	79	006	001	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 06 41 08 1D 01 60 0D 01 01 32 02 21 24 00 00 00 36 00 01 00 00 00 36 E0 5F
18	09.07.2020	14:11:33.125	100KBit/s	62	0	10	001	006	F9 E0 F2 20	Ack		F9 E0 F2 20 01 03 08 0B 06 2C E0
19	09.07.2020	14:11:33.148	100KBit/s	62	0	23	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 0A 11 06 60 0D 01 02 25 02 7C 3F
20	09.07.2020	14:11:33.176	100KBit/s	57	0	29	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 0A 0B 01 55 B3
21	09.07.2020	14:11:38.202	100KBit/s	62	0	5025	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 0B 12 06 60 0D 01 02 32 01 10 43 8B
22	09.07.2020	14:11:38.233	100KBit/s	57	0	31	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 0B 0B 01 62 83
23	09.07.2020	14:11:43.257	100KBit/s	62	0	5024	001	006	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 01 41 0C 12 06 60 0D 01 02 32 01 00 4D 40
24	09.07.2020	14:11:43.287	100KBit/s	57	0	30	006	001	F9 E0 F2 20	Ack		F9 E0 F2 20 06 03 0C 0B 01 E7 13
25	09.07.2020	14:11:43.344	100KBit/s	57	0	55	006	001	F9 E0 F2 20	Singlecast	Multi Channel Cmd Encap	F9 E0 F2 20 06 41 09 1D 01 60 0D 02 01 32 02 21 24 00 00 01 69 00 01 00 00 01 69 62 62
26	09.07.2020	14:11:43.352	100KBit/s	62	0	10	001	006	F9 E0 F2 20	Ack		F9 E0 F2 20 01 03 09 0B 06 1B D0









==> /var/log/openhab2/events.log <==
2020-07-09 14:11:26.611 [ome.event.ItemCommandEvent] - Item 'office_windows_switch' received command ON
2020-07-09 14:11:26.612 [nt.ItemStatePredictedEvent] - office_windows_switch predicted to become ON

==> /var/log/openhab2/openhab.log <==
2020-07-09 14:11:26.616 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Command received zwave:device:67a66fd0:node6:switch_binary2 --> ON [OnOffType]
2020-07-09 14:11:26.616 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_SET
2020-07-09 14:11:26.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 2
2020-07-09 14:11:26.617 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2

==> /var/log/openhab2/events.log <==
2020-07-09 14:11:26.617 [GroupItemStateChangedEvent] - gHouseInteriorTimerLights changed from OFF to ON through office_windows_switch

==> /var/log/openhab2/openhab.log <==
2020-07-09 14:11:26.617 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported

==> /var/log/openhab2/events.log <==
2020-07-09 14:11:26.617 [vent.ItemStateChangedEvent] - office_windows_switch changed from OFF to ON

==> /var/log/openhab2/openhab.log <==
2020-07-09 14:11:26.618 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:26.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:26.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21975 to queue - size 1
2020-07-09 14:11:26.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:26.619 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 06 07 60 0D 01 02 25 01 FF 25 F7 84
2020-07-09 14:11:26.619 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0E 00 13 06 07 60 0D 01 02 25 01 FF 25 F7 84
2020-07-09 14:11:26.620 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-07-09 14:11:26.620 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21975: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 247
2020-07-09 14:11:26.620 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-07-09 14:11:26.621 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-07-09 14:11:26.621 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:26.622 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:26.622 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21975: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 247
2020-07-09 14:11:26.622 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-07-09 14:11:26.622 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:26.622 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:26.628 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-07-09 14:11:26.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:26.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:26.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21975: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 247
2020-07-09 14:11:26.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:26.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21975: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 247
2020-07-09 14:11:26.629 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:26.630 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: sentData successfully placed on stack.
2020-07-09 14:11:26.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21975: Advanced to WAIT_REQUEST
2020-07-09 14:11:26.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 21975: Transaction not completed
2020-07-09 14:11:26.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:26.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:26.667 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 F7 00 00 05 19
2020-07-09 14:11:26.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=247, payload=F7 00 00 05
2020-07-09 14:11:26.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=247, payload=F7 00 00 05
2020-07-09 14:11:26.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21975: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 247
2020-07-09 14:11:26.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:26.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21975: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 247
2020-07-09 14:11:26.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 21975: (Callback 247)
2020-07-09 14:11:26.669 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-07-09 14:11:26.669 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 21975: callback 247
2020-07-09 14:11:26.670 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=247, payload=F7 00 00 05
2020-07-09 14:11:26.670 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 247, Status = Transmission complete and ACK received(0)
2020-07-09 14:11:26.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2020-07-09 14:11:26.670 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21975: Transaction COMPLETED
2020-07-09 14:11:26.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Response processed after 51ms
2020-07-09 14:11:26.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 21975: Transaction completed
2020-07-09 14:11:26.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: notifyTransactionResponse TID:21975 DONE
2020-07-09 14:11:26.671 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-09 14:11:26.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:26.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:26.751 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 06 03 25 03 FF 2E
2020-07-09 14:11:26.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 03 25 03 FF
2020-07-09 14:11:26.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 03 25 03 FF
2020-07-09 14:11:26.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-07-09 14:11:26.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2020-07-09 14:11:26.753 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2020-07-09 14:11:26.754 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2020-07-09 14:11:26.754 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:26.755 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
2020-07-09 14:11:26.755 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 255
2020-07-09 14:11:26.755 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-07-09 14:11:26.756 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255
2020-07-09 14:11:26.756 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:67a66fd0:node6:switch_binary to ON [OnOffType]
2020-07-09 14:11:26.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2020-07-09 14:11:26.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@7fc91df1.
2020-07-09 14:11:26.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-07-09 14:11:26.758 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-07-09 14:11:26.758 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:26.758 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:27.769 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 54 00 00 96
2020-07-09 14:11:27.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0A 32 02 21 34 00 00 00 54 00 00
2020-07-09 14:11:27.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 0A 32 02 21 34 00 00 00 54 00 00
2020-07-09 14:11:27.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-07-09 14:11:27.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2020-07-09 14:11:27.772 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2020-07-09 14:11:27.772 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-07-09 14:11:27.773 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:27.773 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-07-09 14:11:27.773 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Meter: Type=Electric(1), Scale=W(2), Value=8.4
2020-07-09 14:11:27.774 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-07-09 14:11:27.774 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=8.4
2020-07-09 14:11:27.775 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:67a66fd0:node6:meter_watts to 8.4 [DecimalType]
2020-07-09 14:11:27.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2020-07-09 14:11:27.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5c651ec6.
2020-07-09 14:11:27.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-07-09 14:11:27.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-07-09 14:11:27.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:27.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:28.121 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling...
2020-07-09 14:11:28.122 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:67a66fd0:node6:switch_binary1
2020-07-09 14:11:28.122 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 6: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 1
2020-07-09 14:11:28.123 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_GET
2020-07-09 14:11:28.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 1
2020-07-09 14:11:28.123 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2020-07-09 14:11:28.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:28.125 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling skipped for zwave:device:67a66fd0:node6:switch_binary1 on COMMAND_CLASS_BASIC
2020-07-09 14:11:28.125 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:67a66fd0:node6:meter_watts1
2020-07-09 14:11:28.126 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2020-07-09 14:11:28.126 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2020-07-09 14:11:28.127 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Creating new message for application command METER_GET
2020-07-09 14:11:28.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 1
2020-07-09 14:11:28.128 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2020-07-09 14:11:28.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:28.129 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:67a66fd0:node6:meter_kwh1
2020-07-09 14:11:28.129 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2020-07-09 14:11:28.130 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2020-07-09 14:11:28.130 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Creating new message for application command METER_GET
2020-07-09 14:11:28.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 1
2020-07-09 14:11:28.131 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2020-07-09 14:11:28.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:28.132 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:67a66fd0:node6:switch_binary2
2020-07-09 14:11:28.133 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 6: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 2
2020-07-09 14:11:28.133 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_GET
2020-07-09 14:11:28.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 2
2020-07-09 14:11:28.134 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2020-07-09 14:11:28.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:28.134 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling skipped for zwave:device:67a66fd0:node6:switch_binary2 on COMMAND_CLASS_BASIC
2020-07-09 14:11:28.134 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:67a66fd0:node6:meter_watts2
2020-07-09 14:11:28.135 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 2
2020-07-09 14:11:28.135 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 2
2020-07-09 14:11:28.135 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Creating new message for application command METER_GET
2020-07-09 14:11:28.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 2
2020-07-09 14:11:28.135 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2020-07-09 14:11:28.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.136 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:28.136 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:67a66fd0:node6:meter_kwh2
2020-07-09 14:11:28.136 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 2
2020-07-09 14:11:28.136 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for COMMAND_CLASS_METER, endpoint 2
2020-07-09 14:11:28.137 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Creating new message for application command METER_GET
2020-07-09 14:11:28.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Encapsulating message, instance / endpoint 2
2020-07-09 14:11:28.137 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2020-07-09 14:11:28.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-07-09 14:11:28.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:28.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21976 to queue - size 1
2020-07-09 14:11:28.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:28.138 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 06 06 60 0D 01 01 25 02 25 F8 76
2020-07-09 14:11:28.139 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0D 00 13 06 06 60 0D 01 01 25 02 25 F8 76
2020-07-09 14:11:28.139 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-07-09 14:11:28.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21976: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 248
2020-07-09 14:11:28.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:28.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21977 to queue - size 1
2020-07-09 14:11:28.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.140 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-07-09 14:11:28.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:28.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21978 to queue - size 2
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21976: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 248
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21979 to queue - size 3
2020-07-09 14:11:28.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:28.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21980 to queue - size 4
2020-07-09 14:11:28.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Adding to device queue
2020-07-09 14:11:28.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Added 21981 to queue - size 5
2020-07-09 14:11:28.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.147 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-07-09 14:11:28.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:28.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:28.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21976: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 248
2020-07-09 14:11:28.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:28.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21976: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 248
2020-07-09 14:11:28.148 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:28.148 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: sentData successfully placed on stack.
2020-07-09 14:11:28.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21976: Advanced to WAIT_REQUEST
2020-07-09 14:11:28.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 21976: Transaction not completed
2020-07-09 14:11:28.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.163 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 F8 00 00 02 11
2020-07-09 14:11:28.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=248, payload=F8 00 00 02
2020-07-09 14:11:28.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=248, payload=F8 00 00 02
2020-07-09 14:11:28.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21976: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 248
2020-07-09 14:11:28.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:28.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21976: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 248
2020-07-09 14:11:28.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 21976: (Callback 248)
2020-07-09 14:11:28.166 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-07-09 14:11:28.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 21976: callback 248
2020-07-09 14:11:28.166 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=248, payload=F8 00 00 02
2020-07-09 14:11:28.167 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 248, Status = Transmission complete and ACK received(0)
2020-07-09 14:11:28.167 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2020-07-09 14:11:28.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21976: Advanced to WAIT_DATA
2020-07-09 14:11:28.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 21976: Transaction not completed
2020-07-09 14:11:28.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.214 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 06 07 60 0D 01 01 25 03 00 BC
2020-07-09 14:11:28.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 07 60 0D 01 01 25 03 00
2020-07-09 14:11:28.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=6, callback=0, payload=00 06 07 60 0D 01 01 25 03 00
2020-07-09 14:11:28.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-07-09 14:11:28.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Application Command Request (ALIVE:DONE)
2020-07-09 14:11:28.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2020-07-09 14:11:28.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-07-09 14:11:28.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 1
2020-07-09 14:11:28.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: SECURITY not supported
2020-07-09 14:11:28.217 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 6: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
2020-07-09 14:11:28.218 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 0
2020-07-09 14:11:28.218 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-07-09 14:11:28.219 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_SWITCH_BINARY, value=0
2020-07-09 14:11:28.219 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:67a66fd0:node6:switch_binary1 to OFF [OnOffType]
2020-07-09 14:11:28.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Commands processed 1.
2020-07-09 14:11:28.220 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10334937.
2020-07-09 14:11:28.221 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10334937.
2020-07-09 14:11:28.221 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: notifyTransactionResponse TID:21976 DONE
2020-07-09 14:11:28.222 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-09 14:11:28.222 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-07-09 14:11:28.222 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-07-09 14:11:28.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:28.223 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 06 07 60 0D 01 01 32 01 10 25 F9 71
2020-07-09 14:11:28.224 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0E 00 13 06 07 60 0D 01 01 32 01 10 25 F9 71
2020-07-09 14:11:28.224 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-07-09 14:11:28.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21977: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 249
2020-07-09 14:11:28.226 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-07-09 14:11:28.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:28.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:28.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21977: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 249
2020-07-09 14:11:28.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-07-09 14:11:28.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.232 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-07-09 14:11:28.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:28.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:28.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21977: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 249
2020-07-09 14:11:28.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:28.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21977: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 249
2020-07-09 14:11:28.234 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:28.234 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: sentData successfully placed on stack.
2020-07-09 14:11:28.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21977: Advanced to WAIT_REQUEST
2020-07-09 14:11:28.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 21977: Transaction not completed
2020-07-09 14:11:28.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.235 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:28.273 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 F9 00 00 04 16
2020-07-09 14:11:28.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=249, payload=F9 00 00 04
2020-07-09 14:11:28.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=249, payload=F9 00 00 04
2020-07-09 14:11:28.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21977: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 249
2020-07-09 14:11:28.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:28.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21977: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 249
2020-07-09 14:11:28.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 21977: (Callback 249)
2020-07-09 14:11:28.276 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-07-09 14:11:28.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 21977: callback 249
2020-07-09 14:11:28.276 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=249, payload=F9 00 00 04
2020-07-09 14:11:28.277 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 249, Status = Transmission complete and ACK received(0)
2020-07-09 14:11:28.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 6: resetResendCount initComplete=true isDead=false
2020-07-09 14:11:28.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21977: Advanced to WAIT_DATA
2020-07-09 14:11:28.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: TID 21977: Transaction not completed
2020-07-09 14:11:28.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:28.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:33.277 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 6: TID 21977: Timeout at state WAIT_DATA. 3 retries remaining.
2020-07-09 14:11:33.278 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21977: Transaction CANCELLED
2020-07-09 14:11:33.278 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-07-09 14:11:33.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 6: notifyTransactionResponse TID:21977 CANCELLED
2020-07-09 14:11:33.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-07-09 14:11:33.280 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 06 07 60 0D 01 01 32 01 00 25 FA 62
2020-07-09 14:11:33.281 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0E 00 13 06 07 60 0D 01 01 32 01 00 25 FA 62
2020-07-09 14:11:33.281 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-07-09 14:11:33.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21978: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 250
2020-07-09 14:11:33.283 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-07-09 14:11:33.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:33.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-07-09 14:11:33.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21978: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 250
2020-07-09 14:11:33.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-07-09 14:11:33.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-07-09 14:11:33.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-07-09 14:11:33.290 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-07-09 14:11:33.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:33.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:33.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21978: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 250
2020-07-09 14:11:33.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-07-09 14:11:33.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21978: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 250
2020-07-09 14:11:33.292 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-07-09 14:11:33.292 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: sentData successfully placed on stack.