019-10-10 12:01:28.794 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: ZWaveCommandClassTransactionPayload - send to node

2019-10-10 12:01:28.796 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2019-10-10 12:01:28.798 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_BATTERY is NOT required to be secured

2019-10-10 12:01:28.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@15289cc

2019-10-10 12:01:28.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 23 priority from Get to Immediate

2019-10-10 12:01:28.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2019-10-10 12:01:28.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 23 to queue - size 4

2019-10-10 12:01:28.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:01:28.813 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 80 02 25 08 4B 

2019-10-10 12:01:28.817 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 80 02 25 08 4B 

2019-10-10 12:01:28.819 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:01:28.822 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:01:28.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 23: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 8

2019-10-10 12:01:28.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:01:28.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:01:28.828 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2019-10-10 12:01:28.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 23: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 8

2019-10-10 12:01:28.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:01:28.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:28.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:01:28.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:01:28.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:01:28.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 23: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 8

2019-10-10 12:01:28.844 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:01:28.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 23: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 8

2019-10-10 12:01:28.850 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:01:28.852 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2019-10-10 12:01:28.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 23: Advanced to WAIT_REQUEST

2019-10-10 12:01:28.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 23: Transaction not completed

2019-10-10 12:01:28.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:28.863 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:01:30.106 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 08 00 00 80 63 

2019-10-10 12:01:30.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=8, payload=08 00 00 80 

2019-10-10 12:01:30.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=8, payload=08 00 00 80 

2019-10-10 12:01:30.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 23: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 8

2019-10-10 12:01:30.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:01:30.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 23: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 8

2019-10-10 12:01:30.125 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 23: (Callback 8)

2019-10-10 12:01:30.127 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:01:30.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 23: callback 8

2019-10-10 12:01:30.133 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=8, payload=08 00 00 80 

2019-10-10 12:01:30.137 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 8, Status = Transmission complete and ACK received(0)

2019-10-10 12:01:30.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 23: Advanced to WAIT_DATA

2019-10-10 12:01:30.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 23: Transaction not completed

2019-10-10 12:01:30.144 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:30.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:01:30.500 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 80 03 5A 2B 

2019-10-10 12:01:30.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 80 03 5A 

2019-10-10 12:01:30.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 03 80 03 5A 

2019-10-10 12:01:30.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:01:30.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DYNAMIC_VALUES)

2019-10-10 12:01:30.524 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0

2019-10-10 12:01:30.527 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2019-10-10 12:01:30.532 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT

2019-10-10 12:01:30.536 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 3: Battery report value = 90

2019-10-10 12:01:30.542 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2019-10-10 12:01:30.546 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=90

2019-10-10 12:01:30.554 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:ee0235b5:node3:battery-level to 90 [DecimalType]

2019-10-10 12:01:30.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2019-10-10 12:01:30.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@dc1fb0.

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

2019-10-10 12:01:30.570 [vent.ItemStateChangedEvent] - ZWaveNode003SE812Siren_BatteryLevel changed from NULL to 90

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

2019-10-10 12:01:30.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@dc1fb0.

2019-10-10 12:01:30.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:23 DONE

2019-10-10 12:01:30.577 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-10 12:01:30.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 23: Transaction event listener: DONE: DONE -> 

2019-10-10 12:01:30.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2019-10-10 12:01:30.586 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node Init response (4) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@b350b2

2019-10-10 12:01:30.587 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:01:30.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:30.588 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node Init transaction completed with response COMPLETE

2019-10-10 12:01:30.590 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_SWITCH_BINARY

2019-10-10 12:01:30.592 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:01:30.592 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Found 1 instances of COMMAND_CLASS_SWITCH_BINARY for endpoint 0

2019-10-10 12:01:30.595 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Creating new message for application command SWITCH_BINARY_GET

2019-10-10 12:01:30.597 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2019-10-10 12:01:30.599 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

2019-10-10 12:01:30.602 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: ZWaveCommandClassTransactionPayload - send to node

2019-10-10 12:01:30.604 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2019-10-10 12:01:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured

2019-10-10 12:01:30.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1117496

2019-10-10 12:01:30.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Bump transaction 24 priority from Get to Immediate

2019-10-10 12:01:30.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue

2019-10-10 12:01:30.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Added 24 to queue - size 4

2019-10-10 12:01:30.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:01:30.621 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 25 02 25 09 EF 

2019-10-10 12:01:30.625 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 25 02 25 09 EF 

2019-10-10 12:01:30.628 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:01:30.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 24: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 9

2019-10-10 12:01:30.630 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:01:30.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:01:30.638 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2019-10-10 12:01:30.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:01:30.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 24: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 9

2019-10-10 12:01:30.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:01:30.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:01:30.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:30.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:01:30.654 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 09 00 00 03 E1 

2019-10-10 12:01:30.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:01:30.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 24: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 9

2019-10-10 12:01:30.660 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=9, payload=09 00 00 03 

2019-10-10 12:01:30.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:01:30.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 24: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 9

2019-10-10 12:01:30.668 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:01:30.674 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: sentData successfully placed on stack.

2019-10-10 12:01:30.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 24: Advanced to WAIT_REQUEST

2019-10-10 12:01:30.679 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 24: Transaction not completed

2019-10-10 12:01:30.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=9, payload=09 00 00 03 

2019-10-10 12:01:30.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 24: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 9

2019-10-10 12:01:30.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:01:30.691 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 24: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 9

2019-10-10 12:01:30.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 24: (Callback 9)

2019-10-10 12:01:30.696 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:01:30.698 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 24: callback 9

2019-10-10 12:01:30.702 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=9, payload=09 00 00 03 

2019-10-10 12:01:30.704 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 9, Status = Transmission complete and ACK received(0)

2019-10-10 12:01:30.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 24: Advanced to WAIT_DATA

2019-10-10 12:01:30.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: TID 24: Transaction not completed

2019-10-10 12:01:30.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:30.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:01:31.115 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 25 03 00 D4 

2019-10-10 12:01:31.121 [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 

2019-10-10 12:01:31.125 [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 

2019-10-10 12:01:31.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:01:31.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (ALIVE:DYNAMIC_VALUES)

2019-10-10 12:01:31.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0

2019-10-10 12:01:31.135 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY not supported

2019-10-10 12:01:31.137 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

2019-10-10 12:01:31.141 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 3: Switch Binary report, value = 0

2019-10-10 12:01:31.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2019-10-10 12:01:31.147 [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

2019-10-10 12:01:31.149 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:ee0235b5:node3:switch_binary to OFF [OnOffType]

2019-10-10 12:01:31.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.

2019-10-10 12:01:31.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@218d08.

2019-10-10 12:01:31.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@218d08.

2019-10-10 12:01:31.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:24 DONE

2019-10-10 12:01:31.168 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-10 12:01:31.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 24: Transaction event listener: DONE: DONE -> 

2019-10-10 12:01:31.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2019-10-10 12:01:31.176 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@15e3ef8

2019-10-10 12:01:31.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:01:31.179 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node Init transaction completed with response COMPLETE

2019-10-10 12:01:31.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:01:31.182 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_BASIC

2019-10-10 12:01:31.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:01:31.186 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node advancer: DYNAMIC_VALUES - checking COMMAND_CLASS_ALARM

2019-10-10 12:01:31.188 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Found 0 instances of COMMAND_CLASS_ALARM for endpoint 0

2019-10-10 12:01:31.191 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node advancer: Initialisation complete!

2019-10-10 12:01:31.194 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node advancer - advancing to DYNAMIC_END

2019-10-10 12:01:31.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2019-10-10 12:01:31.200 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 3: Serializing to file /var/lib/openhab2/zwave/network_c2415a1a__node_3.xml

2019-10-10 12:01:31.255 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Node advancer - advancing to DONE

2019-10-10 12:01:31.257 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2019-10-10 12:01:31.264 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 3: Serializing to file /var/lib/openhab2/zwave/network_c2415a1a__node_3.xml

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

2019-10-10 12:01:31.270 [hingStatusInfoChangedEvent] - 'zwave:device:ee0235b5:node3' changed from ONLINE: Node initialising: REQUEST_NIF to ONLINE

2019-10-10 12:01:36.413 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:ee0235b5:node8' has been removed.

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

2019-10-10 12:01:36.419 [DEBUG] [org.openhab.binding.zwave           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=426, service.bundleid=217, service.scope=singleton} - org.openhab.binding.zwave

2019-10-10 12:01:36.443 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:ee0235b5:node8.

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

2019-10-10 12:01:36.447 [hingStatusInfoChangedEvent] - 'zwave:device:ee0235b5:node8' changed from UNINITIALIZED to INITIALIZING

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

2019-10-10 12:01:36.453 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: MANUFACTURER not set

2019-10-10 12:01:36.456 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Controller status changed to ONLINE.

2019-10-10 12:01:36.459 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Controller is ONLINE. Starting device initialisation.

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

2019-10-10 12:01:36.462 [hingStatusInfoChangedEvent] - 'zwave:device:ee0235b5:node8' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline

2019-10-10 12:01:36.481 [hingStatusInfoChangedEvent] - 'zwave:device:ee0235b5:node8' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE

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

2019-10-10 12:01:36.510 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating node properties.

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

2019-10-10 12:01:36.513 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ee0235b5:node8' has been updated.

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

2019-10-10 12:01:36.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating node properties. MAN=2147483647

2019-10-10 12:01:36.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Properties synchronised

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

2019-10-10 12:01:36.525 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ee0235b5:node8' has been updated.

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

2019-10-10 12:01:36.533 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2019-10-10 12:01:36.535 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Initialising Thing Node...

2019-10-10 12:01:36.539 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling intialised at 1800 seconds - start in 1162800 milliseconds.

2019-10-10 12:01:36.541 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Device initialisation complete.

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

2019-10-10 12:01:36.543 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ee0235b5:node8' has been updated.

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

2019-10-10 12:01:40.895 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised

2019-10-10 12:02:03.177 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update received

2019-10-10 12:02:03.228 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set action_heal to false (Boolean)

2019-10-10 12:02:03.232 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set binding_cmdrepollperiod to 1500 (BigDecimal)

2019-10-10 12:02:03.237 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set action_failed to false (Boolean)

2019-10-10 12:02:03.240 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set action_remove to false (Boolean)

2019-10-10 12:02:03.244 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set binding_pollperiod to 86400 (BigDecimal)

2019-10-10 12:02:03.252 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling intialised at 86400 seconds - start in 6652800 milliseconds.

2019-10-10 12:02:03.257 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update ignored node_id to 8 (BigDecimal)

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

2019-10-10 12:02:03.276 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ee0235b5:node8' has been updated.

2019-10-10 12:02:03.310 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[]]

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

2019-10-10 12:05:04.058 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ee0235b5

2019-10-10 12:05:04.067 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do

2019-10-10 12:05:04.074 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:ee0235b5

2019-10-10 12:05:04.079 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2019-10-10 12:05:04.086 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2019-10-10 12:05:04.091 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion

2019-10-10 12:05:04.101 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.

2019-10-10 12:05:04.105 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 25 to queue - size 1

2019-10-10 12:05:04.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:05:04.116 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 0A 7B 

2019-10-10 12:05:04.122 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 0A 7B 

2019-10-10 12:05:04.125 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:05:04.128 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:05:04.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:05:04.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 10

2019-10-10 12:05:04.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:05:04.137 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 0A 01 00 00 B9 

2019-10-10 12:05:04.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 10

2019-10-10 12:05:04.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:05:04.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=10, payload=0A 01 00 00 

2019-10-10 12:05:04.144 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:05:04.148 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:05:04.152 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=10, payload=0A 01 00 00 

2019-10-10 12:05:04.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 10

2019-10-10 12:05:04.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:05:04.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 10

2019-10-10 12:05:04.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 25: (Callback 10)

2019-10-10 12:05:04.166 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:05:04.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 25: callback 10

2019-10-10 12:05:04.172 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=10, payload=0A 01 00 00 

2019-10-10 12:05:04.174 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.

2019-10-10 12:05:04.180 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart

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

2019-10-10 12:05:04.181 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@18c6e2b

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

2019-10-10 12:05:04.184 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 25: Transaction COMPLETED

2019-10-10 12:05:04.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 55ms

2019-10-10 12:05:04.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 25: Transaction completed

2019-10-10 12:05:04.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:25 DONE

2019-10-10 12:05:04.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:05:04.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:05:06.137 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed

2019-10-10 12:05:06.151 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery resolved to thingType zwave:everspring_se812_00_000

2019-10-10 12:05:06.171 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed

2019-10-10 12:05:06.182 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery resolved to thingType zwave:everspring_st812_00_000

2019-10-10 12:05:06.200 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery completed

2019-10-10 12:05:06.210 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 6: Device discovery resolved to thingType zwave:fibaro_fgk101_00_000

2019-10-10 12:05:06.223 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery completed

2019-10-10 12:05:06.233 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery could not resolve to a thingType! Manufacturer data not known.

2019-10-10 12:05:34.074 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ee0235b5

2019-10-10 12:05:34.080 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart

2019-10-10 12:05:34.084 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2019-10-10 12:05:34.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 26 to queue - size 1

2019-10-10 12:05:34.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:05:34.099 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 0B BE 

2019-10-10 12:05:34.105 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 0B BE 

2019-10-10 12:05:34.112 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:05:34.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11

2019-10-10 12:05:34.122 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:05:34.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:05:34.135 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:05:34.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11

2019-10-10 12:05:34.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:05:34.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:05:34.144 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:05:34.203 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 0B 06 00 00 BF 

2019-10-10 12:05:34.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=11, payload=0B 06 00 00 

2019-10-10 12:05:34.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=11, payload=0B 06 00 00 

2019-10-10 12:05:34.214 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11

2019-10-10 12:05:34.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:05:34.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 11

2019-10-10 12:05:34.220 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 26: (Callback 11)

2019-10-10 12:05:34.222 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:05:34.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 26: callback 11

2019-10-10 12:05:34.227 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=11, payload=0B 06 00 00 

2019-10-10 12:05:34.229 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.

2019-10-10 12:05:34.231 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone

2019-10-10 12:05:34.234 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2019-10-10 12:05:34.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 27 to queue - size 1

2019-10-10 12:05:34.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:05:34.240 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion

2019-10-10 12:05:34.243 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done

2019-10-10 12:05:34.245 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 26: Advanced to DONE

2019-10-10 12:05:34.247 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 130ms

2019-10-10 12:05:34.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 26: Transaction completed

2019-10-10 12:05:34.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:26 DONE

2019-10-10 12:05:34.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:05:34.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:05:34.262 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 

2019-10-10 12:05:34.266 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 

2019-10-10 12:05:34.268 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:05:34.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 27: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-10-10 12:05:34.270 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:05:34.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:05:34.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:05:34.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 27: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-10-10 12:05:34.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:05:34.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:05:34.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:05:39.273 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 27: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-10-10 12:05:39.291 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 27: Transaction is current transaction, so clearing!!!!!

2019-10-10 12:05:39.293 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 27: Transaction CANCELLED

2019-10-10 12:05:39.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:27 CANCELLED

2019-10-10 12:05:39.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:06:33.396 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 08 09 71 05 00 00 00 FF 07 09 00 70 

2019-10-10 12:06:33.405 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 71 05 00 00 00 FF 07 09 00 

2019-10-10 12:06:33.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 71 05 00 00 00 FF 07 09 00 

2019-10-10 12:06:33.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:06:33.414 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:REQUEST_NIF)

2019-10-10 12:06:33.416 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_ALARM, endpoint 0

2019-10-10 12:06:33.419 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command class COMMAND_CLASS_ALARM not found, trying to add it.

2019-10-10 12:06:33.421 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_ALARM

2019-10-10 12:06:33.425 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_ALARM, endpoint 0 created

2019-10-10 12:06:33.427 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_ALARM to endpoint 0

2019-10-10 12:06:33.430 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported

2019-10-10 12:06:33.432 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_ALARM V0 NOTIFICATION_REPORT

2019-10-10 12:06:33.436 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: NOTIFICATION report - 0 = 0, event=9, status=255, plen=0

2019-10-10 12:06:33.439 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Alarm Type = BURGLAR (0)

2019-10-10 12:06:33.442 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAlarmValueEvent

2019-10-10 12:06:33.444 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255

2019-10-10 12:06:33.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.

2019-10-10 12:06:33.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@ab97ef.

2019-10-10 12:06:33.452 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:06:33.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:06:33.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:06:33.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:06:38.033 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 08 0A 71 05 00 00 00 FF 07 00 01 09 6D 

2019-10-10 12:06:38.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 71 05 00 00 00 FF 07 00 01 09 

2019-10-10 12:06:38.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 71 05 00 00 00 FF 07 00 01 09 

2019-10-10 12:06:38.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:06:38.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:REQUEST_NIF)

2019-10-10 12:06:38.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_ALARM, endpoint 0

2019-10-10 12:06:38.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported

2019-10-10 12:06:38.065 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_ALARM V0 NOTIFICATION_REPORT

2019-10-10 12:06:38.067 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1

2019-10-10 12:06:38.070 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Alarm Type = BURGLAR (0)

2019-10-10 12:06:38.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAlarmValueEvent

2019-10-10 12:06:38.075 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255

2019-10-10 12:06:38.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.

2019-10-10 12:06:38.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@153a36c.

2019-10-10 12:06:38.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:06:38.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:06:38.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:06:38.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

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

2019-10-10 12:06:42.624 [vent.ItemStateChangedEvent] - MarioSGSM_Online changed from OFF to ON

2019-10-10 12:06:42.627 [vent.ItemStateChangedEvent] - MarioSGSM_LatencyMs changed from UNDEF to 1271.0

2019-10-10 12:06:46.324 [vent.ItemStateChangedEvent] - MarioSGSM_LastSeen changed from 2019-10-10T12:00:12.530+0200 to 2019-10-10T12:06:42.603+0200

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

2019-10-10 12:07:20.640 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised

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

2019-10-10 12:07:51.351 [vent.ItemStateChangedEvent] - MarioSGSM_Online changed from ON to OFF

2019-10-10 12:07:51.357 [vent.ItemStateChangedEvent] - MarioSGSM_LatencyMs changed from 1271.0 to UNDEF

2019-10-10 12:13:12.804 [vent.ItemStateChangedEvent] - MarioSGSM_Online changed from OFF to ON

2019-10-10 12:13:12.816 [vent.ItemStateChangedEvent] - MarioSGSM_LatencyMs changed from UNDEF to 1266.0

2019-10-10 12:13:16.494 [vent.ItemStateChangedEvent] - MarioSGSM_LastSeen changed from 2019-10-10T12:06:42.603+0200 to 2019-10-10T12:13:12.773+0200

2019-10-10 12:14:21.535 [vent.ItemStateChangedEvent] - MarioSGSM_Online changed from ON to OFF

2019-10-10 12:14:21.543 [vent.ItemStateChangedEvent] - MarioSGSM_LatencyMs changed from 1266.0 to UNDEF

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

2019-10-10 12:17:14.890 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 08 09 71 05 00 00 00 FF 07 09 00 70 

2019-10-10 12:17:14.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 71 05 00 00 00 FF 07 09 00 

2019-10-10 12:17:14.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 71 05 00 00 00 FF 07 09 00 

2019-10-10 12:17:14.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:17:14.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:REQUEST_NIF)

2019-10-10 12:17:14.920 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_ALARM, endpoint 0

2019-10-10 12:17:14.924 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported

2019-10-10 12:17:14.927 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_ALARM V0 NOTIFICATION_REPORT

2019-10-10 12:17:14.931 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: NOTIFICATION report - 0 = 0, event=9, status=255, plen=0

2019-10-10 12:17:14.934 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Alarm Type = BURGLAR (0)

2019-10-10 12:17:14.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAlarmValueEvent

2019-10-10 12:17:14.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255

2019-10-10 12:17:14.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.

2019-10-10 12:17:14.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c03743.

2019-10-10 12:17:14.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:17:14.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:17:14.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:14.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:16.665 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1B 00 49 84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 29 

2019-10-10 12:17:16.684 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=8, callback=132, payload=84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 

2019-10-10 12:17:16.694 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=8, callback=132, payload=84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 

2019-10-10 12:17:16.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:17:16.700 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

2019-10-10 12:17:16.704 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null

2019-10-10 12:17:16.719 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=8, callback=132, payload=84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 

2019-10-10 12:17:16.723 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update request. Node information received. Transaction null

2019-10-10 12:17:16.726 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO

2019-10-10 12:17:16.736 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created

2019-10-10 12:17:16.739 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO.

2019-10-10 12:17:16.743 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes.

2019-10-10 12:17:16.746 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_ASSOCIATION

2019-10-10 12:17:16.751 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_ASSOCIATION, endpoint 0 created

2019-10-10 12:17:16.754 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_ASSOCIATION.

2019-10-10 12:17:16.758 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_ASSOCIATION to the list of supported command classes.

2019-10-10 12:17:16.761 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_ASSOCIATION_GRP_INFO

2019-10-10 12:17:16.771 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_ASSOCIATION_GRP_INFO, endpoint 0 created

2019-10-10 12:17:16.775 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO.

2019-10-10 12:17:16.778 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO to the list of supported command classes.

2019-10-10 12:17:16.781 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE

2019-10-10 12:17:16.785 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_VERSION

2019-10-10 12:17:16.789 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_VERSION, endpoint 0 created

2019-10-10 12:17:16.793 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_VERSION.

2019-10-10 12:17:16.796 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes.

2019-10-10 12:17:16.800 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC

2019-10-10 12:17:16.804 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created

2019-10-10 12:17:16.808 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC.

2019-10-10 12:17:16.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes.

2019-10-10 12:17:16.814 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY

2019-10-10 12:17:16.820 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created

2019-10-10 12:17:16.823 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY.

2019-10-10 12:17:16.827 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes.

2019-10-10 12:17:16.829 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_POWERLEVEL

2019-10-10 12:17:16.837 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_POWERLEVEL, endpoint 0 created

2019-10-10 12:17:16.840 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_POWERLEVEL.

2019-10-10 12:17:16.844 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_POWERLEVEL to the list of supported command classes.

2019-10-10 12:17:16.846 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_BATTERY

2019-10-10 12:17:16.851 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_BATTERY, endpoint 0 created

2019-10-10 12:17:16.854 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_BATTERY.

2019-10-10 12:17:16.858 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_BATTERY to the list of supported command classes.

2019-10-10 12:17:16.860 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_SECURITY

2019-10-10 12:17:16.872 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_SECURITY, endpoint 0 created

2019-10-10 12:17:16.874 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_SECURITY.

2019-10-10 12:17:16.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_SECURITY to the list of supported command classes.

2019-10-10 12:17:16.881 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Unsupported command class COMMAND_CLASS_SECURITY_2

2019-10-10 12:17:16.885 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_WAKE_UP

2019-10-10 12:17:16.889 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_WAKE_UP, endpoint 0 created

2019-10-10 12:17:16.894 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_WAKE_UP.

2019-10-10 12:17:16.897 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_WAKE_UP to the list of supported command classes.

2019-10-10 12:17:16.901 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_CONFIGURATION

2019-10-10 12:17:16.904 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_CONFIGURATION, endpoint 0 created

2019-10-10 12:17:16.908 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_CONFIGURATION.

2019-10-10 12:17:16.911 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_CONFIGURATION to the list of supported command classes.

2019-10-10 12:17:16.916 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Unsupported command class COMMAND_CLASS_SUPERVISION

2019-10-10 12:17:16.918 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_SENSOR_MULTILEVEL

2019-10-10 12:17:16.927 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 created

2019-10-10 12:17:16.932 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_SENSOR_MULTILEVEL.

2019-10-10 12:17:16.935 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_SENSOR_MULTILEVEL to the list of supported command classes.

2019-10-10 12:17:16.939 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_SENSOR_BINARY

2019-10-10 12:17:16.942 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0 created

2019-10-10 12:17:16.946 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_SENSOR_BINARY.

2019-10-10 12:17:16.950 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_SENSOR_BINARY to the list of supported command classes.

2019-10-10 12:17:16.953 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Creating new instance of command class COMMAND_CLASS_FIRMWARE_UPDATE_MD

2019-10-10 12:17:16.957 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Command class COMMAND_CLASS_FIRMWARE_UPDATE_MD, endpoint 0 created

2019-10-10 12:17:16.960 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update is adding command class COMMAND_CLASS_FIRMWARE_UPDATE_MD.

2019-10-10 12:17:16.964 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Adding command class COMMAND_CLASS_FIRMWARE_UPDATE_MD to the list of supported command classes.

2019-10-10 12:17:16.966 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update - no transaction.

2019-10-10 12:17:16.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:16.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:17.223 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Is awake with 1 messages in the queue

2019-10-10 12:17:17.229 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Start sleep timer at 5000ms

2019-10-10 12:17:17.233 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveNodeStatusEvent

2019-10-10 12:17:17.254 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 8: Node Status event - Node is AWAKE

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

2019-10-10 12:17:17.263 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ee0235b5:node8' has been updated.

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

2019-10-10 12:17:19.733 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: WakeupTimerTask 1 Messages waiting, state REQUEST_NIF

2019-10-10 12:17:22.233 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: WakeupTimerTask 1 Messages waiting, state REQUEST_NIF

2019-10-10 12:17:22.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: No more messages, go back to sleep

2019-10-10 12:17:22.240 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION

2019-10-10 12:17:22.244 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY NOT required on COMMAND_CLASS_WAKE_UP

2019-10-10 12:17:22.248 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured

2019-10-10 12:17:22.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@cca272

2019-10-10 12:17:22.256 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Bump transaction 28 priority from Immediate to Immediate

2019-10-10 12:17:22.260 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue

2019-10-10 12:17:22.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 28 to queue - size 4

2019-10-10 12:17:22.266 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:22.271 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 08 93 

2019-10-10 12:17:22.276 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 08 93 

2019-10-10 12:17:22.279 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:17:22.281 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:17:22.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 9: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:22.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:22.294 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 

2019-10-10 12:17:22.294 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:17:22.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:22.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:22.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:22.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:22.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:22.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 9: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.324 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:22.327 [DEBUG] [lmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack.

2019-10-10 12:17:22.330 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1B 00 49 84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 29 

2019-10-10 12:17:22.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 9: Advanced to WAIT_DATA

2019-10-10 12:17:22.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 9: Transaction not completed

2019-10-10 12:17:22.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:22.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:22.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=8, callback=132, payload=84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 

2019-10-10 12:17:22.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=8, callback=132, payload=84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 

2019-10-10 12:17:22.363 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 9: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.367 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:22.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 9: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 9: (Callback 0)

2019-10-10 12:17:22.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 9: callback 0

2019-10-10 12:17:22.385 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=8, callback=132, payload=84 08 15 04 07 01 5E 85 59 55 86 72 5A 73 80 98 9F 71 84 70 6C 31 30 7A 

2019-10-10 12:17:22.388 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 8: Application update request. Node information received. Transaction TID 9: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0

2019-10-10 12:17:22.392 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE

2019-10-10 12:17:22.395 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Unsupported command class COMMAND_CLASS_SECURITY_2

2019-10-10 12:17:22.399 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Unsupported command class COMMAND_CLASS_SUPERVISION

2019-10-10 12:17:22.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 9: Advanced to DONE

2019-10-10 12:17:22.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 124ms

2019-10-10 12:17:22.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 9: Transaction completed

2019-10-10 12:17:22.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:9 DONE

2019-10-10 12:17:22.416 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-10 12:17:22.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 9: Transaction event listener: DONE: DONE -> 

2019-10-10 12:17:22.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:22.422 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@12f70a2

2019-10-10 12:17:22.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:22.426 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node Init transaction completed with response COMPLETE

2019-10-10 12:17:22.429 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 84 08 25 0D 4B 

2019-10-10 12:17:22.430 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer - advancing to SECURITY_REPORT

2019-10-10 12:17:22.432 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2019-10-10 12:17:22.437 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 84 08 25 0D 4B 

2019-10-10 12:17:22.440 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: Updated networkKey

2019-10-10 12:17:22.442 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:17:22.444 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:17:22.445 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 28: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 13

2019-10-10 12:17:22.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:22.451 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:22.453 [DEBUG] [mmandclass.ZWaveSecurityCommandClass] - NODE 8: setupNetworkKey useSchemeZero=false

2019-10-10 12:17:22.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 28: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 13

2019-10-10 12:17:22.456 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2019-10-10 12:17:22.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:17:22.463 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Skipping secure inclusion

2019-10-10 12:17:22.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:22.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:22.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:22.468 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer - advancing to MANUFACTURER

2019-10-10 12:17:22.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:22.474 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0D 00 00 03 E5 

2019-10-10 12:17:22.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 28: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 13

2019-10-10 12:17:22.476 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2019-10-10 12:17:22.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:22.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 28: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 13

2019-10-10 12:17:22.483 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer: MANUFACTURER - send ManufacturerSpecific

2019-10-10 12:17:22.489 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:22.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=13, payload=0D 00 00 03 

2019-10-10 12:17:22.492 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.

2019-10-10 12:17:22.495 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 8: Creating new message for command MANUFACTURER_SPECIFIC_GET

2019-10-10 12:17:22.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 28: Advanced to WAIT_REQUEST

2019-10-10 12:17:22.500 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: ZWaveCommandClassTransactionPayload - send to node

2019-10-10 12:17:22.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 28: Transaction not completed

2019-10-10 12:17:22.504 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC

2019-10-10 12:17:22.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=13, payload=0D 00 00 03 

2019-10-10 12:17:22.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured

2019-10-10 12:17:22.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 28: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 13

2019-10-10 12:17:22.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@930731

2019-10-10 12:17:22.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:22.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 28: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 13

2019-10-10 12:17:22.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Bump transaction 29 priority from Config to Immediate

2019-10-10 12:17:22.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 28: (Callback 13)

2019-10-10 12:17:22.526 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:17:22.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 28: callback 13

2019-10-10 12:17:22.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue

2019-10-10 12:17:22.534 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=13, payload=0D 00 00 03 

2019-10-10 12:17:22.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 29 to queue - size 3

2019-10-10 12:17:22.538 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 13, Status = Transmission complete and ACK received(0)

2019-10-10 12:17:22.541 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:22.542 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 28: Transaction COMPLETED

2019-10-10 12:17:22.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 100ms

2019-10-10 12:17:22.549 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 28: Transaction completed

2019-10-10 12:17:22.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:28 DONE

2019-10-10 12:17:22.556 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-10 12:17:22.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 28: Transaction event listener: DONE: DONE -> 

2019-10-10 12:17:22.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:22.564 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Went to sleep COMPLETE

2019-10-10 12:17:22.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:22.653 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Is awake with 1 messages in the queue

2019-10-10 12:17:22.657 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Start sleep timer at 5000ms

2019-10-10 12:17:22.660 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveNodeStatusEvent

2019-10-10 12:17:22.675 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 8: Node Status event - Node is AWAKE

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

2019-10-10 12:17:22.686 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:ee0235b5:node8' has been updated.

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

2019-10-10 12:17:25.160 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: WakeupTimerTask 1 Messages waiting, state MANUFACTURER

2019-10-10 12:17:27.660 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: WakeupTimerTask 1 Messages waiting, state MANUFACTURER

2019-10-10 12:17:27.663 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: No more messages, go back to sleep

2019-10-10 12:17:27.681 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 8: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION

2019-10-10 12:17:27.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY NOT required on COMMAND_CLASS_WAKE_UP

2019-10-10 12:17:27.687 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured

2019-10-10 12:17:27.689 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@34c902

2019-10-10 12:17:27.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Bump transaction 30 priority from Immediate to Immediate

2019-10-10 12:17:27.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue

2019-10-10 12:17:27.698 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 30 to queue - size 4

2019-10-10 12:17:27.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:27.707 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 72 04 25 0E B2 

2019-10-10 12:17:27.711 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 72 04 25 0E B2 

2019-10-10 12:17:27.714 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:17:27.716 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:17:27.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 29: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 14

2019-10-10 12:17:27.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:27.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:27.724 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2019-10-10 12:17:27.725 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 29: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 14

2019-10-10 12:17:27.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:17:27.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:27.729 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:27.732 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:27.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:27.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 29: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 14

2019-10-10 12:17:27.741 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0E 00 00 02 E7 

2019-10-10 12:17:27.743 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:27.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 29: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 14

2019-10-10 12:17:27.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=14, payload=0E 00 00 02 

2019-10-10 12:17:27.750 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:27.755 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.

2019-10-10 12:17:27.758 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 29: Advanced to WAIT_REQUEST

2019-10-10 12:17:27.758 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 08 08 72 05 02 08 02 00 00 0F 85 

2019-10-10 12:17:27.762 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 29: Transaction not completed

2019-10-10 12:17:27.766 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=14, payload=0E 00 00 02 

2019-10-10 12:17:27.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 08 72 05 02 08 02 00 00 0F 

2019-10-10 12:17:27.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 29: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 14

2019-10-10 12:17:27.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:27.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 29: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 14

2019-10-10 12:17:27.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 29: (Callback 14)

2019-10-10 12:17:27.784 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:17:27.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 29: callback 14

2019-10-10 12:17:27.791 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=14, payload=0E 00 00 02 

2019-10-10 12:17:27.794 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 14, Status = Transmission complete and ACK received(0)

2019-10-10 12:17:27.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 29: Advanced to WAIT_DATA

2019-10-10 12:17:27.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 29: Transaction not completed

2019-10-10 12:17:27.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 08 72 05 02 08 02 00 00 0F 

2019-10-10 12:17:27.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:17:27.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:MANUFACTURER)

2019-10-10 12:17:27.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0

2019-10-10 12:17:27.820 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY NOT required on COMMAND_CLASS_MANUFACTURER_SPECIFIC

2019-10-10 12:17:27.823 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_MANUFACTURER_SPECIFIC V0 MANUFACTURER_SPECIFIC_REPORT

2019-10-10 12:17:27.827 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 8: Manufacturer ID = 0x208

2019-10-10 12:17:27.830 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 8: Device Type     = 0x200

2019-10-10 12:17:27.834 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 8: Device ID       = 0xf

2019-10-10 12:17:27.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.

2019-10-10 12:17:27.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@119f46.

2019-10-10 12:17:27.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@119f46.

2019-10-10 12:17:27.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:29 DONE

2019-10-10 12:17:27.853 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-10 12:17:27.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 29: Transaction event listener: DONE: DONE -> 

2019-10-10 12:17:27.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1

2019-10-10 12:17:27.859 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1d35f4f

2019-10-10 12:17:27.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:17:27.862 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node Init transaction completed with response COMPLETE

2019-10-10 12:17:27.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:27.865 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer - advancing to APP_VERSION

2019-10-10 12:17:27.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-10-10 12:17:27.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveInitializationStateEvent

2019-10-10 12:17:27.873 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: Node advancer: APP_VERSION - send VersionMessage

2019-10-10 12:17:27.875 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 08 02 84 08 25 0F 49 

2019-10-10 12:17:27.877 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 8: Creating new message for command VERSION_GET

2019-10-10 12:17:27.880 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 8: ZWaveCommandClassTransactionPayload - send to node

2019-10-10 12:17:27.880 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 09 00 13 08 02 84 08 25 0F 49 

2019-10-10 12:17:27.883 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY NOT required on COMMAND_CLASS_VERSION

2019-10-10 12:17:27.884 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-10 12:17:27.886 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-10-10 12:17:27.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 30: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 15

2019-10-10 12:17:27.889 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:27.895 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-10-10 12:17:27.896 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2019-10-10 12:17:27.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 15

2019-10-10 12:17:27.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_VERSION is NOT required to be secured

2019-10-10 12:17:27.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-10 12:17:27.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@fd5ff5

2019-10-10 12:17:27.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:27.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:27.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:27.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Bump transaction 31 priority from Config to Immediate

2019-10-10 12:17:27.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue

2019-10-10 12:17:27.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Added 31 to queue - size 3

2019-10-10 12:17:27.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:27.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-10-10 12:17:27.925 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 15

2019-10-10 12:17:27.928 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 0F 00 00 02 E6 

2019-10-10 12:17:27.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:27.930 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 15

2019-10-10 12:17:27.933 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 

2019-10-10 12:17:27.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=15, payload=0F 00 00 02 

2019-10-10 12:17:27.936 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.

2019-10-10 12:17:27.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30: Advanced to WAIT_REQUEST

2019-10-10 12:17:27.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 30: Transaction not completed

2019-10-10 12:17:27.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=15, payload=0F 00 00 02 

2019-10-10 12:17:27.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 30: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 15

2019-10-10 12:17:27.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-10 12:17:27.954 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 30: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 15

2019-10-10 12:17:27.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 30: (Callback 15)

2019-10-10 12:17:27.960 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-10-10 12:17:27.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 30: callback 15

2019-10-10 12:17:27.966 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=15, payload=0F 00 00 02 

2019-10-10 12:17:27.969 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 15, Status = Transmission complete and ACK received(0)

2019-10-10 12:17:27.972 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 30: Transaction COMPLETED

2019-10-10 12:17:27.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 88ms

2019-10-10 12:17:27.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: TID 30: Transaction completed

2019-10-10 12:17:27.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:30 DONE

2019-10-10 12:17:27.985 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-10 12:17:27.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 30: Transaction event listener: DONE: DONE -> 

2019-10-10 12:17:27.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:27.995 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Went to sleep COMPLETE

2019-10-10 12:17:27.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

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

2019-10-10 12:17:32.895 [vent.ItemStateChangedEvent] - MarioSGSM_Online changed from OFF to ON

2019-10-10 12:17:32.911 [vent.ItemStateChangedEvent] - MarioSGSM_LatencyMs changed from UNDEF to 1248.0

2019-10-10 12:17:36.609 [vent.ItemStateChangedEvent] - MarioSGSM_LastSeen changed from 2019-10-10T12:13:12.773+0200 to 2019-10-10T12:17:32.863+0200

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

2019-10-10 12:17:44.624 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 08 0A 71 05 00 00 00 FF 07 00 01 09 6D 

2019-10-10 12:17:44.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 71 05 00 00 00 FF 07 00 01 09 

2019-10-10 12:17:44.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0A 71 05 00 00 00 FF 07 00 01 09 

2019-10-10 12:17:44.640 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-10 12:17:44.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:APP_VERSION)

2019-10-10 12:17:44.646 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_ALARM, endpoint 0

2019-10-10 12:17:44.649 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY NOT required on COMMAND_CLASS_ALARM

2019-10-10 12:17:44.652 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_ALARM V0 NOTIFICATION_REPORT

2019-10-10 12:17:44.655 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: NOTIFICATION report - 0 = 0, event=0, status=255, plen=1

2019-10-10 12:17:44.658 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 8: Alarm Type = BURGLAR (0)

2019-10-10 12:17:44.661 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAlarmValueEvent

2019-10-10 12:17:44.664 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255

2019-10-10 12:17:44.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.

2019-10-10 12:17:44.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@68893a.

2019-10-10 12:17:44.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:17:44.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

2019-10-10 12:17:44.679 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-10-10 12:17:44.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

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

2019-10-10 12:18:37.135 [vent.ItemStateChangedEvent] - MarioSGSM_LatencyMs changed from 1248.0 to 514.0

2019-10-10 12:18:37.930 [vent.ItemStateChangedEvent] - MarioSGSM_LastSeen changed from 2019-10-10T12:17:32.863+0200 to 2019-10-10T12:18:37.104+0200