2019-08-26 20:09:58.189 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 135: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:09:58.189 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 135: SECURITY not supported 2019-08-26 20:09:58.190 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 135: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:09:58.190 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 135: Sensor Type = Luminance(3), Scale = 1 2019-08-26 20:09:58.190 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 135: Sensor Value = 16 2019-08-26 20:09:58.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:09:58.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR _MULTILEVEL, value=16 2019-08-26 20:09:58.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Updating channel state zwave:device:6dad8bea:node135:sensor_luminance to 16 % [Quanti tyType] 2019-08-26 20:09:58.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Commands processed 1. 2019-08-26 20:09:58.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload @774e3f02. 2019-08-26 20:09:58.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:09:58.190 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:09:58.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:09:58.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:09:58.348 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 87 05 31 05 1B 01 00 5C 2019-08-26 20:09:58.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[ 0], dest=135, callback=0, payload=00 87 05 31 05 1B 01 00 2019-08-26 20:09:58.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=135, callb ack=0, payload=00 87 05 31 05 1B 01 00 2019-08-26 20:09:58.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:09:58.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:09:58.349 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 135: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:09:58.349 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 135: SECURITY not supported 2019-08-26 20:09:58.349 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 135: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:09:58.349 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 135: Sensor Type = Ultraviolet(27), Scale = 0 2019-08-26 20:09:58.349 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 135: Sensor Value = 0E+1 2019-08-26 20:09:58.349 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:09:58.349 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR _MULTILEVEL, value=0E+1 2019-08-26 20:09:58.349 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 135: Sensor conversion not performed for ULTRAVIOLET. 2019-08-26 20:09:58.350 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Updating channel state zwave:device:6dad8bea:node135:sensor_ultraviolet to 0 [Decimal Type] 2019-08-26 20:09:58.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Commands processed 1. 2019-08-26 20:09:58.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload @882c76d. 2019-08-26 20:09:58.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:09:58.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:09:58.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:09:58.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:09:58.429 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 87 02 84 07 F5 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[ 0], dest=135, callback=0, payload=00 87 02 84 07 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=135, callb ack=0, payload=00 87 02 84 07 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Commands processed 1. 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload @4a8ab900. 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:09:58.429 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:09:58.430 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:09:58.430 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. ^C nsautomate@ihp:/etc/openhab2/items$ clear nsautomate@ihp:/etc/openhab2/items$ tail -f /var/log/openhab2/zwave.log 2019-08-26 20:10:56.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Adding to device queue 2019-08-26 20:10:56.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: Added 43946 to queue - size 1 2019-08-26 20:10:56.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:10:56.313 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 1E 1B B7 2019-08-26 20:10:56.313 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 1E 1B B7 2019-08-26 20:10:56.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43946: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 27 2019-08-26 20:10:56.593 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 35: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@12b3bbe3 2019-08-26 20:10:56.593 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue 2019-08-26 20:10:56.593 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 35: Added 43947 to queue - size 1 2019-08-26 20:10:56.593 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:01.313 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 30: TID 43946: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:11:01.313 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43946: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:11:01.313 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43946: Transaction CANCELLED 2019-08-26 20:11:01.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 30: notifyTransactionResponse TID:43946 CANCELLED 2019-08-26 20:11:01.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:01.314 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 23 1C 8D 2019-08-26 20:11:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43946: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:01.314 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 23 1C 8D 2019-08-26 20:11:01.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43947: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 28 2019-08-26 20:11:01.314 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 30: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@645d240c 2019-08-26 20:11:02.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1b7909b1 2019-08-26 20:11:02.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue 2019-08-26 20:11:02.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Added 43948 to queue - size 1 2019-08-26 20:11:02.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:02.049 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 10 1D BF 2019-08-26 20:11:02.049 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 10 1D BF 2019-08-26 20:11:02.050 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:02.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29 2019-08-26 20:11:02.674 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:02.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:02.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:02.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29 2019-08-26 20:11:02.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:02.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:02.674 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:02.676 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 1D 23 8C 2019-08-26 20:11:02.676 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=29, payload=1D 23 2019-08-26 20:11:02.676 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=29, payload=1D 23 2019-08-26 20:11:02.676 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29 2019-08-26 20:11:02.676 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 29 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43948: (Callback 29) 2019-08-26 20:11:02.677 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43948: callback 29 2019-08-26 20:11:02.677 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=29, payload=1D 23 2019-08-26 20:11:02.677 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 16: Got NodeNeighborUpdate request. 2019-08-26 20:11:02.677 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 16: NodeNeighborUpdate FAILED 2019-08-26 20:11:02.677 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:02.677 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43948: Transaction CANCELLED 2019-08-26 20:11:02.677 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:11:02.677 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43948: Transaction RESET with 2 retries remaining. 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Added 43948 to queue - size 1 2019-08-26 20:11:02.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:02.678 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 10 1E BC 2019-08-26 20:11:02.678 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 10 1E BC 2019-08-26 20:11:02.678 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:02.678 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 30 2019-08-26 20:11:02.678 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: TID 43948: Transaction not completed 2019-08-26 20:11:02.678 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:02.678 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:02.678 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 30 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:02.679 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 1E 23 8F 2019-08-26 20:11:02.679 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=30, payload=1E 23 2019-08-26 20:11:02.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=30, payload=1E 23 2019-08-26 20:11:02.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 30 2019-08-26 20:11:02.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:02.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 30 2019-08-26 20:11:02.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43948: (Callback 30) 2019-08-26 20:11:02.680 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:02.680 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43948: callback 30 2019-08-26 20:11:02.680 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=30, payload=1E 23 2019-08-26 20:11:02.680 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 16: Got NodeNeighborUpdate request. 2019-08-26 20:11:02.680 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 16: NodeNeighborUpdate FAILED 2019-08-26 20:11:02.680 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:02.680 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43948: Transaction CANCELLED 2019-08-26 20:11:02.681 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:11:02.681 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43948: Transaction RESET with 1 retries remaining. 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Added 43948 to queue - size 1 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:02.681 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 10 1F BD 2019-08-26 20:11:02.681 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 10 1F BD 2019-08-26 20:11:02.681 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 31 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: TID 43948: Transaction not completed 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:02.681 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:02.682 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:02.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:02.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:02.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 31 2019-08-26 20:11:02.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:02.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:02.682 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:02.683 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 1F 23 8E 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=31, payload=1F 23 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=31, payload=1F 23 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 31 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43948: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 31 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43948: (Callback 31) 2019-08-26 20:11:02.683 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:02.683 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43948: callback 31 2019-08-26 20:11:02.683 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=31, payload=1F 23 2019-08-26 20:11:02.683 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 16: Got NodeNeighborUpdate request. 2019-08-26 20:11:02.684 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 16: NodeNeighborUpdate FAILED 2019-08-26 20:11:02.684 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:02.684 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43948: Transaction CANCELLED 2019-08-26 20:11:02.684 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:02.684 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Retry count exceeded. Discarding message: TID 43948: [CANCELLED] priority=Controller, requiresResponse=true, callback: 31 2019-08-26 20:11:02.684 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:02.684 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Setting OFFLINE 2019-08-26 20:11:02.684 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 16: Node Status event - Node is DEAD 2019-08-26 20:11:02.684 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 16: Node is DEAD. 2019-08-26 20:11:02.684 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:02.684 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Setting OFFLINE 2019-08-26 20:11:02.685 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 16: Node Status event - Node is DEAD 2019-08-26 20:11:02.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: TID 43948: Transaction completed 2019-08-26 20:11:02.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: notifyTransactionResponse TID:43948 CANCELLED 2019-08-26 20:11:02.685 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:02.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:02.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:02.685 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43948: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:02.686 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 16: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@2702a2ba 2019-08-26 20:11:03.841 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@26557334 2019-08-26 20:11:03.841 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Adding to device queue 2019-08-26 20:11:03.841 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Added 43949 to queue - size 1 2019-08-26 20:11:03.841 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:03.842 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 5B 20 C9 2019-08-26 20:11:03.842 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 5B 20 C9 2019-08-26 20:11:03.842 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:03.842 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 32 2019-08-26 20:11:04.140 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:04.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:04.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:04.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 32 2019-08-26 20:11:04.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:04.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:04.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:04.142 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 20 23 B1 2019-08-26 20:11:04.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=32, payload=20 23 2019-08-26 20:11:04.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=32, payload=20 23 2019-08-26 20:11:04.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 32 2019-08-26 20:11:04.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:04.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 32 2019-08-26 20:11:04.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43949: (Callback 32) 2019-08-26 20:11:04.144 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:04.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43949: callback 32 2019-08-26 20:11:04.144 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=32, payload=20 23 2019-08-26 20:11:04.144 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 91: Got NodeNeighborUpdate request. 2019-08-26 20:11:04.144 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 91: NodeNeighborUpdate FAILED 2019-08-26 20:11:04.144 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:04.144 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43949: Transaction CANCELLED 2019-08-26 20:11:04.144 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:04.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:11:04.144 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43949: Transaction RESET with 2 retries remaining. 2019-08-26 20:11:04.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Adding to device queue 2019-08-26 20:11:04.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Added 43949 to queue - size 1 2019-08-26 20:11:04.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:04.144 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 5B 21 C8 2019-08-26 20:11:04.145 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 5B 21 C8 2019-08-26 20:11:04.145 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:04.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 33 2019-08-26 20:11:04.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: TID 43949: Transaction not completed 2019-08-26 20:11:04.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:04.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:04.145 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:04.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:04.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:04.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 33 2019-08-26 20:11:04.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:04.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:04.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:04.146 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 21 23 B0 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=33, payload=21 23 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=33, payload=21 23 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 33 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 33 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43949: (Callback 33) 2019-08-26 20:11:04.147 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:04.147 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43949: callback 33 2019-08-26 20:11:04.148 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=33, payload=21 23 2019-08-26 20:11:04.148 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 91: Got NodeNeighborUpdate request. 2019-08-26 20:11:04.148 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 91: NodeNeighborUpdate FAILED 2019-08-26 20:11:04.148 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:04.148 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43949: Transaction CANCELLED 2019-08-26 20:11:04.148 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:04.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:11:04.148 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43949: Transaction RESET with 1 retries remaining. 2019-08-26 20:11:04.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Adding to device queue 2019-08-26 20:11:04.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Added 43949 to queue - size 1 2019-08-26 20:11:04.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:04.148 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 5B 22 CB 2019-08-26 20:11:04.148 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 5B 22 CB 2019-08-26 20:11:04.149 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:04.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 34 2019-08-26 20:11:04.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: TID 43949: Transaction not completed 2019-08-26 20:11:04.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:04.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:04.149 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:04.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:04.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:04.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 34 2019-08-26 20:11:04.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:04.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:04.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:04.150 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 22 23 B3 2019-08-26 20:11:04.150 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=34, payload=22 23 2019-08-26 20:11:04.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=34, payload=22 23 2019-08-26 20:11:04.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 34 2019-08-26 20:11:04.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:04.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43949: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 34 2019-08-26 20:11:04.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43949: (Callback 34) 2019-08-26 20:11:04.151 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:04.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43949: callback 34 2019-08-26 20:11:04.151 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=34, payload=22 23 2019-08-26 20:11:04.151 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 91: Got NodeNeighborUpdate request. 2019-08-26 20:11:04.151 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 91: NodeNeighborUpdate FAILED 2019-08-26 20:11:04.151 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:04.151 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43949: Transaction CANCELLED 2019-08-26 20:11:04.152 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:04.152 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: Retry count exceeded. Discarding message: TID 43949: [CANCELLED] priority=Controller, requiresResponse=true, callback: 34 2019-08-26 20:11:04.152 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:04.152 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Setting OFFLINE 2019-08-26 20:11:04.152 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 91: Node Status event - Node is DEAD 2019-08-26 20:11:04.152 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 91: Node is DEAD. 2019-08-26 20:11:04.152 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:04.152 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Setting OFFLINE 2019-08-26 20:11:04.152 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 91: Node Status event - Node is DEAD 2019-08-26 20:11:04.152 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: TID 43949: Transaction completed 2019-08-26 20:11:04.153 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 91: notifyTransactionResponse TID:43949 CANCELLED 2019-08-26 20:11:04.155 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 91: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:04.155 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43949: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:04.156 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 91: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@481c38d6 2019-08-26 20:11:04.155 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:04.156 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:06.314 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 35: TID 43947: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:11:06.314 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43947: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:11:06.314 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43947: Transaction CANCELLED 2019-08-26 20:11:06.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:43947 CANCELLED 2019-08-26 20:11:06.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:06.315 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43947: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:06.315 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 35: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1946a688 2019-08-26 20:11:06.749 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 22 22 B2 2019-08-26 20:11:06.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=34, payload=22 22 2019-08-26 20:11:06.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=34, payload=22 22 2019-08-26 20:11:06.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:06.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:11:06.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:11:06.750 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=34, payload=22 22 2019-08-26 20:11:06.750 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction 2019-08-26 20:11:06.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:06.750 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:19.505 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@2f53f179 2019-08-26 20:11:19.505 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: Adding to device queue 2019-08-26 20:11:19.505 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: Added 43950 to queue - size 1 2019-08-26 20:11:19.505 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:19.506 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 35 23 A4 2019-08-26 20:11:19.506 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 35 23 A4 2019-08-26 20:11:19.506 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:19.506 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43950: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 35 2019-08-26 20:11:19.507 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:19.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:19.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:19.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43950: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 35 2019-08-26 20:11:19.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:19.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:19.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:20.043 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 35 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 5F 2019-08-26 20:11:20.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=53, callback=64, payload=40 35 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:11:20.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=53, callback=64, payload=40 35 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:11:20.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43950: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 35 2019-08-26 20:11:20.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:20.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43950: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 35 2019-08-26 20:11:20.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43950: (Callback 35) 2019-08-26 20:11:20.045 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=53, callback=64, payload=40 35 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:11:20.045 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:11:20.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:20.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:21.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 55: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@889ea91 2019-08-26 20:11:21.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 55: Adding to device queue 2019-08-26 20:11:21.237 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 55: Added 43951 to queue - size 1 2019-08-26 20:11:21.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:21.238 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 37 24 A1 2019-08-26 20:11:21.238 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 37 24 A1 2019-08-26 20:11:21.238 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43951: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 36 2019-08-26 20:11:21.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@5c15807b 2019-08-26 20:11:21.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Adding to device queue 2019-08-26 20:11:21.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Added 43952 to queue - size 1 2019-08-26 20:11:21.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:21.923 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 12 0C 60 0D 02 01 32 02 21 32 00 00 00 00 BA 2019-08-26 20:11:21.923 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0C 60 0D 02 01 32 02 21 32 00 00 00 00 2019-08-26 20:11:21.924 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0C 60 0D 02 01 32 02 21 32 00 00 00 00 2019-08-26 20:11:21.924 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43950: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 35 2019-08-26 20:11:21.924 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (DEAD:UPDATE_NEIGHBORS) 2019-08-26 20:11:21.924 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:21.924 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Setting ONLINE 2019-08-26 20:11:21.924 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 18: Node Status event - Node is ALIVE 2019-08-26 20:11:21.924 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: Node is ALIVE. Init stage is UPDATE_NEIGHBORS. 2019-08-26 20:11:21.925 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:21.925 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Setting ONLINE 2019-08-26 20:11:21.925 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 18: Node Status event - Node is ALIVE 2019-08-26 20:11:21.925 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-08-26 20:11:21.925 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 2 2019-08-26 20:11:21.925 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER 2019-08-26 20:11:21.925 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT 2019-08-26 20:11:21.925 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 2019-08-26 20:11:21.925 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent 2019-08-26 20:11:21.925 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_METER, value=0E+1 2019-08-26 20:11:21.925 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:6dad8bea:node18:meter_watts2 to 0 [DecimalType] 2019-08-26 20:11:21.925 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1. 2019-08-26 20:11:21.926 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@61fa991. 2019-08-26 20:11:21.926 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-08-26 20:11:21.926 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-08-26 20:11:21.926 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:21.926 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:24.506 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 53: TID 43950: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:11:24.507 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43950: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:11:24.507 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43950: Transaction CANCELLED 2019-08-26 20:11:24.507 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 53: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:24.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: notifyTransactionResponse TID:43950 CANCELLED 2019-08-26 20:11:24.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:24.507 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 5D 25 CA 2019-08-26 20:11:24.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43950: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:24.507 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 5D 25 CA 2019-08-26 20:11:24.508 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 53: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@17b61824 2019-08-26 20:11:24.508 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:24.508 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 37 2019-08-26 20:11:24.508 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:24.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:24.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:24.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 37 2019-08-26 20:11:24.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:24.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:24.509 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:24.509 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 25 23 B4 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=37, payload=25 23 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=37, payload=25 23 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 37 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 37 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43952: (Callback 37) 2019-08-26 20:11:24.510 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:24.510 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43952: callback 37 2019-08-26 20:11:24.510 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=37, payload=25 23 2019-08-26 20:11:24.511 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 93: Got NodeNeighborUpdate request. 2019-08-26 20:11:24.511 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 93: NodeNeighborUpdate FAILED 2019-08-26 20:11:24.511 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:24.511 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43952: Transaction CANCELLED 2019-08-26 20:11:24.511 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:24.511 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:11:24.511 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43952: Transaction RESET with 2 retries remaining. 2019-08-26 20:11:24.511 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Adding to device queue 2019-08-26 20:11:24.511 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Added 43952 to queue - size 1 2019-08-26 20:11:24.511 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:24.511 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 5D 26 C9 2019-08-26 20:11:24.511 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 5D 26 C9 2019-08-26 20:11:24.511 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:24.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 38 2019-08-26 20:11:24.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: TID 43952: Transaction not completed 2019-08-26 20:11:24.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:24.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:24.512 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:24.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:24.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:24.513 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 38 2019-08-26 20:11:24.513 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:24.513 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:24.513 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:24.513 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 26 23 B7 2019-08-26 20:11:24.513 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=38, payload=26 23 2019-08-26 20:11:24.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=38, payload=26 23 2019-08-26 20:11:24.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 38 2019-08-26 20:11:24.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:24.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 38 2019-08-26 20:11:24.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43952: (Callback 38) 2019-08-26 20:11:24.514 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:24.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43952: callback 38 2019-08-26 20:11:24.514 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=38, payload=26 23 2019-08-26 20:11:24.514 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 93: Got NodeNeighborUpdate request. 2019-08-26 20:11:24.514 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 93: NodeNeighborUpdate FAILED 2019-08-26 20:11:24.514 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:24.514 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43952: Transaction CANCELLED 2019-08-26 20:11:24.514 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:11:24.515 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43952: Transaction RESET with 1 retries remaining. 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Adding to device queue 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Added 43952 to queue - size 1 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:24.515 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 5D 27 C8 2019-08-26 20:11:24.515 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 5D 27 C8 2019-08-26 20:11:24.515 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 39 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: TID 43952: Transaction not completed 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:24.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:24.516 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:24.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:24.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:24.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 39 2019-08-26 20:11:24.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:24.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:24.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:24.517 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 27 23 B6 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=39, payload=27 23 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=39, payload=27 23 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 39 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43952: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 39 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43952: (Callback 39) 2019-08-26 20:11:24.517 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:11:24.517 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43952: callback 39 2019-08-26 20:11:24.518 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=39, payload=27 23 2019-08-26 20:11:24.518 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 93: Got NodeNeighborUpdate request. 2019-08-26 20:11:24.518 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 93: NodeNeighborUpdate FAILED 2019-08-26 20:11:24.518 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:11:24.518 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43952: Transaction CANCELLED 2019-08-26 20:11:24.518 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:24.518 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: Retry count exceeded. Discarding message: TID 43952: [CANCELLED] priority=Controller, requiresResponse=true, callback: 39 2019-08-26 20:11:24.518 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:24.518 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Setting OFFLINE 2019-08-26 20:11:24.518 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 93: Node Status event - Node is DEAD 2019-08-26 20:11:24.518 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 93: Node is DEAD. 2019-08-26 20:11:24.519 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:24.519 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Setting OFFLINE 2019-08-26 20:11:24.519 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 93: Node Status event - Node is DEAD 2019-08-26 20:11:24.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: TID 43952: Transaction completed 2019-08-26 20:11:24.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 93: notifyTransactionResponse TID:43952 CANCELLED 2019-08-26 20:11:24.519 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 93: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:11:24.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:24.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43952: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:24.520 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:24.520 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 93: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@65689a9 2019-08-26 20:11:26.238 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 55: TID 43951: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:11:26.238 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43951: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:11:26.238 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43951: Transaction CANCELLED 2019-08-26 20:11:26.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 55: notifyTransactionResponse TID:43951 CANCELLED 2019-08-26 20:11:26.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:26.239 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43951: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:11:26.239 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 55: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1c4319d3 2019-08-26 20:11:28.632 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 88 06 31 05 01 22 00 B7 D9 2019-08-26 20:11:28.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 06 31 05 01 22 00 B7 2019-08-26 20:11:28.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 06 31 05 01 22 00 B7 2019-08-26 20:11:28.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:28.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (DEAD:UPDATE_NEIGHBORS) 2019-08-26 20:11:28.632 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:28.633 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Setting ONLINE 2019-08-26 20:11:28.633 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 136: Node Status event - Node is ALIVE 2019-08-26 20:11:28.633 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Node is ALIVE. Init stage is UPDATE_NEIGHBORS. 2019-08-26 20:11:28.633 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:11:28.633 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Setting ONLINE 2019-08-26 20:11:28.633 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 136: Node Status event - Node is ALIVE 2019-08-26 20:11:28.633 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:11:28.633 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: SECURITY not supported 2019-08-26 20:11:28.634 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 136: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:11:28.634 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Type = Temperature(1), Scale = 0 2019-08-26 20:11:28.634 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Value = 18.3 2019-08-26 20:11:28.634 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:11:28.634 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=18.3 2019-08-26 20:11:28.634 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Updating channel state zwave:device:6dad8bea:node136:sensor_temperature to 18.3 °C [QuantityType] 2019-08-26 20:11:28.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:28.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@19c252eb. 2019-08-26 20:11:28.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:28.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:28.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:28.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:28.875 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 88 05 31 05 05 01 3B 76 2019-08-26 20:11:28.875 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 05 31 05 05 01 3B 2019-08-26 20:11:28.876 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 05 31 05 05 01 3B 2019-08-26 20:11:28.876 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:28.876 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:28.876 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:11:28.876 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: SECURITY not supported 2019-08-26 20:11:28.876 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 136: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:11:28.876 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Type = RelativeHumidity(5), Scale = 0 2019-08-26 20:11:28.876 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Value = 59 2019-08-26 20:11:28.876 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:11:28.876 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=59 2019-08-26 20:11:28.877 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 136: Sensor conversion not performed for RELATIVE_HUMIDITY. 2019-08-26 20:11:28.877 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Updating channel state zwave:device:6dad8bea:node136:sensor_relhumidity to 59 [DecimalType] 2019-08-26 20:11:28.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:28.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@46a09a1d. 2019-08-26 20:11:28.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:28.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:28.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:28.877 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:29.446 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 88 05 31 05 05 01 3B 76 2019-08-26 20:11:29.446 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 05 31 05 05 01 3B 2019-08-26 20:11:29.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 05 31 05 05 01 3B 2019-08-26 20:11:29.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:29.447 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:29.447 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:11:29.447 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: SECURITY not supported 2019-08-26 20:11:29.447 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 136: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:11:29.447 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Type = RelativeHumidity(5), Scale = 0 2019-08-26 20:11:29.447 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Value = 59 2019-08-26 20:11:29.447 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:11:29.447 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=59 2019-08-26 20:11:29.448 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 136: Sensor conversion not performed for RELATIVE_HUMIDITY. 2019-08-26 20:11:29.448 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Updating channel state zwave:device:6dad8bea:node136:sensor_relhumidity to 59 [DecimalType] 2019-08-26 20:11:29.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:29.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1ba7c491. 2019-08-26 20:11:29.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:29.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:29.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:29.448 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:30.297 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 88 03 80 03 64 9E 2019-08-26 20:11:30.298 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 03 80 03 64 2019-08-26 20:11:30.298 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 03 80 03 64 2019-08-26 20:11:30.298 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:30.298 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:30.298 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Incoming command class COMMAND_CLASS_BATTERY, endpoint 0 2019-08-26 20:11:30.298 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: SECURITY not supported 2019-08-26 20:11:30.298 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 136: Received COMMAND_CLASS_BATTERY V1 BATTERY_REPORT 2019-08-26 20:11:30.298 [DEBUG] [nal.protocol.commandclass.ZWaveBatteryCommandClass] - NODE 136: Battery report value = 100 2019-08-26 20:11:30.298 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2019-08-26 20:11:30.299 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BATTERY, value=100 2019-08-26 20:11:30.299 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Updating channel state zwave:device:6dad8bea:node136:battery-level to 100 [DecimalType] 2019-08-26 20:11:30.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:30.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@600fb555. 2019-08-26 20:11:30.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:30.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:30.420 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 88 06 31 05 03 0A 00 0C 48 2019-08-26 20:11:30.420 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 06 31 05 03 0A 00 0C 2019-08-26 20:11:30.421 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 06 31 05 03 0A 00 0C 2019-08-26 20:11:30.421 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:30.421 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:30.421 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:11:30.421 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: SECURITY not supported 2019-08-26 20:11:30.421 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 136: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:11:30.421 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Type = Luminance(3), Scale = 1 2019-08-26 20:11:30.421 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Value = 12 2019-08-26 20:11:30.421 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:11:30.421 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=12 2019-08-26 20:11:30.422 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Updating channel state zwave:device:6dad8bea:node136:sensor_luminance to 12 % [QuantityType] 2019-08-26 20:11:30.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:30.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6bd1f6b5. 2019-08-26 20:11:30.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:30.422 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:30.604 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 88 05 31 05 1B 01 00 53 2019-08-26 20:11:30.604 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 05 31 05 1B 01 00 2019-08-26 20:11:30.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 05 31 05 1B 01 00 2019-08-26 20:11:30.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:30.605 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:30.605 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0 2019-08-26 20:11:30.605 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 136: SECURITY not supported 2019-08-26 20:11:30.605 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 136: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT 2019-08-26 20:11:30.605 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Type = Ultraviolet(27), Scale = 0 2019-08-26 20:11:30.605 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 136: Sensor Value = 0E+1 2019-08-26 20:11:30.605 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent 2019-08-26 20:11:30.605 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1 2019-08-26 20:11:30.605 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 136: Sensor conversion not performed for ULTRAVIOLET. 2019-08-26 20:11:30.605 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 136: Updating channel state zwave:device:6dad8bea:node136:sensor_ultraviolet to 0 [DecimalType] 2019-08-26 20:11:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@29e3f9a0. 2019-08-26 20:11:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:30.606 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:30.697 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 88 02 84 07 FA 2019-08-26 20:11:30.697 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 02 84 07 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=136, callback=0, payload=00 88 02 84 07 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Commands processed 1. 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 136: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@c453341. 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:30.698 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:43.251 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 12 0C 60 0D 01 01 32 02 21 32 00 00 00 00 B9 2019-08-26 20:11:43.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0C 60 0D 01 01 32 02 21 32 00 00 00 00 2019-08-26 20:11:43.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0C 60 0D 01 01 32 02 21 32 00 00 00 00 2019-08-26 20:11:43.251 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:43.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:11:43.252 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-08-26 20:11:43.252 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1 2019-08-26 20:11:43.252 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER 2019-08-26 20:11:43.252 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT 2019-08-26 20:11:43.252 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=0E+1 2019-08-26 20:11:43.252 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent 2019-08-26 20:11:43.252 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1 2019-08-26 20:11:43.252 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:6dad8bea:node18:meter_watts1 to 0 [DecimalType] 2019-08-26 20:11:43.252 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1. 2019-08-26 20:11:43.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@117d8a81. 2019-08-26 20:11:43.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:43.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:11:43.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:43.253 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:44.664 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 27 22 B7 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=39, payload=27 22 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=39, payload=27 22 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:11:44.665 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=39, payload=27 22 2019-08-26 20:11:44.665 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:44.665 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:56.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@2a5f0884 2019-08-26 20:11:56.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Adding to device queue 2019-08-26 20:11:56.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: Added 43953 to queue - size 1 2019-08-26 20:11:56.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:11:56.104 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 87 28 1D 2019-08-26 20:11:56.104 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 87 28 1D 2019-08-26 20:11:56.104 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:11:56.104 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43953: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 40 2019-08-26 20:11:56.105 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:11:56.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:56.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:11:56.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43953: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 40 2019-08-26 20:11:56.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:11:56.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:56.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:56.640 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B DA 2019-08-26 20:11:56.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=135, callback=64, payload=40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:11:56.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=135, callback=64, payload=40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:11:56.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43953: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 40 2019-08-26 20:11:56.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:11:56.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43953: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 40 2019-08-26 20:11:56.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43953: (Callback 40) 2019-08-26 20:11:56.642 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=135, callback=64, payload=40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:11:56.642 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:11:56.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:11:56.642 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:56.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@22f19c37 2019-08-26 20:11:56.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue 2019-08-26 20:11:56.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Added 43954 to queue - size 1 2019-08-26 20:11:56.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:11:59.469 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@40c58a3d 2019-08-26 20:11:59.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Adding to device queue 2019-08-26 20:11:59.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Added 43955 to queue - size 2 2019-08-26 20:11:59.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:00.623 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 7A 0E 60 0D 01 01 32 02 21 44 00 00 00 00 00 00 A3 2019-08-26 20:12:00.623 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=122, callback=0, payload=00 7A 0E 60 0D 01 01 32 02 21 44 00 00 00 00 00 00 2019-08-26 20:12:00.624 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=122, callback=0, payload=00 7A 0E 60 0D 01 01 32 02 21 44 00 00 00 00 00 00 2019-08-26 20:12:00.624 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43953: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 40 2019-08-26 20:12:00.624 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 122: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:12:00.624 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 122: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-08-26 20:12:00.624 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 122: Incoming command class COMMAND_CLASS_METER, endpoint 1 2019-08-26 20:12:00.624 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 122: SECURITY NOT required on COMMAND_CLASS_METER 2019-08-26 20:12:00.624 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 122: Received COMMAND_CLASS_METER V3 METER_REPORT 2019-08-26 20:12:00.624 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 122: Meter: Type=Electric(1), Scale=kWh(0), Value=0E+1 2019-08-26 20:12:00.625 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 122: Got an event from Z-Wave network: ZWaveMeterValueEvent 2019-08-26 20:12:00.625 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 122: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0E+1 2019-08-26 20:12:00.625 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 122: Updating channel state zwave:device:6dad8bea:node122:meter_kwh1 to 0 [DecimalType] 2019-08-26 20:12:00.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 122: Commands processed 1. 2019-08-26 20:12:00.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 122: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@75fda245. 2019-08-26 20:12:00.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-08-26 20:12:00.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2019-08-26 20:12:00.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:00.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.105 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 135: TID 43953: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:12:01.105 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43953: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:12:01.105 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43953: Transaction CANCELLED 2019-08-26 20:12:01.105 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 135: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 135: notifyTransactionResponse TID:43953 CANCELLED 2019-08-26 20:12:01.105 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.106 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 03 29 98 2019-08-26 20:12:01.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43953: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:01.106 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 03 29 98 2019-08-26 20:12:01.106 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:01.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 41 2019-08-26 20:12:01.106 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 135: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@51d86b8f 2019-08-26 20:12:01.107 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:01.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 41 2019-08-26 20:12:01.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:01.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.107 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.108 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 29 23 B8 2019-08-26 20:12:01.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=41, payload=29 23 2019-08-26 20:12:01.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=41, payload=29 23 2019-08-26 20:12:01.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 41 2019-08-26 20:12:01.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:01.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 41 2019-08-26 20:12:01.108 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43954: (Callback 41) 2019-08-26 20:12:01.109 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:01.109 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43954: callback 41 2019-08-26 20:12:01.109 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=41, payload=29 23 2019-08-26 20:12:01.109 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 3: Got NodeNeighborUpdate request. 2019-08-26 20:12:01.109 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 3: NodeNeighborUpdate FAILED 2019-08-26 20:12:01.109 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:01.109 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43954: Transaction CANCELLED 2019-08-26 20:12:01.109 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.109 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:12:01.109 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43954: Transaction RESET with 2 retries remaining. 2019-08-26 20:12:01.109 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue 2019-08-26 20:12:01.109 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Added 43954 to queue - size 2 2019-08-26 20:12:01.109 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.109 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 03 2A 9B 2019-08-26 20:12:01.109 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 03 2A 9B 2019-08-26 20:12:01.110 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:01.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 42 2019-08-26 20:12:01.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: TID 43954: Transaction not completed 2019-08-26 20:12:01.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.110 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:01.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 42 2019-08-26 20:12:01.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:01.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.111 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 2A 23 BB 2019-08-26 20:12:01.111 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=42, payload=2A 23 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=42, payload=2A 23 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 42 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 42 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43954: (Callback 42) 2019-08-26 20:12:01.112 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43954: callback 42 2019-08-26 20:12:01.112 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=42, payload=2A 23 2019-08-26 20:12:01.112 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 3: Got NodeNeighborUpdate request. 2019-08-26 20:12:01.112 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 3: NodeNeighborUpdate FAILED 2019-08-26 20:12:01.112 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:01.112 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43954: Transaction CANCELLED 2019-08-26 20:12:01.112 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:12:01.113 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43954: Transaction RESET with 1 retries remaining. 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Added 43954 to queue - size 2 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.113 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 03 2B 9A 2019-08-26 20:12:01.113 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 03 2B 9A 2019-08-26 20:12:01.113 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 43 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: TID 43954: Transaction not completed 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.114 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 43 2019-08-26 20:12:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.115 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 2B 23 BA 2019-08-26 20:12:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=43, payload=2B 23 2019-08-26 20:12:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=43, payload=2B 23 2019-08-26 20:12:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 43 2019-08-26 20:12:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43954: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 43 2019-08-26 20:12:01.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43954: (Callback 43) 2019-08-26 20:12:01.116 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:01.116 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43954: callback 43 2019-08-26 20:12:01.116 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=43, payload=2B 23 2019-08-26 20:12:01.116 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 3: Got NodeNeighborUpdate request. 2019-08-26 20:12:01.116 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 3: NodeNeighborUpdate FAILED 2019-08-26 20:12:01.116 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:01.116 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43954: Transaction CANCELLED 2019-08-26 20:12:01.116 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.116 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Retry count exceeded. Discarding message: TID 43954: [CANCELLED] priority=Controller, requiresResponse=true, callback: 43 2019-08-26 20:12:01.116 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:12:01.116 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Setting OFFLINE 2019-08-26 20:12:01.116 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 3: Node Status event - Node is DEAD 2019-08-26 20:12:01.117 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 3: Node is DEAD. 2019-08-26 20:12:01.117 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:12:01.117 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Setting OFFLINE 2019-08-26 20:12:01.117 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 3: Node Status event - Node is DEAD 2019-08-26 20:12:01.117 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: TID 43954: Transaction completed 2019-08-26 20:12:01.117 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:43954 CANCELLED 2019-08-26 20:12:01.117 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.117 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43954: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.118 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 8A 2C 14 2019-08-26 20:12:01.118 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 8A 2C 14 2019-08-26 20:12:01.118 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 3: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@15d08d5b 2019-08-26 20:12:01.118 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:01.119 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 44 2019-08-26 20:12:01.120 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 44 2019-08-26 20:12:01.120 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 2C 23 BD 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.120 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=44, payload=2C 23 2019-08-26 20:12:01.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=44, payload=2C 23 2019-08-26 20:12:01.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 44 2019-08-26 20:12:01.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:01.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 44 2019-08-26 20:12:01.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43955: (Callback 44) 2019-08-26 20:12:01.121 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:01.121 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43955: callback 44 2019-08-26 20:12:01.121 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=44, payload=2C 23 2019-08-26 20:12:01.121 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 138: Got NodeNeighborUpdate request. 2019-08-26 20:12:01.121 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 138: NodeNeighborUpdate FAILED 2019-08-26 20:12:01.121 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:01.121 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43955: Transaction CANCELLED 2019-08-26 20:12:01.121 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:12:01.122 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43955: Transaction RESET with 2 retries remaining. 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Adding to device queue 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Added 43955 to queue - size 1 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.122 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 8A 2D 15 2019-08-26 20:12:01.122 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 8A 2D 15 2019-08-26 20:12:01.122 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 45 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: TID 43955: Transaction not completed 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.122 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.123 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:01.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 45 2019-08-26 20:12:01.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:01.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.124 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 2D 23 BC 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=45, payload=2D 23 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=45, payload=2D 23 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 45 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 45 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43955: (Callback 45) 2019-08-26 20:12:01.124 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:01.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43955: callback 45 2019-08-26 20:12:01.125 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=45, payload=2D 23 2019-08-26 20:12:01.125 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 138: Got NodeNeighborUpdate request. 2019-08-26 20:12:01.125 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 138: NodeNeighborUpdate FAILED 2019-08-26 20:12:01.125 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:01.125 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43955: Transaction CANCELLED 2019-08-26 20:12:01.125 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:12:01.125 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43955: Transaction RESET with 1 retries remaining. 2019-08-26 20:12:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Adding to device queue 2019-08-26 20:12:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Added 43955 to queue - size 1 2019-08-26 20:12:01.125 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.125 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 8A 2E 16 2019-08-26 20:12:01.125 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 8A 2E 16 2019-08-26 20:12:01.126 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: TID 43955: Transaction not completed 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.126 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:01.126 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46 2019-08-26 20:12:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:01.127 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 2E 23 BF 2019-08-26 20:12:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=46, payload=2E 23 2019-08-26 20:12:01.127 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=46, payload=2E 23 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43955: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 46 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43955: (Callback 46) 2019-08-26 20:12:01.128 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43955: callback 46 2019-08-26 20:12:01.128 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=46, payload=2E 23 2019-08-26 20:12:01.128 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 138: Got NodeNeighborUpdate request. 2019-08-26 20:12:01.128 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 138: NodeNeighborUpdate FAILED 2019-08-26 20:12:01.128 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:01.128 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43955: Transaction CANCELLED 2019-08-26 20:12:01.128 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: Retry count exceeded. Discarding message: TID 43955: [CANCELLED] priority=Controller, requiresResponse=true, callback: 46 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: TID 43955: Transaction completed 2019-08-26 20:12:01.128 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 138: notifyTransactionResponse TID:43955 CANCELLED 2019-08-26 20:12:01.129 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 138: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:01.129 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:01.129 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:01.129 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43955: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:01.129 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 138: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@727d1e7f 2019-08-26 20:12:02.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 47: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@5ec250e 2019-08-26 20:12:02.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 47: Adding to device queue 2019-08-26 20:12:02.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 47: Added 43956 to queue - size 1 2019-08-26 20:12:02.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:02.113 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 2F 2F B2 2019-08-26 20:12:02.113 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 2F 2F B2 2019-08-26 20:12:02.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43956: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 47 2019-08-26 20:12:02.332 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B DA 2019-08-26 20:12:02.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=135, callback=64, payload=40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:02.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=135, callback=64, payload=40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:02.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:02.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:12:02.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:12:02.333 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=135, callback=64, payload=40 87 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:02.333 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:12:02.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:02.333 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:02.949 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 75: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@152725a0 2019-08-26 20:12:02.950 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 75: Adding to device queue 2019-08-26 20:12:02.950 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 75: Added 43957 to queue - size 1 2019-08-26 20:12:02.950 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:07.113 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 47: TID 43956: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:12:07.113 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43956: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:12:07.113 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43956: Transaction CANCELLED 2019-08-26 20:12:07.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 47: notifyTransactionResponse TID:43956 CANCELLED 2019-08-26 20:12:07.113 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:07.114 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 4B 30 C9 2019-08-26 20:12:07.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43956: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:07.114 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 4B 30 C9 2019-08-26 20:12:07.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43957: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 48 2019-08-26 20:12:07.114 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 47: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@3e3aa32 2019-08-26 20:12:11.668 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 2E 22 BE 2019-08-26 20:12:11.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=46, payload=2E 22 2019-08-26 20:12:11.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=46, payload=2E 22 2019-08-26 20:12:11.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:11.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:12:11.669 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:12:11.669 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=46, payload=2E 22 2019-08-26 20:12:11.670 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction 2019-08-26 20:12:11.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:11.670 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:12.114 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 75: TID 43957: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:12:12.114 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43957: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:12:12.114 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43957: Transaction CANCELLED 2019-08-26 20:12:12.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 75: notifyTransactionResponse TID:43957 CANCELLED 2019-08-26 20:12:12.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:12.115 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43957: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:12.115 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 75: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@514e139a 2019-08-26 20:12:14.131 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 74 0E 60 0D 01 01 32 02 21 44 00 00 00 01 00 00 AC 2019-08-26 20:12:14.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=116, callback=0, payload=00 74 0E 60 0D 01 01 32 02 21 44 00 00 00 01 00 00 2019-08-26 20:12:14.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=116, callback=0, payload=00 74 0E 60 0D 01 01 32 02 21 44 00 00 00 01 00 00 2019-08-26 20:12:14.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:14.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 116: Application Command Request (DEAD:UPDATE_NEIGHBORS) 2019-08-26 20:12:14.132 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:12:14.132 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Setting ONLINE 2019-08-26 20:12:14.132 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 116: Node Status event - Node is ALIVE 2019-08-26 20:12:14.132 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 116: Node is ALIVE. Init stage is UPDATE_NEIGHBORS. 2019-08-26 20:12:14.132 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:12:14.132 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Setting ONLINE 2019-08-26 20:12:14.132 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 116: Node Status event - Node is ALIVE 2019-08-26 20:12:14.132 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 116: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-08-26 20:12:14.132 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 116: Incoming command class COMMAND_CLASS_METER, endpoint 1 2019-08-26 20:12:14.132 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 116: SECURITY NOT required on COMMAND_CLASS_METER 2019-08-26 20:12:14.132 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 116: Received COMMAND_CLASS_METER V3 METER_REPORT 2019-08-26 20:12:14.133 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 116: Meter: Type=Electric(1), Scale=kWh(0), Value=0.01 2019-08-26 20:12:14.133 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Got an event from Z-Wave network: ZWaveMeterValueEvent 2019-08-26 20:12:14.133 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.01 2019-08-26 20:12:14.133 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 116: Updating channel state zwave:device:6dad8bea:node116:meter_kwh1 to 0.01 [DecimalType] 2019-08-26 20:12:14.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 116: Commands processed 1. 2019-08-26 20:12:14.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 116: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@43e57ba3. 2019-08-26 20:12:14.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:12:14.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:12:14.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:14.133 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:16.588 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 19: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@277a2239 2019-08-26 20:12:16.588 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2019-08-26 20:12:16.588 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 19: Added 43958 to queue - size 1 2019-08-26 20:12:16.588 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:16.588 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 13 31 90 2019-08-26 20:12:16.588 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 13 31 90 2019-08-26 20:12:16.589 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:16.589 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43958: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 49 2019-08-26 20:12:16.589 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:16.589 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:16.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:16.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43958: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 49 2019-08-26 20:12:16.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:16.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:16.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:17.123 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 7E 2019-08-26 20:12:17.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=64, payload=40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:17.123 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=64, payload=40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:17.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43958: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 49 2019-08-26 20:12:17.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:17.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43958: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 49 2019-08-26 20:12:17.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43958: (Callback 49) 2019-08-26 20:12:17.124 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=64, payload=40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:17.124 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:12:17.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:17.124 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:19.190 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 7E 2019-08-26 20:12:19.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=64, payload=40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:19.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=64, payload=40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:19.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43958: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 49 2019-08-26 20:12:19.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:19.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43958: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 49 2019-08-26 20:12:19.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43958: (Callback 49) 2019-08-26 20:12:19.192 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=19, callback=64, payload=40 13 1A 04 11 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:19.192 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:12:19.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:19.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:21.589 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 19: TID 43958: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:12:21.589 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43958: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:12:21.589 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43958: Transaction CANCELLED 2019-08-26 20:12:21.589 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:21.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:43958 CANCELLED 2019-08-26 20:12:21.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:21.590 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43958: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:21.590 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 19: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@3c8c434d 2019-08-26 20:12:25.633 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 31 22 A1 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=49, payload=31 22 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=49, payload=31 22 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:12:25.634 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=49, payload=31 22 2019-08-26 20:12:25.634 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:25.634 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@6b493abe 2019-08-26 20:12:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Adding to device queue 2019-08-26 20:12:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Added 43959 to queue - size 1 2019-08-26 20:12:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:26.191 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 03 32 83 2019-08-26 20:12:26.191 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 03 32 83 2019-08-26 20:12:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43959: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 50 2019-08-26 20:12:31.054 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 7A 0E 60 0D 02 01 32 02 21 44 00 00 00 00 00 00 A0 2019-08-26 20:12:31.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=122, callback=0, payload=00 7A 0E 60 0D 02 01 32 02 21 44 00 00 00 00 00 00 2019-08-26 20:12:31.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=122, callback=0, payload=00 7A 0E 60 0D 02 01 32 02 21 44 00 00 00 00 00 00 2019-08-26 20:12:31.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:31.055 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 122: Application Command Request (ALIVE:UPDATE_NEIGHBORS) 2019-08-26 20:12:31.056 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 122: Decapsulating COMMAND_CLASS_MULTI_CHANNEL 2019-08-26 20:12:31.056 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 122: Incoming command class COMMAND_CLASS_METER, endpoint 2 2019-08-26 20:12:31.056 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 122: SECURITY NOT required on COMMAND_CLASS_METER 2019-08-26 20:12:31.056 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 122: Received COMMAND_CLASS_METER V3 METER_REPORT 2019-08-26 20:12:31.056 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 122: Meter: Type=Electric(1), Scale=kWh(0), Value=0E+1 2019-08-26 20:12:31.056 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 122: Got an event from Z-Wave network: ZWaveMeterValueEvent 2019-08-26 20:12:31.056 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 122: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_METER, value=0E+1 2019-08-26 20:12:31.056 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 122: Updating channel state zwave:device:6dad8bea:node122:meter_kwh2 to 0 [DecimalType] 2019-08-26 20:12:31.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 122: Commands processed 1. 2019-08-26 20:12:31.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 122: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@661f97a5. 2019-08-26 20:12:31.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:12:31.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2019-08-26 20:12:31.057 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:31.057 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:31.192 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 3: TID 43959: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:12:31.192 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43959: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:12:31.192 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43959: Transaction CANCELLED 2019-08-26 20:12:31.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: notifyTransactionResponse TID:43959 CANCELLED 2019-08-26 20:12:31.192 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:31.193 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43959: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:31.193 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 3: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@7316ed68 2019-08-26 20:12:35.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 141: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@6c65d5fe 2019-08-26 20:12:35.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 141: Adding to device queue 2019-08-26 20:12:35.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 141: Added 43960 to queue - size 1 2019-08-26 20:12:35.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:35.899 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 8D 33 0C 2019-08-26 20:12:35.899 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 8D 33 0C 2019-08-26 20:12:35.899 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:35.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43960: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 51 2019-08-26 20:12:35.900 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:35.900 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:35.900 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:35.900 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43960: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 51 2019-08-26 20:12:35.901 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:35.901 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:35.901 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:40.060 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B D0 2019-08-26 20:12:40.061 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=141, callback=64, payload=40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:40.061 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=141, callback=64, payload=40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:40.061 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43960: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 51 2019-08-26 20:12:40.061 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:40.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43960: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 51 2019-08-26 20:12:40.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43960: (Callback 51) 2019-08-26 20:12:40.062 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=141, callback=64, payload=40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:40.062 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:12:40.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:40.062 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:40.899 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 141: TID 43960: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:12:40.899 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43960: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:12:40.899 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43960: Transaction CANCELLED 2019-08-26 20:12:40.899 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 141: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:40.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 141: notifyTransactionResponse TID:43960 CANCELLED 2019-08-26 20:12:40.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:40.900 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43960: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:40.900 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 141: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@7469d31f 2019-08-26 20:12:47.234 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B D0 2019-08-26 20:12:47.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=141, callback=64, payload=40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:47.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=141, callback=64, payload=40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:47.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:47.235 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:12:47.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:12:47.236 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=141, callback=64, payload=40 8D 1A 04 21 01 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:47.236 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:12:47.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:47.236 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:50.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@6eca2058 2019-08-26 20:12:50.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Adding to device queue 2019-08-26 20:12:50.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Added 43961 to queue - size 1 2019-08-26 20:12:50.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:50.313 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 2E 34 A8 2019-08-26 20:12:50.314 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 2E 34 A8 2019-08-26 20:12:50.314 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:50.314 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 52 2019-08-26 20:12:53.070 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:53.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:53.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:53.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 52 2019-08-26 20:12:53.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:53.070 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:53.071 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:53.086 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 34 23 A5 2019-08-26 20:12:53.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=52, payload=34 23 2019-08-26 20:12:53.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=52, payload=34 23 2019-08-26 20:12:53.086 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 52 2019-08-26 20:12:53.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:53.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 52 2019-08-26 20:12:53.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43961: (Callback 52) 2019-08-26 20:12:53.087 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:53.087 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43961: callback 52 2019-08-26 20:12:53.087 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=52, payload=34 23 2019-08-26 20:12:53.087 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 46: Got NodeNeighborUpdate request. 2019-08-26 20:12:53.087 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 46: NodeNeighborUpdate FAILED 2019-08-26 20:12:53.087 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:53.087 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43961: Transaction CANCELLED 2019-08-26 20:12:53.087 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:12:53.088 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43961: Transaction RESET with 2 retries remaining. 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Adding to device queue 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Added 43961 to queue - size 1 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:53.088 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 2E 35 A9 2019-08-26 20:12:53.088 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 2E 35 A9 2019-08-26 20:12:53.088 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 53 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: TID 43961: Transaction not completed 2019-08-26 20:12:53.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:53.089 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:53.089 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:53.089 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:53.089 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:53.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 53 2019-08-26 20:12:53.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:53.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:53.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:53.090 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 35 23 A4 2019-08-26 20:12:53.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=53, payload=35 23 2019-08-26 20:12:53.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=53, payload=35 23 2019-08-26 20:12:53.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 53 2019-08-26 20:12:53.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:53.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 53 2019-08-26 20:12:53.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43961: (Callback 53) 2019-08-26 20:12:53.091 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:53.091 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43961: callback 53 2019-08-26 20:12:53.091 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=53, payload=35 23 2019-08-26 20:12:53.091 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 46: Got NodeNeighborUpdate request. 2019-08-26 20:12:53.091 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 46: NodeNeighborUpdate FAILED 2019-08-26 20:12:53.091 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:53.091 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43961: Transaction CANCELLED 2019-08-26 20:12:53.092 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:12:53.092 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43961: Transaction RESET with 1 retries remaining. 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Adding to device queue 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Added 43961 to queue - size 1 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:53.092 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 2E 36 AA 2019-08-26 20:12:53.092 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 2E 36 AA 2019-08-26 20:12:53.092 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 54 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: TID 43961: Transaction not completed 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:53.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:53.093 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:53.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:53.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:53.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 54 2019-08-26 20:12:53.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:53.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:53.093 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:53.094 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 36 23 A7 2019-08-26 20:12:53.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=54, payload=36 23 2019-08-26 20:12:53.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=54, payload=36 23 2019-08-26 20:12:53.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 54 2019-08-26 20:12:53.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:53.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43961: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 54 2019-08-26 20:12:53.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43961: (Callback 54) 2019-08-26 20:12:53.094 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:12:53.095 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43961: callback 54 2019-08-26 20:12:53.095 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=54, payload=36 23 2019-08-26 20:12:53.095 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 46: Got NodeNeighborUpdate request. 2019-08-26 20:12:53.095 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 46: NodeNeighborUpdate FAILED 2019-08-26 20:12:53.095 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:12:53.095 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43961: Transaction CANCELLED 2019-08-26 20:12:53.095 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:53.095 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: Retry count exceeded. Discarding message: TID 43961: [CANCELLED] priority=Controller, requiresResponse=true, callback: 54 2019-08-26 20:12:53.095 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:12:53.095 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Setting OFFLINE 2019-08-26 20:12:53.095 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 46: Node Status event - Node is DEAD 2019-08-26 20:12:53.096 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 46: Node is DEAD. 2019-08-26 20:12:53.096 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:12:53.096 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Setting OFFLINE 2019-08-26 20:12:53.096 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 46: Node Status event - Node is DEAD 2019-08-26 20:12:53.096 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: TID 43961: Transaction completed 2019-08-26 20:12:53.096 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 46: notifyTransactionResponse TID:43961 CANCELLED 2019-08-26 20:12:53.096 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 46: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:12:53.096 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:53.097 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:53.096 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43961: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:12:53.097 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 46: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@455839be 2019-08-26 20:12:55.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 79: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1618c3b 2019-08-26 20:12:55.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 79: Adding to device queue 2019-08-26 20:12:55.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 79: Added 43962 to queue - size 1 2019-08-26 20:12:55.546 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:55.546 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 4F 37 CA 2019-08-26 20:12:55.547 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 4F 37 CA 2019-08-26 20:12:55.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43962: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 55 2019-08-26 20:12:56.933 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 36 23 A7 2019-08-26 20:12:56.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=54, payload=36 23 2019-08-26 20:12:56.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=54, payload=36 23 2019-08-26 20:12:56.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:12:56.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:12:56.934 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:12:56.934 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=54, payload=36 23 2019-08-26 20:12:56.934 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction 2019-08-26 20:12:56.935 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:56.935 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:59.321 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 15: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@45708832 2019-08-26 20:12:59.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 15: Adding to device queue 2019-08-26 20:12:59.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 15: Added 43963 to queue - size 1 2019-08-26 20:12:59.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:12:59.322 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0F 38 85 2019-08-26 20:12:59.322 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0F 38 85 2019-08-26 20:12:59.322 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:12:59.323 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43963: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 56 2019-08-26 20:12:59.323 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:12:59.323 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:59.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:12:59.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43963: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 56 2019-08-26 20:12:59.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:12:59.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:59.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:12:59.860 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 0F 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 65 2019-08-26 20:12:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=15, callback=64, payload=40 0F 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:59.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=15, callback=64, payload=40 0F 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43963: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 56 2019-08-26 20:12:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:12:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43963: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 56 2019-08-26 20:12:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43963: (Callback 56) 2019-08-26 20:12:59.861 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=15, callback=64, payload=40 0F 1A 04 11 06 5E 20 86 72 26 5A 59 85 73 98 7A 56 70 31 32 8E 60 75 71 27 22 EF 2B 2019-08-26 20:12:59.861 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64). 2019-08-26 20:12:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:12:59.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:13:00.547 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 79: TID 43962: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:13:00.547 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43962: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:13:00.547 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43962: Transaction CANCELLED 2019-08-26 20:13:00.547 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 79: notifyTransactionResponse TID:43962 CANCELLED 2019-08-26 20:13:00.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:00.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43962: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:13:00.548 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 79: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@194fbae6 2019-08-26 20:13:04.323 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 15: TID 43963: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:13:04.323 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43963: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:13:04.323 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43963: Transaction CANCELLED 2019-08-26 20:13:04.323 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:13:04.323 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 15: notifyTransactionResponse TID:43963 CANCELLED 2019-08-26 20:13:04.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:04.324 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43963: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:13:04.324 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 15: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@e1872a6 2019-08-26 20:13:05.806 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@2ef8727e 2019-08-26 20:13:05.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2019-08-26 20:13:05.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Added 43964 to queue - size 1 2019-08-26 20:13:05.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:05.807 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0D 39 86 2019-08-26 20:13:05.807 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0D 39 86 2019-08-26 20:13:05.807 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:13:05.808 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57 2019-08-26 20:13:05.808 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:13:05.808 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:13:05.808 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:13:05.808 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57 2019-08-26 20:13:05.809 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:13:05.809 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:05.809 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:13:05.809 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 39 23 A8 2019-08-26 20:13:05.809 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=57, payload=39 23 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=57, payload=39 23 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 57 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43964: (Callback 57) 2019-08-26 20:13:05.810 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43964: callback 57 2019-08-26 20:13:05.810 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=57, payload=39 23 2019-08-26 20:13:05.810 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 13: Got NodeNeighborUpdate request. 2019-08-26 20:13:05.810 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 13: NodeNeighborUpdate FAILED 2019-08-26 20:13:05.810 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:13:05.810 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43964: Transaction CANCELLED 2019-08-26 20:13:05.810 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:13:05.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: CANCEL while sending message. Requeueing - 2 attempts left! 2019-08-26 20:13:05.810 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43964: Transaction RESET with 2 retries remaining. 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Added 43964 to queue - size 1 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:05.811 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0D 3A 85 2019-08-26 20:13:05.811 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0D 3A 85 2019-08-26 20:13:05.811 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 58 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: TID 43964: Transaction not completed 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:05.811 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:13:05.812 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:13:05.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:13:05.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:13:05.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 58 2019-08-26 20:13:05.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:13:05.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:05.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:13:05.813 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 3A 23 AB 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=58, payload=3A 23 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=58, payload=3A 23 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 58 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 58 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43964: (Callback 58) 2019-08-26 20:13:05.813 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:13:05.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43964: callback 58 2019-08-26 20:13:05.813 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=58, payload=3A 23 2019-08-26 20:13:05.814 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 13: Got NodeNeighborUpdate request. 2019-08-26 20:13:05.814 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 13: NodeNeighborUpdate FAILED 2019-08-26 20:13:05.814 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:13:05.814 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43964: Transaction CANCELLED 2019-08-26 20:13:05.814 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:13:05.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: CANCEL while sending message. Requeueing - 1 attempts left! 2019-08-26 20:13:05.814 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43964: Transaction RESET with 1 retries remaining. 2019-08-26 20:13:05.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Adding to device queue 2019-08-26 20:13:05.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Added 43964 to queue - size 1 2019-08-26 20:13:05.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:05.814 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0D 3B 84 2019-08-26 20:13:05.814 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0D 3B 84 2019-08-26 20:13:05.814 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-08-26 20:13:05.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 59 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: TID 43964: Transaction not completed 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:13:05.815 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 59 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK 2019-08-26 20:13:05.815 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:05.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2019-08-26 20:13:05.816 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 3B 23 AA 2019-08-26 20:13:05.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=59, payload=3B 23 2019-08-26 20:13:05.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=59, payload=3B 23 2019-08-26 20:13:05.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 59 2019-08-26 20:13:05.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2019-08-26 20:13:05.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43964: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 59 2019-08-26 20:13:05.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43964: (Callback 59) 2019-08-26 20:13:05.817 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match! 2019-08-26 20:13:05.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43964: callback 59 2019-08-26 20:13:05.817 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=59, payload=3B 23 2019-08-26 20:13:05.817 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 13: Got NodeNeighborUpdate request. 2019-08-26 20:13:05.817 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 13: NodeNeighborUpdate FAILED 2019-08-26 20:13:05.817 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveNetworkEvent 2019-08-26 20:13:05.817 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43964: Transaction CANCELLED 2019-08-26 20:13:05.817 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:13:05.817 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: Retry count exceeded. Discarding message: TID 43964: [CANCELLED] priority=Controller, requiresResponse=true, callback: 59 2019-08-26 20:13:05.817 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:13:05.817 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Setting OFFLINE 2019-08-26 20:13:05.818 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 13: Node Status event - Node is DEAD 2019-08-26 20:13:05.818 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 13: Node is DEAD. 2019-08-26 20:13:05.818 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2019-08-26 20:13:05.818 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Setting OFFLINE 2019-08-26 20:13:05.818 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 13: Node Status event - Node is DEAD 2019-08-26 20:13:05.818 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: TID 43964: Transaction completed 2019-08-26 20:13:05.818 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 13: notifyTransactionResponse TID:43964 CANCELLED 2019-08-26 20:13:05.820 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2019-08-26 20:13:05.820 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:05.820 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43964: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:13:05.820 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:05.820 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 13: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@66b4b359 2019-08-26 20:13:07.336 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 3B 23 AA 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=59, payload=3B 23 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=59, payload=3B 23 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null 2019-08-26 20:13:07.337 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=59, payload=3B 23 2019-08-26 20:13:07.337 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2019-08-26 20:13:07.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:13.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@5b87a1af 2019-08-26 20:13:13.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: Adding to device queue 2019-08-26 20:13:13.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: Added 43965 to queue - size 1 2019-08-26 20:13:13.734 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:13.735 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 35 3C BB 2019-08-26 20:13:13.735 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 35 3C BB 2019-08-26 20:13:13.735 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43965: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 60 2019-08-26 20:13:18.735 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 53: TID 43965: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-08-26 20:13:18.735 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43965: Transaction is current transaction, so clearing!!!!! 2019-08-26 20:13:18.735 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43965: Transaction CANCELLED 2019-08-26 20:13:18.735 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 53: notifyTransactionResponse TID:43965 CANCELLED 2019-08-26 20:13:18.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-08-26 20:13:18.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43965: Transaction event listener: DONE: CANCELLED -> 2019-08-26 20:13:18.736 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 53: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@5c6d3800