tail -f /var/log/openhab2/openhab.log /var/log/openhab2/events.log
Filter
2020-11-21 11:13:05.293 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:switch_binary3 on COMMAND_CLASS_BASIC

2020-11-21 11:13:05.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:13:05.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17536 to queue - size 1

2020-11-21 11:13:05.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:13:05.303 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 25 02 25 04 E2 

2020-11-21 11:13:05.306 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 25 02 25 04 E2 

2020-11-21 11:13:05.309 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:13:05.313 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:13:05.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17536: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 4

2020-11-21 11:13:05.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:13:05.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:13:05.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17537 to queue - size 1

2020-11-21 11:13:05.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:13:05.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17536: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 4

2020-11-21 11:13:05.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:13:05.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.326 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:13:05.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:13:05.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17538 to queue - size 2

2020-11-21 11:13:05.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:05.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:05.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17536: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 4

2020-11-21 11:13:05.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:13:05.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17536: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 4

2020-11-21 11:13:05.340 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:05.342 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:13:05.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17536: Advanced to WAIT_REQUEST

2020-11-21 11:13:05.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17536: Transaction not completed

2020-11-21 11:13:05.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.459 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 04 00 00 0F E0 

2020-11-21 11:13:05.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=4, payload=04 00 00 0F 

2020-11-21 11:13:05.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=4, payload=04 00 00 0F 

2020-11-21 11:13:05.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17536: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 4

2020-11-21 11:13:05.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:13:05.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17536: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 4

2020-11-21 11:13:05.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17536: (Callback 4)

2020-11-21 11:13:05.472 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:13:05.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17536: callback 4

2020-11-21 11:13:05.476 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=4, payload=04 00 00 0F 

2020-11-21 11:13:05.477 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 4, Status = Transmission complete and ACK received(0)

2020-11-21 11:13:05.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:13:05.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17536: Advanced to WAIT_DATA

2020-11-21 11:13:05.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17536: Transaction not completed

2020-11-21 11:13:05.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.586 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 FF 2B 

2020-11-21 11:13:05.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF 

2020-11-21 11:13:05.592 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF 

2020-11-21 11:13:05.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:13:05.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:13:05.597 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:13:05.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:13:05.601 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:13:05.602 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:13:05.604 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 255

2020-11-21 11:13:05.606 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:13:05.608 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255

2020-11-21 11:13:05.610 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary to ON [OnOffType]

2020-11-21 11:13:05.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:13:05.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@baedf6.

2020-11-21 11:13:05.617 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@baedf6.

2020-11-21 11:13:05.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17536 DONE

2020-11-21 11:13:05.621 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:13:05.623 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:13:05.625 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:13:05.627 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:13:05.631 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 02 31 04 25 05 9F 

2020-11-21 11:13:05.633 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 02 31 04 25 05 9F 

2020-11-21 11:13:05.635 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:13:05.638 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:13:05.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:13:05.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17537: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 5

2020-11-21 11:13:05.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:13:05.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17537: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 5

2020-11-21 11:13:05.644 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:13:05.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:13:05.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:05.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:05.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17537: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 5

2020-11-21 11:13:05.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:13:05.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17537: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 5

2020-11-21 11:13:05.656 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:05.657 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:13:05.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17537: Advanced to WAIT_REQUEST

2020-11-21 11:13:05.660 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17537: Transaction not completed

2020-11-21 11:13:05.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.854 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 05 00 00 15 FB 

2020-11-21 11:13:05.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=5, payload=05 00 00 15 

2020-11-21 11:13:05.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=5, payload=05 00 00 15 

2020-11-21 11:13:05.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17537: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 5

2020-11-21 11:13:05.862 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:13:05.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17537: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 5

2020-11-21 11:13:05.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17537: (Callback 5)

2020-11-21 11:13:05.867 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:13:05.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17537: callback 5

2020-11-21 11:13:05.869 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=5, payload=05 00 00 15 

2020-11-21 11:13:05.871 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 5, Status = Transmission complete and ACK received(0)

2020-11-21 11:13:05.872 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:13:05.874 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17537: Advanced to WAIT_DATA

2020-11-21 11:13:05.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17537: Transaction not completed

2020-11-21 11:13:05.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:05.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:05.999 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 64 

2020-11-21 11:13:06.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 

2020-11-21 11:13:06.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 

2020-11-21 11:13:06.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:13:06.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:13:06.008 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:13:06.009 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL

2020-11-21 11:13:06.010 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0

2020-11-21 11:13:06.012 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:13:06.013 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT

2020-11-21 11:13:06.014 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Type = Luminance(3), Scale = 1

2020-11-21 11:13:06.016 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Value = 2.00E+3

2020-11-21 11:13:06.017 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

2020-11-21 11:13:06.020 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2.00E+3

2020-11-21 11:13:06.023 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:sensor_luminance to 2.00E+3 % [QuantityType]

2020-11-21 11:13:06.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:13:06.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@71cae8.

2020-11-21 11:13:06.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@71cae8.

2020-11-21 11:13:06.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17537 DONE

2020-11-21 11:13:06.035 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:13:06.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:13:06.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:13:06.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:06.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:13:06.054 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 03 25 02 25 06 8F 

2020-11-21 11:13:06.057 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 03 25 02 25 06 8F 

2020-11-21 11:13:06.060 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:13:06.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17538: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 6

2020-11-21 11:13:06.062 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:13:06.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:13:06.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:13:06.069 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:13:06.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17538: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 6

2020-11-21 11:13:06.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:06.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:13:06.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:06.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17538: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 6

2020-11-21 11:13:06.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:13:06.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17538: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 6

2020-11-21 11:13:06.087 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:13:06.089 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:13:06.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17538: Advanced to WAIT_REQUEST

2020-11-21 11:13:06.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17538: Transaction not completed

2020-11-21 11:13:06.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:06.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:06.265 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 06 00 00 14 F9 

2020-11-21 11:13:06.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=6, payload=06 00 00 14 

2020-11-21 11:13:06.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=6, payload=06 00 00 14 

2020-11-21 11:13:06.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17538: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 6

2020-11-21 11:13:06.271 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:13:06.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17538: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 6

2020-11-21 11:13:06.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17538: (Callback 6)

2020-11-21 11:13:06.276 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:13:06.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17538: callback 6

2020-11-21 11:13:06.279 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=6, payload=06 00 00 14 

2020-11-21 11:13:06.281 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 6, Status = Transmission complete and ACK received(0)

2020-11-21 11:13:06.282 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:13:06.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17538: Advanced to WAIT_DATA

2020-11-21 11:13:06.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17538: Transaction not completed

2020-11-21 11:13:06.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:06.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:13:06.404 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 03 07 60 0D 03 01 25 03 FF 44 

2020-11-21 11:13:06.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 03 01 25 03 FF 

2020-11-21 11:13:06.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 03 01 25 03 FF 

2020-11-21 11:13:06.410 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:13:06.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:13:06.413 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:13:06.414 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL

2020-11-21 11:13:06.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 3

2020-11-21 11:13:06.417 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:13:06.418 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:13:06.419 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 255

2020-11-21 11:13:06.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:13:06.422 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=3, command class=COMMAND_CLASS_SWITCH_BINARY, value=255

2020-11-21 11:13:06.424 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary3 to ON [OnOffType]

2020-11-21 11:13:06.426 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:13:06.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@919302.

==> /var/log/openhab2/events.log <==

2020-11-21 11:13:06.430 [vent.ItemStateChangedEvent] - Garagentor_Relais changed from OFF to ON

==> /var/log/openhab2/openhab.log <==

2020-11-21 11:13:06.431 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@919302.

2020-11-21 11:13:06.433 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17538 DONE

2020-11-21 11:13:06.435 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:13:06.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:13:06.438 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:13:06.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:13:06.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

==> /var/log/openhab2/events.log <==

2020-11-21 11:13:30.028 [ome.event.ItemCommandEvent] - Item 'Dyson527_Request' received command {"msg":"REQUEST-CURRENT-STATE", "time":"2020-11-21T10:13:30.012Z"}

2020-11-21 11:13:30.034 [nt.ItemStatePredictedEvent] - Dyson527_Request predicted to become NULL

2020-11-21 11:13:59.872 [vent.ItemStateChangedEvent] - Temperatur_Werkstatt_Fritz changed from 8.5 °C to 9.0 °C

2020-11-21 11:14:00.023 [ome.event.ItemCommandEvent] - Item 'Dyson527_Request' received command {"msg":"REQUEST-CURRENT-STATE", "time":"2020-11-21T10:14:00.011Z"}

2020-11-21 11:14:00.027 [nt.ItemStatePredictedEvent] - Dyson527_Request predicted to become NULL

2020-11-21 11:14:22.867 [vent.ItemStateChangedEvent] - Temperatur_Werkstatt changed from 7.90 °C to 8.00 °C

2020-11-21 11:14:25.053 [ome.event.ItemCommandEvent] - Item 'Garagentor_Switch' received command OFF

2020-11-21 11:14:25.087 [nt.ItemStatePredictedEvent] - Garagentor_Switch predicted to become OFF

2020-11-21 11:14:25.108 [vent.ItemStateChangedEvent] - Garagentor_Switch changed from ON to OFF

==> /var/log/openhab2/openhab.log <==

2020-11-21 11:14:25.126 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Command received zwave:device:16f41b43916:node3:switch_binary --> OFF [OnOffType]

2020-11-21 11:14:25.130 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_SET

2020-11-21 11:14:25.134 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:25.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

2020-11-21 11:14:25.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:25.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17539 to queue - size 1

2020-11-21 11:14:25.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:25.151 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 03 03 25 01 00 25 07 E0 

2020-11-21 11:14:25.156 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0A 00 13 03 03 25 01 00 25 07 E0 

2020-11-21 11:14:25.160 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:25.163 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:25.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17539: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 7

2020-11-21 11:14:25.168 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling initialised at 1800 seconds - start in 1500 milliseconds.

2020-11-21 11:14:25.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:25.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:25.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17539: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 7

2020-11-21 11:14:25.183 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:25.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:25.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:25.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:25.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17539: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 7

2020-11-21 11:14:25.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:25.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17539: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 7

2020-11-21 11:14:25.209 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:25.211 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:25.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17539: Advanced to WAIT_REQUEST

2020-11-21 11:14:25.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17539: Transaction not completed

2020-11-21 11:14:25.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:25.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:25.313 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 07 00 00 0F E3 

2020-11-21 11:14:25.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=7, payload=07 00 00 0F 

2020-11-21 11:14:25.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=7, payload=07 00 00 0F 

2020-11-21 11:14:25.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17539: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 7

2020-11-21 11:14:25.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:25.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17539: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 7

2020-11-21 11:14:25.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17539: (Callback 7)

2020-11-21 11:14:25.326 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:25.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17539: callback 7

2020-11-21 11:14:25.329 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=7, payload=07 00 00 0F 

2020-11-21 11:14:25.331 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 7, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:25.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:25.334 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 17539: Transaction COMPLETED

2020-11-21 11:14:25.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Response processed after 172ms

2020-11-21 11:14:25.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17539: Transaction completed

2020-11-21 11:14:25.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17539 DONE

2020-11-21 11:14:25.341 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:25.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:25.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:25.436 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 00 D4 

2020-11-21 11:14:25.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 00 

2020-11-21 11:14:25.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 00 

2020-11-21 11:14:25.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:25.445 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:25.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:25.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:14:25.449 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:25.451 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:14:25.452 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 0

2020-11-21 11:14:25.454 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:14:25.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=0

2020-11-21 11:14:25.457 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary to OFF [OnOffType]

2020-11-21 11:14:25.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:25.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1062229.

2020-11-21 11:14:25.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:25.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:25.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:25.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:26.668 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...

2020-11-21 11:14:26.672 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:switch_binary

2020-11-21 11:14:26.675 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:14:26.678 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_GET

2020-11-21 11:14:26.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:26.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

2020-11-21 11:14:26.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:switch_binary on COMMAND_CLASS_BASIC

2020-11-21 11:14:26.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:alarm_burglar

2020-11-21 11:14:26.691 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:alarm_system

2020-11-21 11:14:26.694 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:alarm_motion1

2020-11-21 11:14:26.696 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:alarm_motion1 on COMMAND_CLASS_BASIC

2020-11-21 11:14:26.699 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:sensor_luminance2

2020-11-21 11:14:26.701 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 2

2020-11-21 11:14:26.703 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Creating new message for command SENSOR_MULTILEVEL_GET

2020-11-21 11:14:26.706 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Encapsulating message, instance / endpoint 2

2020-11-21 11:14:26.708 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 3: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2

2020-11-21 11:14:26.710 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:26.713 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured

2020-11-21 11:14:26.715 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:sensor_luminance2 on COMMAND_CLASS_BASIC

2020-11-21 11:14:26.717 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:switch_binary3

2020-11-21 11:14:26.720 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 3

2020-11-21 11:14:26.722 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_GET

2020-11-21 11:14:26.724 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Encapsulating message, instance / endpoint 3

2020-11-21 11:14:26.727 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 3: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 3

2020-11-21 11:14:26.729 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:26.732 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured

2020-11-21 11:14:26.734 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:switch_binary3 on COMMAND_CLASS_BASIC

2020-11-21 11:14:26.737 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:26.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17540 to queue - size 1

2020-11-21 11:14:26.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:26.745 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 25 02 25 08 EE 

2020-11-21 11:14:26.748 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 25 02 25 08 EE 

2020-11-21 11:14:26.751 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:26.754 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:26.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17540: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 8

2020-11-21 11:14:26.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:26.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:26.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17541 to queue - size 1

2020-11-21 11:14:26.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:26.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:26.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17540: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 8

2020-11-21 11:14:26.765 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:26.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:26.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:26.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:26.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:26.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17542 to queue - size 2

2020-11-21 11:14:26.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:26.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:26.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:26.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17540: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 8

2020-11-21 11:14:26.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:26.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17540: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 8

2020-11-21 11:14:26.804 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:26.808 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:26.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17540: Advanced to WAIT_REQUEST

2020-11-21 11:14:26.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17540: Transaction not completed

2020-11-21 11:14:26.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:26.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:26.899 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 08 00 00 0F EC 

2020-11-21 11:14:26.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=8, payload=08 00 00 0F 

2020-11-21 11:14:26.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=8, payload=08 00 00 0F 

2020-11-21 11:14:26.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17540: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 8

2020-11-21 11:14:26.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:26.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17540: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 8

2020-11-21 11:14:26.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17540: (Callback 8)

2020-11-21 11:14:26.911 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:26.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17540: callback 8

2020-11-21 11:14:26.914 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=8, payload=08 00 00 0F 

2020-11-21 11:14:26.916 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 8, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:26.917 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:26.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17540: Advanced to WAIT_DATA

2020-11-21 11:14:26.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17540: Transaction not completed

2020-11-21 11:14:26.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:26.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.026 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 00 D4 

2020-11-21 11:14:27.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 00 

2020-11-21 11:14:27.029 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 00 

2020-11-21 11:14:27.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:27.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:27.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:27.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:14:27.036 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:27.038 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:14:27.039 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 0

2020-11-21 11:14:27.040 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:14:27.042 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=0

2020-11-21 11:14:27.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary to OFF [OnOffType]

2020-11-21 11:14:27.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:27.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@ab0e2.

2020-11-21 11:14:27.050 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@ab0e2.

2020-11-21 11:14:27.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17540 DONE

2020-11-21 11:14:27.054 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:27.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:14:27.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:27.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:27.062 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 02 31 04 25 09 93 

2020-11-21 11:14:27.064 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 02 31 04 25 09 93 

2020-11-21 11:14:27.067 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:27.068 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:27.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17541: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 9

2020-11-21 11:14:27.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:27.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:27.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17541: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 9

2020-11-21 11:14:27.074 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:27.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:27.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:27.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:27.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17541: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 9

2020-11-21 11:14:27.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:27.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17541: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 9

2020-11-21 11:14:27.085 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:27.087 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:27.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17541: Advanced to WAIT_REQUEST

2020-11-21 11:14:27.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17541: Transaction not completed

2020-11-21 11:14:27.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.285 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 09 00 00 15 F7 

2020-11-21 11:14:27.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=9, payload=09 00 00 15 

2020-11-21 11:14:27.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=9, payload=09 00 00 15 

2020-11-21 11:14:27.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17541: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 9

2020-11-21 11:14:27.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:27.294 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17541: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 9

2020-11-21 11:14:27.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17541: (Callback 9)

2020-11-21 11:14:27.298 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:27.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17541: callback 9

2020-11-21 11:14:27.301 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=9, payload=09 00 00 15 

2020-11-21 11:14:27.302 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 9, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:27.305 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:27.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17541: Advanced to WAIT_DATA

2020-11-21 11:14:27.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17541: Transaction not completed

2020-11-21 11:14:27.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.429 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 64 

2020-11-21 11:14:27.432 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 

2020-11-21 11:14:27.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 

2020-11-21 11:14:27.436 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:27.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:27.439 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:27.441 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL

2020-11-21 11:14:27.443 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0

2020-11-21 11:14:27.444 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:27.446 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT

2020-11-21 11:14:27.447 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Type = Luminance(3), Scale = 1

2020-11-21 11:14:27.450 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Value = 2.00E+3

2020-11-21 11:14:27.451 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

2020-11-21 11:14:27.453 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2.00E+3

2020-11-21 11:14:27.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:sensor_luminance to 2.00E+3 % [QuantityType]

2020-11-21 11:14:27.458 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:27.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6a1dc1.

2020-11-21 11:14:27.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6a1dc1.

2020-11-21 11:14:27.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17541 DONE

2020-11-21 11:14:27.465 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:27.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:14:27.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:27.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:27.474 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 03 25 02 25 0A 83 

2020-11-21 11:14:27.476 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 03 25 02 25 0A 83 

2020-11-21 11:14:27.478 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:27.479 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17542: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 10

2020-11-21 11:14:27.480 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:27.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:27.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:27.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17542: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 10

2020-11-21 11:14:27.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:27.487 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:27.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:27.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.494 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:27.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17542: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 10

2020-11-21 11:14:27.498 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:27.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17542: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 10

2020-11-21 11:14:27.502 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:27.503 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:27.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17542: Advanced to WAIT_REQUEST

2020-11-21 11:14:27.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17542: Transaction not completed

2020-11-21 11:14:27.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.697 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0A 00 00 15 F4 

2020-11-21 11:14:27.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=10, payload=0A 00 00 15 

2020-11-21 11:14:27.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=10, payload=0A 00 00 15 

2020-11-21 11:14:27.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17542: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 10

2020-11-21 11:14:27.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:27.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17542: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 10

2020-11-21 11:14:27.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17542: (Callback 10)

2020-11-21 11:14:27.717 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:27.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17542: callback 10

2020-11-21 11:14:27.722 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=10, payload=0A 00 00 15 

2020-11-21 11:14:27.725 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 10, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:27.728 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:27.730 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17542: Advanced to WAIT_DATA

2020-11-21 11:14:27.733 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17542: Transaction not completed

2020-11-21 11:14:27.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.737 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:27.833 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 03 07 60 0D 03 01 25 03 00 BB 

2020-11-21 11:14:27.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 03 01 25 03 00 

2020-11-21 11:14:27.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 03 01 25 03 00 

2020-11-21 11:14:27.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:27.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:27.849 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:27.851 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL

2020-11-21 11:14:27.854 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 3

2020-11-21 11:14:27.856 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:27.859 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:14:27.861 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 0

2020-11-21 11:14:27.864 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:14:27.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=3, command class=COMMAND_CLASS_SWITCH_BINARY, value=0

2020-11-21 11:14:27.870 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary3 to OFF [OnOffType]

==> /var/log/openhab2/events.log <==

2020-11-21 11:14:27.879 [vent.ItemStateChangedEvent] - Garagentor_Relais changed from ON to OFF

==> /var/log/openhab2/openhab.log <==

2020-11-21 11:14:27.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:27.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b9ecd.

2020-11-21 11:14:27.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b9ecd.

2020-11-21 11:14:27.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17542 DONE

2020-11-21 11:14:27.893 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:27.896 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:14:27.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:27.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:27.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

==> /var/log/openhab2/events.log <==

2020-11-21 11:14:30.039 [ome.event.ItemCommandEvent] - Item 'Dyson527_Request' received command {"msg":"REQUEST-CURRENT-STATE", "time":"2020-11-21T10:14:30.023Z"}

2020-11-21 11:14:30.045 [nt.ItemStatePredictedEvent] - Dyson527_Request predicted to become NULL

2020-11-21 11:14:40.323 [ome.event.ItemCommandEvent] - Item 'Garagentor_Switch' received command ON

2020-11-21 11:14:40.342 [nt.ItemStatePredictedEvent] - Garagentor_Switch predicted to become ON

2020-11-21 11:14:40.352 [vent.ItemStateChangedEvent] - Garagentor_Switch changed from OFF to ON

==> /var/log/openhab2/openhab.log <==

2020-11-21 11:14:40.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Command received zwave:device:16f41b43916:node3:switch_binary --> ON [OnOffType]

2020-11-21 11:14:40.360 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_SET

2020-11-21 11:14:40.362 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:40.364 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

2020-11-21 11:14:40.367 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:40.368 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17543 to queue - size 1

2020-11-21 11:14:40.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:40.376 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 03 03 25 01 FF 25 0B 13 

2020-11-21 11:14:40.381 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0A 00 13 03 03 25 01 FF 25 0B 13 

2020-11-21 11:14:40.383 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:40.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17543: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 11

2020-11-21 11:14:40.386 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:40.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:40.389 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling initialised at 1800 seconds - start in 1500 milliseconds.

2020-11-21 11:14:40.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:40.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17543: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 11

2020-11-21 11:14:40.393 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:40.395 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:40.396 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:40.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:40.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:40.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:40.404 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17543: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 11

2020-11-21 11:14:40.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:40.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17543: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 11

2020-11-21 11:14:40.411 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:40.413 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:40.415 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17543: Advanced to WAIT_REQUEST

2020-11-21 11:14:40.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17543: Transaction not completed

2020-11-21 11:14:40.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:40.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:40.534 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0B 00 00 0F EF 

2020-11-21 11:14:40.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=11, payload=0B 00 00 0F 

2020-11-21 11:14:40.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=11, payload=0B 00 00 0F 

2020-11-21 11:14:40.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17543: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 11

2020-11-21 11:14:40.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:40.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17543: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 11

2020-11-21 11:14:40.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17543: (Callback 11)

2020-11-21 11:14:40.548 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:40.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17543: callback 11

2020-11-21 11:14:40.551 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=11, payload=0B 00 00 0F 

2020-11-21 11:14:40.553 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 11, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:40.555 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:40.556 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 17543: Transaction COMPLETED

2020-11-21 11:14:40.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Response processed after 173ms

2020-11-21 11:14:40.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17543: Transaction completed

2020-11-21 11:14:40.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17543 DONE

2020-11-21 11:14:40.564 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:40.566 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:40.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:40.665 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 FF 2B 

2020-11-21 11:14:40.667 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF 

2020-11-21 11:14:40.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF 

2020-11-21 11:14:40.671 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:40.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:40.675 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:40.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:14:40.678 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:40.680 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:14:40.682 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 255

2020-11-21 11:14:40.684 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:14:40.685 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255

2020-11-21 11:14:40.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary to ON [OnOffType]

2020-11-21 11:14:40.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:40.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@eee50d.

2020-11-21 11:14:40.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:40.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:40.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:40.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:41.889 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...

2020-11-21 11:14:41.893 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:switch_binary

2020-11-21 11:14:41.896 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:14:41.898 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_GET

2020-11-21 11:14:41.901 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:41.904 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

2020-11-21 11:14:41.907 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:switch_binary on COMMAND_CLASS_BASIC

2020-11-21 11:14:41.911 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:alarm_burglar

2020-11-21 11:14:41.915 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:alarm_system

2020-11-21 11:14:41.919 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:alarm_motion1

2020-11-21 11:14:41.922 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:alarm_motion1 on COMMAND_CLASS_BASIC

2020-11-21 11:14:41.926 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:sensor_luminance2

2020-11-21 11:14:41.930 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 2

2020-11-21 11:14:41.934 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Creating new message for command SENSOR_MULTILEVEL_GET

2020-11-21 11:14:41.938 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Encapsulating message, instance / endpoint 2

2020-11-21 11:14:41.942 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 3: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2

2020-11-21 11:14:41.945 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:41.949 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured

2020-11-21 11:14:41.954 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:sensor_luminance2 on COMMAND_CLASS_BASIC

2020-11-21 11:14:41.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:16f41b43916:node3:switch_binary3

2020-11-21 11:14:41.961 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 3: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 3

2020-11-21 11:14:41.965 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_GET

2020-11-21 11:14:41.969 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Encapsulating message, instance / endpoint 3

2020-11-21 11:14:41.972 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 3: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 3

2020-11-21 11:14:41.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:41.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured

2020-11-21 11:14:41.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling skipped for zwave:device:16f41b43916:node3:switch_binary3 on COMMAND_CLASS_BASIC

2020-11-21 11:14:41.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:41.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17544 to queue - size 1

2020-11-21 11:14:41.991 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:41.995 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 25 02 25 0C EA 

2020-11-21 11:14:41.998 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 25 02 25 0C EA 

2020-11-21 11:14:42.002 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:42.005 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:42.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17544: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 12

2020-11-21 11:14:42.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:42.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:42.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:42.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17544: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 12

2020-11-21 11:14:42.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17545 to queue - size 1

2020-11-21 11:14:42.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:42.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.017 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2020-11-21 11:14:42.019 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:42.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 17546 to queue - size 2

2020-11-21 11:14:42.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17544: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 12

2020-11-21 11:14:42.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:42.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17544: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 12

2020-11-21 11:14:42.032 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.034 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:42.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17544: Advanced to WAIT_REQUEST

2020-11-21 11:14:42.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17544: Transaction not completed

2020-11-21 11:14:42.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.152 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0C 00 00 0F E8 

2020-11-21 11:14:42.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=12, payload=0C 00 00 0F 

2020-11-21 11:14:42.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=12, payload=0C 00 00 0F 

2020-11-21 11:14:42.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17544: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 12

2020-11-21 11:14:42.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:42.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17544: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 12

2020-11-21 11:14:42.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17544: (Callback 12)

2020-11-21 11:14:42.166 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:42.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17544: callback 12

2020-11-21 11:14:42.171 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=12, payload=0C 00 00 0F 

2020-11-21 11:14:42.173 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 12, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:42.175 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:42.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17544: Advanced to WAIT_DATA

2020-11-21 11:14:42.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17544: Transaction not completed

2020-11-21 11:14:42.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.276 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 FF 2B 

2020-11-21 11:14:42.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF 

2020-11-21 11:14:42.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 25 03 FF 

2020-11-21 11:14:42.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:42.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:42.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:42.288 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2020-11-21 11:14:42.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:42.291 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:14:42.293 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 255

2020-11-21 11:14:42.294 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:14:42.296 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255

2020-11-21 11:14:42.298 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary to ON [OnOffType]

2020-11-21 11:14:42.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:42.304 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@13cd292.

2020-11-21 11:14:42.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@13cd292.

2020-11-21 11:14:42.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17544 DONE

2020-11-21 11:14:42.311 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:42.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:14:42.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:42.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:42.321 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 02 31 04 25 0D 97 

2020-11-21 11:14:42.324 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 02 31 04 25 0D 97 

2020-11-21 11:14:42.326 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:42.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17545: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 13

2020-11-21 11:14:42.328 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:42.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:42.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:42.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17545: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 13

2020-11-21 11:14:42.335 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:42.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:42.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.345 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17545: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 13

2020-11-21 11:14:42.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:42.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17545: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 13

2020-11-21 11:14:42.350 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.352 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:42.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17545: Advanced to WAIT_REQUEST

2020-11-21 11:14:42.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17545: Transaction not completed

2020-11-21 11:14:42.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.535 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0D 00 00 14 F2 

2020-11-21 11:14:42.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=13, payload=0D 00 00 14 

2020-11-21 11:14:42.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=13, payload=0D 00 00 14 

2020-11-21 11:14:42.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17545: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 13

2020-11-21 11:14:42.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:42.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17545: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 13

2020-11-21 11:14:42.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17545: (Callback 13)

2020-11-21 11:14:42.549 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:42.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17545: callback 13

2020-11-21 11:14:42.553 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=13, payload=0D 00 00 14 

2020-11-21 11:14:42.555 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 13, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:42.557 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:42.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17545: Advanced to WAIT_DATA

2020-11-21 11:14:42.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17545: Transaction not completed

2020-11-21 11:14:42.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.678 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 64 

2020-11-21 11:14:42.681 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 

2020-11-21 11:14:42.683 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0A 60 0D 00 01 31 05 03 0A 07 D0 

2020-11-21 11:14:42.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:42.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:42.689 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:42.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL

2020-11-21 11:14:42.693 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0

2020-11-21 11:14:42.695 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:42.697 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SENSOR_MULTILEVEL V4 SENSOR_MULTILEVEL_REPORT

2020-11-21 11:14:42.699 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Type = Luminance(3), Scale = 1

2020-11-21 11:14:42.701 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 3: Sensor Value = 2.00E+3

2020-11-21 11:14:42.703 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

2020-11-21 11:14:42.705 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=2.00E+3

2020-11-21 11:14:42.707 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:sensor_luminance to 2.00E+3 % [QuantityType]

2020-11-21 11:14:42.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:42.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a45261.

2020-11-21 11:14:42.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a45261.

2020-11-21 11:14:42.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17545 DONE

2020-11-21 11:14:42.720 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:42.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:14:42.724 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2020-11-21 11:14:42.726 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2020-11-21 11:14:42.732 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 03 06 60 0D 01 03 25 02 25 0E 87 

2020-11-21 11:14:42.735 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 0D 00 13 03 06 60 0D 01 03 25 02 25 0E 87 

2020-11-21 11:14:42.737 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2020-11-21 11:14:42.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17546: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 14

2020-11-21 11:14:42.740 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2020-11-21 11:14:42.742 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:42.745 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2020-11-21 11:14:42.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17546: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 14

2020-11-21 11:14:42.748 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2020-11-21 11:14:42.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2020-11-21 11:14:42.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17546: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 14

2020-11-21 11:14:42.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:42.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17546: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 14

2020-11-21 11:14:42.766 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2020-11-21 11:14:42.768 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2020-11-21 11:14:42.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17546: Advanced to WAIT_REQUEST

2020-11-21 11:14:42.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17546: Transaction not completed

2020-11-21 11:14:42.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:42.946 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0E 00 00 14 F1 

2020-11-21 11:14:42.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=14, payload=0E 00 00 14 

2020-11-21 11:14:42.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=14, payload=0E 00 00 14 

2020-11-21 11:14:42.953 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17546: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 14

2020-11-21 11:14:42.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2020-11-21 11:14:42.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17546: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 14

2020-11-21 11:14:42.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17546: (Callback 14)

2020-11-21 11:14:42.958 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2020-11-21 11:14:42.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17546: callback 14

2020-11-21 11:14:42.961 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=14, payload=0E 00 00 14 

2020-11-21 11:14:42.963 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 14, Status = Transmission complete and ACK received(0)

2020-11-21 11:14:42.964 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:42.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17546: Advanced to WAIT_DATA

2020-11-21 11:14:42.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 17546: Transaction not completed

2020-11-21 11:14:42.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:42.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2020-11-21 11:14:43.082 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 03 07 60 0D 03 01 25 03 FF 44 

2020-11-21 11:14:43.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 03 01 25 03 FF 

2020-11-21 11:14:43.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 07 60 0D 03 01 25 03 FF 

2020-11-21 11:14:43.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2020-11-21 11:14:43.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DONE)

2020-11-21 11:14:43.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: resetResendCount initComplete=true isDead=false

2020-11-21 11:14:43.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL

2020-11-21 11:14:43.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 3

2020-11-21 11:14:43.095 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2020-11-21 11:14:43.097 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2020-11-21 11:14:43.098 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 255

2020-11-21 11:14:43.101 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2020-11-21 11:14:43.103 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=3, command class=COMMAND_CLASS_SWITCH_BINARY, value=255

2020-11-21 11:14:43.105 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:16f41b43916:node3:switch_binary3 to ON [OnOffType]

2020-11-21 11:14:43.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2020-11-21 11:14:43.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a15bee.

2020-11-21 11:14:43.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a15bee.

2020-11-21 11:14:43.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:17546 DONE

2020-11-21 11:14:43.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2020-11-21 11:14:43.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2020-11-21 11:14:43.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

==> /var/log/openhab2/events.log <==

2020-11-21 11:14:43.118 [vent.ItemStateChangedEvent] - Garagentor_Relais changed from OFF to ON

==> /var/log/openhab2/openhab.log <==

2020-11-21 11:14:43.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2020-11-21 11:14:43.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.