2019-08-26 20:02:21.491 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction
2019-08-26 20:02:21.491 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:21.491 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:23.861 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 43: TID 43871: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-08-26 20:02:23.861 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43871: Transaction is current transaction, so clearing!!!!!
2019-08-26 20:02:23.861 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43871: Transaction CANCELLED
2019-08-26 20:02:23.861 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:43871 CANCELLED
2019-08-26 20:02:23.862 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:23.862 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43871: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:02:23.862 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 43: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@68a5e78
2019-08-26 20:02:30.985 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 03 0E 60 0D 01 01 32 02 21 44 00 00 00 9C 00 00 46
2019-08-26 20:02:30.985 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0E 60 0D 01 01 32 02 21 44 00 00 00 9C 00 00
2019-08-26 20:02:30.986 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=3, callback=0, payload=00 03 0E 60 0D 01 01 32 02 21 44 00 00 00 9C 00 00
2019-08-26 20:02:30.986 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:02:30.986 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:02:30.986 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:02:30.986 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Setting ONLINE
2019-08-26 20:02:30.986 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 3: Node Status event - Node is ALIVE
2019-08-26 20:02:30.986 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 3: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:02:30.987 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:02:30.987 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Setting ONLINE
2019-08-26 20:02:30.987 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 3: Node Status event - Node is ALIVE
2019-08-26 20:02:30.987 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 3: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-08-26 20:02:30.987 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 3: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-08-26 20:02:30.987 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 3: SECURITY NOT required on COMMAND_CLASS_METER
2019-08-26 20:02:30.987 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 3: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-26 20:02:30.988 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 3: Meter: Type=Electric(1), Scale=kWh(0), Value=1.56
2019-08-26 20:02:30.988 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-26 20:02:30.988 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=1.56
2019-08-26 20:02:30.988 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:6dad8bea:node3:meter_kwh1 to 1.56 [DecimalType]
2019-08-26 20:02:30.988 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Commands processed 1.
2019-08-26 20:02:30.988 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 3: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@426b6806.
2019-08-26 20:02:30.988 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:02:30.988 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:02:30.988 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:30.988 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:32.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@123b1630
2019-08-26 20:02:32.548 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue
2019-08-26 20:02:32.549 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: Added 43872 to queue - size 1
2019-08-26 20:02:32.549 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:32.549 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 1C 93 3D
2019-08-26 20:02:32.549 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 1C 93 3D
2019-08-26 20:02:32.549 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:32.549 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43872: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 147
2019-08-26 20:02:32.550 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:32.550 [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:02:32.550 [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:02:32.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43872: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 147
2019-08-26 20:02:32.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:32.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:32.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:33.218 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 1C 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 76
2019-08-26 20:02:33.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=28, callback=64, payload=40 1C 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:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=28, callback=64, payload=40 1C 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:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43872: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 147
2019-08-26 20:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43872: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 147
2019-08-26 20:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43872: (Callback 147)
2019-08-26 20:02:33.219 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=28, callback=64, payload=40 1C 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:02:33.219 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).
2019-08-26 20:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:33.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:37.549 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 28: TID 43872: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-08-26 20:02:37.550 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43872: Transaction is current transaction, so clearing!!!!!
2019-08-26 20:02:37.550 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43872: Transaction CANCELLED
2019-08-26 20:02:37.550 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-08-26 20:02:37.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:43872 CANCELLED
2019-08-26 20:02:37.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:37.550 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43872: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:02:37.551 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@5893df82
2019-08-26 20:02:41.209 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@14fb2f
2019-08-26 20:02:41.209 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Adding to device queue
2019-08-26 20:02:41.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Added 43873 to queue - size 1
2019-08-26 20:02:41.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:41.210 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0E 94 28
2019-08-26 20:02:41.210 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0E 94 28
2019-08-26 20:02:41.210 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:41.210 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 148
2019-08-26 20:02:41.211 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:41.211 [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:02:41.211 [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:02:41.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 148
2019-08-26 20:02:41.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:41.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:41.211 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:41.212 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 94 23 05
2019-08-26 20:02:41.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=148, payload=94 23
2019-08-26 20:02:41.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=148, payload=94 23
2019-08-26 20:02:41.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 148
2019-08-26 20:02:41.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:41.212 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 148
2019-08-26 20:02:41.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43873: (Callback 148)
2019-08-26 20:02:41.213 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-08-26 20:02:41.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43873: callback 148
2019-08-26 20:02:41.213 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=148, payload=94 23
2019-08-26 20:02:41.213 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 14: Got NodeNeighborUpdate request.
2019-08-26 20:02:41.213 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 14: NodeNeighborUpdate FAILED
2019-08-26 20:02:41.213 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveNetworkEvent
2019-08-26 20:02:41.213 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43873: Transaction CANCELLED
2019-08-26 20:02:41.213 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-08-26 20:02:41.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: CANCEL while sending message. Requeueing - 2 attempts left!
2019-08-26 20:02:41.213 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43873: Transaction RESET with 2 retries remaining.
2019-08-26 20:02:41.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Adding to device queue
2019-08-26 20:02:41.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Added 43873 to queue - size 1
2019-08-26 20:02:41.213 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:41.213 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0E 95 29
2019-08-26 20:02:41.214 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0E 95 29
2019-08-26 20:02:41.214 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:41.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 149
2019-08-26 20:02:41.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: TID 43873: Transaction not completed
2019-08-26 20:02:41.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:41.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:41.214 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:41.215 [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:02:41.215 [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:02:41.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 149
2019-08-26 20:02:41.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:41.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:41.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:41.215 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 95 23 04
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=149, payload=95 23
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=149, payload=95 23
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 149
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 149
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43873: (Callback 149)
2019-08-26 20:02:41.216 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-08-26 20:02:41.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43873: callback 149
2019-08-26 20:02:41.216 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=149, payload=95 23
2019-08-26 20:02:41.217 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 14: Got NodeNeighborUpdate request.
2019-08-26 20:02:41.217 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 14: NodeNeighborUpdate FAILED
2019-08-26 20:02:41.217 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveNetworkEvent
2019-08-26 20:02:41.217 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43873: Transaction CANCELLED
2019-08-26 20:02:41.217 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-08-26 20:02:41.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: CANCEL while sending message. Requeueing - 1 attempts left!
2019-08-26 20:02:41.217 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43873: Transaction RESET with 1 retries remaining.
2019-08-26 20:02:41.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Adding to device queue
2019-08-26 20:02:41.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Added 43873 to queue - size 1
2019-08-26 20:02:41.217 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:41.217 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 0E 96 2A
2019-08-26 20:02:41.217 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 0E 96 2A
2019-08-26 20:02:41.217 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:41.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 150
2019-08-26 20:02:41.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: TID 43873: Transaction not completed
2019-08-26 20:02:41.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:41.218 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:41.218 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:41.218 [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:02:41.218 [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:02:41.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 150
2019-08-26 20:02:41.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:41.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:41.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:41.219 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 96 23 07
2019-08-26 20:02:41.219 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=150, payload=96 23
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=150, payload=96 23
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 150
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43873: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 150
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43873: (Callback 150)
2019-08-26 20:02:41.220 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43873: callback 150
2019-08-26 20:02:41.220 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=150, payload=96 23
2019-08-26 20:02:41.220 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 14: Got NodeNeighborUpdate request.
2019-08-26 20:02:41.220 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 14: NodeNeighborUpdate FAILED
2019-08-26 20:02:41.220 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveNetworkEvent
2019-08-26 20:02:41.220 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43873: Transaction CANCELLED
2019-08-26 20:02:41.220 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: Retry count exceeded. Discarding message: TID 43873: [CANCELLED] priority=Controller, requiresResponse=true, callback: 150
2019-08-26 20:02:41.220 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: TID 43873: Transaction completed
2019-08-26 20:02:41.221 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 14: notifyTransactionResponse TID:43873 CANCELLED
2019-08-26 20:02:41.221 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-08-26 20:02:41.221 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:41.221 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:41.221 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43873: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:02:41.221 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 14: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@12d4b588
2019-08-26 20:02:41.897 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@6d45dde
2019-08-26 20:02:41.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Adding to device queue
2019-08-26 20:02:41.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: Added 43874 to queue - size 1
2019-08-26 20:02:41.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:41.898 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 07 97 22
2019-08-26 20:02:41.898 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 07 97 22
2019-08-26 20:02:41.898 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43874: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 151
2019-08-26 20:02:46.899 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 7: TID 43874: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-08-26 20:02:46.899 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43874: Transaction is current transaction, so clearing!!!!!
2019-08-26 20:02:46.899 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43874: Transaction CANCELLED
2019-08-26 20:02:46.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 7: notifyTransactionResponse TID:43874 CANCELLED
2019-08-26 20:02:46.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:46.899 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43874: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:02:46.900 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 7: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@75397f36
2019-08-26 20:02:47.534 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@5d5b36b0
2019-08-26 20:02:47.534 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Adding to device queue
2019-08-26 20:02:47.534 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Added 43875 to queue - size 1
2019-08-26 20:02:47.534 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:47.534 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 01 98 2B
2019-08-26 20:02:47.534 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 01 98 2B
2019-08-26 20:02:47.535 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:47.535 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 152
2019-08-26 20:02:47.535 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:47.535 [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:02:47.535 [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:02:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 152
2019-08-26 20:02:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:47.536 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 98 23 09
2019-08-26 20:02:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=152, payload=98 23
2019-08-26 20:02:47.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=152, payload=98 23
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 152
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 152
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43875: (Callback 152)
2019-08-26 20:02:47.537 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43875: callback 152
2019-08-26 20:02:47.537 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=152, payload=98 23
2019-08-26 20:02:47.537 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 1: Got NodeNeighborUpdate request.
2019-08-26 20:02:47.537 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 1: NodeNeighborUpdate FAILED
2019-08-26 20:02:47.537 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43875: Transaction CANCELLED
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: CANCEL while sending message. Requeueing - 2 attempts left!
2019-08-26 20:02:47.537 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43875: Transaction RESET with 2 retries remaining.
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Adding to device queue
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Added 43875 to queue - size 1
2019-08-26 20:02:47.537 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:47.538 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 01 99 2A
2019-08-26 20:02:47.538 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 01 99 2A
2019-08-26 20:02:47.538 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:47.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 153
2019-08-26 20:02:47.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: TID 43875: Transaction not completed
2019-08-26 20:02:47.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:47.538 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:47.538 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:47.539 [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:02:47.539 [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:02:47.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 153
2019-08-26 20:02:47.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:47.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:47.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:47.539 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 99 23 08
2019-08-26 20:02:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=153, payload=99 23
2019-08-26 20:02:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=153, payload=99 23
2019-08-26 20:02:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 153
2019-08-26 20:02:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 153
2019-08-26 20:02:47.540 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43875: (Callback 153)
2019-08-26 20:02:47.540 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-08-26 20:02:47.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43875: callback 153
2019-08-26 20:02:47.541 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=153, payload=99 23
2019-08-26 20:02:47.541 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 1: Got NodeNeighborUpdate request.
2019-08-26 20:02:47.541 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 1: NodeNeighborUpdate FAILED
2019-08-26 20:02:47.541 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43875: Transaction CANCELLED
2019-08-26 20:02:47.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: CANCEL while sending message. Requeueing - 1 attempts left!
2019-08-26 20:02:47.541 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43875: Transaction RESET with 1 retries remaining.
2019-08-26 20:02:47.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Adding to device queue
2019-08-26 20:02:47.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Added 43875 to queue - size 1
2019-08-26 20:02:47.541 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:47.541 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 01 9A 29
2019-08-26 20:02:47.541 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 01 9A 29
2019-08-26 20:02:47.542 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:47.542 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 154
2019-08-26 20:02:47.542 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: TID 43875: Transaction not completed
2019-08-26 20:02:47.542 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:47.542 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:47.542 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:47.542 [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:02:47.542 [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:02:47.543 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 154
2019-08-26 20:02:47.543 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:47.543 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:47.543 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:47.543 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 9A 23 0B
2019-08-26 20:02:47.543 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=154, payload=9A 23
2019-08-26 20:02:47.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=154, payload=9A 23
2019-08-26 20:02:47.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 154
2019-08-26 20:02:47.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:47.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43875: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 154
2019-08-26 20:02:47.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43875: (Callback 154)
2019-08-26 20:02:47.544 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-08-26 20:02:47.544 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 43875: callback 154
2019-08-26 20:02:47.544 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=154, payload=9A 23
2019-08-26 20:02:47.544 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 1: Got NodeNeighborUpdate request.
2019-08-26 20:02:47.544 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NODE 1: NodeNeighborUpdate FAILED
2019-08-26 20:02:47.544 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43875: Transaction CANCELLED
2019-08-26 20:02:47.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: Retry count exceeded. Discarding message: TID 43875: [CANCELLED] priority=Controller, requiresResponse=true, callback: 154
2019-08-26 20:02:47.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: TID 43875: Transaction completed
2019-08-26 20:02:47.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 1: notifyTransactionResponse TID:43875 CANCELLED
2019-08-26 20:02:47.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:47.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:47.545 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43875: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:02:47.545 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 1: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1c5defef
2019-08-26 20:02:50.748 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 26 03 20 01 00 F6
2019-08-26 20:02:50.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=38, callback=0, payload=00 26 03 20 01 00
2019-08-26 20:02:50.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=38, callback=0, payload=00 26 03 20 01 00
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 38: Application Command Request (ALIVE:UPDATE_NEIGHBORS)
2019-08-26 20:02:50.749 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 38: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2019-08-26 20:02:50.749 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 38: SECURITY not supported
2019-08-26 20:02:50.749 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 38: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2019-08-26 20:02:50.749 [DEBUG] [ernal.protocol.commandclass.ZWaveBasicCommandClass] - NODE 38: Basic report, value = 0
2019-08-26 20:02:50.749 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 38: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-08-26 20:02:50.749 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 38: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 38: Commands processed 1.
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 38: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5765e63b.
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:50.749 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:51.213 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 26 0A 71 05 00 00 00 FF 07 00 00 00 4B
2019-08-26 20:02:51.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=38, callback=0, payload=00 26 0A 71 05 00 00 00 FF 07 00 00 00
2019-08-26 20:02:51.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=38, callback=0, payload=00 26 0A 71 05 00 00 00 FF 07 00 00 00
2019-08-26 20:02:51.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:02:51.214 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 38: Application Command Request (ALIVE:UPDATE_NEIGHBORS)
2019-08-26 20:02:51.214 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 38: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2019-08-26 20:02:51.214 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 38: SECURITY not supported
2019-08-26 20:02:51.214 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 38: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2019-08-26 20:02:51.214 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 38: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0
2019-08-26 20:02:51.215 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 38: Alarm Type = BURGLAR (0)
2019-08-26 20:02:51.215 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 38: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2019-08-26 20:02:51.215 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 38: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2019-08-26 20:02:51.215 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 38: Alarm converter processing NOTIFICATION
2019-08-26 20:02:51.215 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 38: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-26 20:02:51.215 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 38: Updating channel state zwave:device:6dad8bea:node38:alarm_motion to OFF [OnOffType]
2019-08-26 20:02:51.215 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 38: Alarm converter processing NOTIFICATION
2019-08-26 20:02:51.215 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 38: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-26 20:02:51.215 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 38: Updating channel state zwave:device:6dad8bea:node38:alarm_tamper to OFF [OnOffType]
2019-08-26 20:02:51.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 38: Commands processed 1.
2019-08-26 20:02:51.215 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 38: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4accd79b.
2019-08-26 20:02:51.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:02:51.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:02:51.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:51.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:56.720 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 9A 22 0A
2019-08-26 20:02:56.720 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=154, payload=9A 22
2019-08-26 20:02:56.720 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=154, payload=9A 22
2019-08-26 20:02:56.720 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:02:56.721 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2019-08-26 20:02:56.721 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null
2019-08-26 20:02:56.721 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=154, payload=9A 22
2019-08-26 20:02:56.721 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction
2019-08-26 20:02:56.721 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:56.721 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:58.913 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@72da7af3
2019-08-26 20:02:58.913 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Adding to device queue
2019-08-26 20:02:58.913 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: Added 43876 to queue - size 1
2019-08-26 20:02:58.913 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:02:58.913 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 28 9B 01
2019-08-26 20:02:58.913 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 28 9B 01
2019-08-26 20:02:58.914 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:02:58.914 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43876: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 155
2019-08-26 20:02:58.914 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:02:58.915 [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:02:58.915 [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:02:58.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43876: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 155
2019-08-26 20:02:58.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:02:58.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:58.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:02:59.586 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 28 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 75
2019-08-26 20:02:59.586 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=40, callback=64, payload=40 28 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:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=40, callback=64, payload=40 28 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:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43876: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 155
2019-08-26 20:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43876: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 155
2019-08-26 20:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43876: (Callback 155)
2019-08-26 20:02:59.587 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=40, callback=64, payload=40 28 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:02:59.587 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).
2019-08-26 20:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:02:59.587 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:03.914 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 40: TID 43876: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-08-26 20:03:03.914 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43876: Transaction is current transaction, so clearing!!!!!
2019-08-26 20:03:03.914 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43876: Transaction CANCELLED
2019-08-26 20:03:03.915 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 40: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-08-26 20:03:03.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 40: notifyTransactionResponse TID:43876 CANCELLED
2019-08-26 20:03:03.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:03.915 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43876: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:03:03.915 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 40: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@3cc9b051
2019-08-26 20:03:07.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 48: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@2dc59d78
2019-08-26 20:03:07.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-08-26 20:03:07.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 48: Added 43877 to queue - size 1
2019-08-26 20:03:07.163 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:07.164 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 30 9C 1E
2019-08-26 20:03:07.164 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 05 00 48 30 9C 1E
2019-08-26 20:03:07.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43877: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 156
2019-08-26 20:03:08.160 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 9B 23 0A
2019-08-26 20:03:08.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=155, payload=9B 23
2019-08-26 20:03:08.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=155, payload=9B 23
2019-08-26 20:03:08.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:08.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2019-08-26 20:03:08.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: null
2019-08-26 20:03:08.161 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=155, payload=9B 23
2019-08-26 20:03:08.161 [DEBUG] [erialmessage.RequestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction
2019-08-26 20:03:08.161 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:08.162 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:11.380 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 79 0E 60 0D 01 01 32 02 21 44 00 00 02 FE 00 00 5C
2019-08-26 20:03:11.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=121, callback=0, payload=00 79 0E 60 0D 01 01 32 02 21 44 00 00 02 FE 00 00
2019-08-26 20:03:11.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=121, callback=0, payload=00 79 0E 60 0D 01 01 32 02 21 44 00 00 02 FE 00 00
2019-08-26 20:03:11.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:11.381 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 121: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:03:11.381 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:11.381 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Setting ONLINE
2019-08-26 20:03:11.381 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 121: Node Status event - Node is ALIVE
2019-08-26 20:03:11.382 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 121: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:03:11.382 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:11.382 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Setting ONLINE
2019-08-26 20:03:11.382 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 121: Node Status event - Node is ALIVE
2019-08-26 20:03:11.382 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 121: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-08-26 20:03:11.382 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 121: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-08-26 20:03:11.382 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 121: SECURITY NOT required on COMMAND_CLASS_METER
2019-08-26 20:03:11.382 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 121: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-26 20:03:11.382 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 121: Meter: Type=Electric(1), Scale=kWh(0), Value=7.66
2019-08-26 20:03:11.382 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-26 20:03:11.382 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=7.66
2019-08-26 20:03:11.382 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 121: Updating channel state zwave:device:6dad8bea:node121:meter_kwh1 to 7.66 [DecimalType]
2019-08-26 20:03:11.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 121: Commands processed 1.
2019-08-26 20:03:11.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 121: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f692302.
2019-08-26 20:03:11.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:11.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:11.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:11.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:12.164 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 48: TID 43877: Timeout at state WAIT_REQUEST. 3 retries remaining.
2019-08-26 20:03:12.164 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 43877: Transaction is current transaction, so clearing!!!!!
2019-08-26 20:03:12.164 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 43877: Transaction CANCELLED
2019-08-26 20:03:12.164 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 48: notifyTransactionResponse TID:43877 CANCELLED
2019-08-26 20:03:12.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:12.165 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 43877: Transaction event listener: DONE: CANCELLED ->
2019-08-26 20:03:12.165 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 48: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@73461eb6
2019-08-26 20:03:26.188 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 40 06 31 05 04 22 00 00 A3
2019-08-26 20:03:26.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 06 31 05 04 22 00 00
2019-08-26 20:03:26.188 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=64, callback=0, payload=00 40 06 31 05 04 22 00 00
2019-08-26 20:03:26.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:26.189 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 64: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:03:26.189 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:26.189 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Setting ONLINE
2019-08-26 20:03:26.189 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 64: Node Status event - Node is ALIVE
2019-08-26 20:03:26.189 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 64: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:03:26.189 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:26.189 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Setting ONLINE
2019-08-26 20:03:26.189 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 64: Node Status event - Node is ALIVE
2019-08-26 20:03:26.190 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 64: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-08-26 20:03:26.190 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 64: SECURITY not supported
2019-08-26 20:03:26.190 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 64: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2019-08-26 20:03:26.190 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 64: Sensor Type = Power(4), Scale = 0
2019-08-26 20:03:26.190 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 64: Sensor Value = 0E+1
2019-08-26 20:03:26.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-08-26 20:03:26.190 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2019-08-26 20:03:26.191 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 64: Sensor conversion not performed for POWER.
2019-08-26 20:03:26.191 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 64: Updating channel state zwave:device:6dad8bea:node64:sensor_power to 0 [DecimalType]
2019-08-26 20:03:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 64: Commands processed 1.
2019-08-26 20:03:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 64: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@16db51b1.
2019-08-26 20:03:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:26.191 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:26.573 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 42 06 31 05 04 22 00 00 A1
2019-08-26 20:03:26.573 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=66, callback=0, payload=00 42 06 31 05 04 22 00 00
2019-08-26 20:03:26.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=66, callback=0, payload=00 42 06 31 05 04 22 00 00
2019-08-26 20:03:26.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:26.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 66: Application Command Request (ALIVE:UPDATE_NEIGHBORS)
2019-08-26 20:03:26.574 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 66: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-08-26 20:03:26.574 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 66: SECURITY not supported
2019-08-26 20:03:26.574 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 66: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2019-08-26 20:03:26.574 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 66: Sensor Type = Power(4), Scale = 0
2019-08-26 20:03:26.574 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 66: Sensor Value = 0E+1
2019-08-26 20:03:26.574 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 66: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-08-26 20:03:26.574 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 66: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2019-08-26 20:03:26.574 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 66: Sensor conversion not performed for POWER.
2019-08-26 20:03:26.575 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 66: Updating channel state zwave:device:6dad8bea:node66:sensor_power to 0 [DecimalType]
2019-08-26 20:03:26.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 66: Commands processed 1.
2019-08-26 20:03:26.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 66: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6e77296f.
2019-08-26 20:03:26.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:26.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:26.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:26.575 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:26.849 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 44 06 31 05 04 22 00 00 A7
2019-08-26 20:03:26.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=68, callback=0, payload=00 44 06 31 05 04 22 00 00
2019-08-26 20:03:26.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=68, callback=0, payload=00 44 06 31 05 04 22 00 00
2019-08-26 20:03:26.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:26.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 68: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:03:26.850 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:26.850 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Setting ONLINE
2019-08-26 20:03:26.851 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 68: Node Status event - Node is ALIVE
2019-08-26 20:03:26.851 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 68: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:03:26.851 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:26.851 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Setting ONLINE
2019-08-26 20:03:26.851 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 68: Node Status event - Node is ALIVE
2019-08-26 20:03:26.851 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 68: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-08-26 20:03:26.851 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 68: SECURITY not supported
2019-08-26 20:03:26.852 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 68: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2019-08-26 20:03:26.852 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 68: Sensor Type = Power(4), Scale = 0
2019-08-26 20:03:26.852 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 68: Sensor Value = 0E+1
2019-08-26 20:03:26.852 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-08-26 20:03:26.852 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2019-08-26 20:03:26.852 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 68: Sensor conversion not performed for POWER.
2019-08-26 20:03:26.852 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 68: Updating channel state zwave:device:6dad8bea:node68:sensor_power to 0 [DecimalType]
2019-08-26 20:03:26.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 68: Commands processed 1.
2019-08-26 20:03:26.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 68: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@422b5b35.
2019-08-26 20:03:26.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:26.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:26.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:26.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:27.078 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 43 06 31 05 04 22 00 00 A0
2019-08-26 20:03:27.078 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=67, callback=0, payload=00 43 06 31 05 04 22 00 00
2019-08-26 20:03:27.078 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=67, callback=0, payload=00 43 06 31 05 04 22 00 00
2019-08-26 20:03:27.079 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:27.079 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 67: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:03:27.079 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:27.079 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Setting ONLINE
2019-08-26 20:03:27.079 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 67: Node Status event - Node is ALIVE
2019-08-26 20:03:27.079 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 67: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:03:27.079 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:27.079 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Setting ONLINE
2019-08-26 20:03:27.079 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 67: Node Status event - Node is ALIVE
2019-08-26 20:03:27.079 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 67: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-08-26 20:03:27.080 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 67: SECURITY not supported
2019-08-26 20:03:27.080 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 67: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2019-08-26 20:03:27.080 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 67: Sensor Type = Power(4), Scale = 0
2019-08-26 20:03:27.080 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 67: Sensor Value = 0E+1
2019-08-26 20:03:27.080 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-08-26 20:03:27.080 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2019-08-26 20:03:27.080 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 67: Sensor conversion not performed for POWER.
2019-08-26 20:03:27.080 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 67: Updating channel state zwave:device:6dad8bea:node67:sensor_power to 0 [DecimalType]
2019-08-26 20:03:27.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 67: Commands processed 1.
2019-08-26 20:03:27.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 67: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@41c1141.
2019-08-26 20:03:27.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:27.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:27.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:27.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:27.130 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 27 0E 60 0D 01 01 32 02 21 44 00 00 00 93 00 00 6D
2019-08-26 20:03:27.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=39, callback=0, payload=00 27 0E 60 0D 01 01 32 02 21 44 00 00 00 93 00 00
2019-08-26 20:03:27.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=39, callback=0, payload=00 27 0E 60 0D 01 01 32 02 21 44 00 00 00 93 00 00
2019-08-26 20:03:27.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:27.130 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 39: Application Command Request (ALIVE:UPDATE_NEIGHBORS)
2019-08-26 20:03:27.130 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 39: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-08-26 20:03:27.131 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 39: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-08-26 20:03:27.131 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 39: SECURITY NOT required on COMMAND_CLASS_METER
2019-08-26 20:03:27.131 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 39: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-26 20:03:27.131 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 39: Meter: Type=Electric(1), Scale=kWh(0), Value=1.47
2019-08-26 20:03:27.131 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 39: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-26 20:03:27.131 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 39: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=1.47
2019-08-26 20:03:27.131 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 39: Updating channel state zwave:device:6dad8bea:node39:meter_kwh1 to 1.47 [DecimalType]
2019-08-26 20:03:27.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 39: Commands processed 1.
2019-08-26 20:03:27.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 39: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@48069eaa.
2019-08-26 20:03:27.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:27.131 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:27.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:27.132 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:27.286 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 3C 06 31 05 04 22 00 00 DF
2019-08-26 20:03:27.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=60, callback=0, payload=00 3C 06 31 05 04 22 00 00
2019-08-26 20:03:27.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=60, callback=0, payload=00 3C 06 31 05 04 22 00 00
2019-08-26 20:03:27.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-26 20:03:27.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 60: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:03:27.287 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:27.287 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Setting ONLINE
2019-08-26 20:03:27.287 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 60: Node Status event - Node is ALIVE
2019-08-26 20:03:27.288 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 60: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:03:27.288 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:27.288 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Setting ONLINE
2019-08-26 20:03:27.288 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 60: Node Status event - Node is ALIVE
2019-08-26 20:03:27.288 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 60: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-08-26 20:03:27.288 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 60: SECURITY not supported
2019-08-26 20:03:27.288 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 60: Received COMMAND_CLASS_SENSOR_MULTILEVEL V2 SENSOR_MULTILEVEL_REPORT
2019-08-26 20:03:27.288 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 60: Sensor Type = Power(4), Scale = 0
2019-08-26 20:03:27.288 [DEBUG] [col.commandclass.ZWaveMultiLevelSensorCommandClass] - NODE 60: Sensor Value = 0E+1
2019-08-26 20:03:27.289 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2019-08-26 20:03:27.289 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=0E+1
2019-08-26 20:03:27.289 [DEBUG] [.internal.converter.ZWaveMultiLevelSensorConverter] - NODE 60: Sensor conversion not performed for POWER.
2019-08-26 20:03:27.289 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 60: Updating channel state zwave:device:6dad8bea:node60:sensor_power to 0 [DecimalType]
2019-08-26 20:03:27.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 60: Commands processed 1.
2019-08-26 20:03:27.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 60: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fedfa94.
2019-08-26 20:03:27.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:27.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-26 20:03:27.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:27.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:33.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 103: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@75f28b3e
2019-08-26 20:03:33.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 103: Adding to device queue
2019-08-26 20:03:33.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 103: Added 43878 to queue - size 1
2019-08-26 20:03:33.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-26 20:03:33.337 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 67 9D 48
2019-08-26 20:03:33.337 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 67 9D 48
2019-08-26 20:03:33.337 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-08-26 20:03:33.337 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 43878: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 157
2019-08-26 20:03:33.338 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-08-26 20:03:33.338 [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:03:33.338 [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:03:33.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43878: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 157
2019-08-26 20:03:33.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-08-26 20:03:33.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:33.338 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:33.506 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 144: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@f815aa0
2019-08-26 20:03:33.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 144: Adding to device queue
2019-08-26 20:03:33.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 144: Added 43879 to queue - size 1
2019-08-26 20:03:33.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:33.694 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 120: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@3b500060
2019-08-26 20:03:33.695 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 120: Adding to device queue
2019-08-26 20:03:33.695 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 120: Added 43880 to queue - size 2
2019-08-26 20:03:33.695 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:33.883 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 00 49 40 67 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 0D
2019-08-26 20:03:33.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=103, callback=64, payload=40 67 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:03:33.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=103, callback=64, payload=40 67 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:03:33.884 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43878: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 157
2019-08-26 20:03:33.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-08-26 20:03:33.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 43878: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 157
2019-08-26 20:03:33.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 43878: (Callback 157)
2019-08-26 20:03:33.885 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=103, callback=64, payload=40 67 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:03:33.885 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 25 03 20 01 00 F5
2019-08-26 20:03:33.885 [WARN ] [otocol.serialmessage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).
2019-08-26 20:03:33.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:33.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:33.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=37, callback=0, payload=00 25 03 20 01 00
2019-08-26 20:03:33.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=37, callback=0, payload=00 25 03 20 01 00
2019-08-26 20:03:33.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43878: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 157
2019-08-26 20:03:33.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 37: Application Command Request (DEAD:UPDATE_NEIGHBORS)
2019-08-26 20:03:33.886 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:33.886 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Setting ONLINE
2019-08-26 20:03:33.886 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 37: Node Status event - Node is ALIVE
2019-08-26 20:03:33.886 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 37: Node is ALIVE. Init stage is UPDATE_NEIGHBORS.
2019-08-26 20:03:33.886 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2019-08-26 20:03:33.886 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Setting ONLINE
2019-08-26 20:03:33.886 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - NODE 37: Node Status event - Node is ALIVE
2019-08-26 20:03:33.887 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 37: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2019-08-26 20:03:33.887 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 37: SECURITY not supported
2019-08-26 20:03:33.887 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 37: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2019-08-26 20:03:33.887 [DEBUG] [ernal.protocol.commandclass.ZWaveBasicCommandClass] - NODE 37: Basic report, value = 0
2019-08-26 20:03:33.887 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-08-26 20:03:33.887 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2019-08-26 20:03:33.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 37: Commands processed 1.
2019-08-26 20:03:33.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 37: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@707e8af.
2019-08-26 20:03:33.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-08-26 20:03:33.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-08-26 20:03:33.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:33.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:34.383 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 25 0A 71 05 00 00 00 FF 07 00 00 00 48
2019-08-26 20:03:34.383 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=37, callback=0, payload=00 25 0A 71 05 00 00 00 FF 07 00 00 00
2019-08-26 20:03:34.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=37, callback=0, payload=00 25 0A 71 05 00 00 00 FF 07 00 00 00
2019-08-26 20:03:34.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 43878: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 157
2019-08-26 20:03:34.384 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 37: Application Command Request (ALIVE:UPDATE_NEIGHBORS)
2019-08-26 20:03:34.384 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 37: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2019-08-26 20:03:34.384 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 37: SECURITY not supported
2019-08-26 20:03:34.384 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 37: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2019-08-26 20:03:34.384 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 37: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0
2019-08-26 20:03:34.384 [DEBUG] [ernal.protocol.commandclass.ZWaveAlarmCommandClass] - NODE 37: Alarm Type = BURGLAR (0)
2019-08-26 20:03:34.384 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2019-08-26 20:03:34.384 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2019-08-26 20:03:34.384 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 37: Alarm converter processing NOTIFICATION
2019-08-26 20:03:34.385 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 37: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-26 20:03:34.385 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Updating channel state zwave:device:6dad8bea:node37:alarm_motion to OFF [OnOffType]
2019-08-26 20:03:34.385 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 37: Alarm converter processing NOTIFICATION
2019-08-26 20:03:34.385 [DEBUG] [nding.zwave.internal.converter.ZWaveAlarmConverter] - NODE 37: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-26 20:03:34.385 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 37: Updating channel state zwave:device:6dad8bea:node37:alarm_tamper to OFF [OnOffType]
2019-08-26 20:03:34.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 37: Commands processed 1.
2019-08-26 20:03:34.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 37: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@63757717.
2019-08-26 20:03:34.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-08-26 20:03:34.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2019-08-26 20:03:34.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-26 20:03:34.385 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-08-26 20:03:36.585 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 45: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1314b841
2019-08-26 20:03:36.586 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-08-26 20:03:36.586 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 45: Added 43881 to queue - size 3
2019-08-26 20:03:36.586 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.