2019-10-17 07:03:14.329 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:03:15.852 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:03:23.847 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:04:21.626 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:04:26.258 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:05:11.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@e66b4

2019-10-17 07:05:11.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Adding to device queue

2019-10-17 07:05:11.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: Added 4716 to queue - size 21

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

2019-10-17 07:05:11.867 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 15 0E A9 

2019-10-17 07:05:11.873 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 15 0E A9 

2019-10-17 07:05:11.878 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-17 07:05:11.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:11.881 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

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

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

2019-10-17 07:05:11.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:11.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-17 07:05:11.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:05:12.012 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 49 40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A E1 

2019-10-17 07:05:12.021 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:12.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:12.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:12.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-17 07:05:12.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:12.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4716: (Callback 14)

2019-10-17 07:05:12.048 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:12.051 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:05:12.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:05:13.256 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 49 40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A E1 

2019-10-17 07:05:13.268 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:13.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:13.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:13.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-17 07:05:13.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:13.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4716: (Callback 14)

2019-10-17 07:05:13.295 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:13.299 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:05:13.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:05:14.143 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 49 40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A E1 

2019-10-17 07:05:14.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:14.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:14.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:14.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-17 07:05:14.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4716: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14

2019-10-17 07:05:14.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4716: (Callback 14)

2019-10-17 07:05:14.193 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=21, callback=64, payload=40 15 10 04 10 01 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:05:14.197 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:05:14.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:05:16.885 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 21: TID 4716: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-10-17 07:05:16.893 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 4716: Transaction is current transaction, so clearing!!!!!

2019-10-17 07:05:16.898 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 4716: Transaction CANCELLED

2019-10-17 07:05:16.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2019-10-17 07:05:16.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 21: notifyTransactionResponse TID:4716 CANCELLED

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

2019-10-17 07:05:16.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4716: Transaction event listener: DONE: CANCELLED -> 

2019-10-17 07:05:16.928 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 21: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1a959c0

2019-10-17 07:05:29.394 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling...

2019-10-17 07:05:29.401 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling deferred until initialisation complete

2019-10-17 07:05:33.769 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 0E 22 9E 

2019-10-17 07:05:33.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=14, payload=0E 22 

2019-10-17 07:05:33.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=14, payload=0E 22 

2019-10-17 07:05:33.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-17 07:05:33.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

2019-10-17 07:05:33.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null

2019-10-17 07:05:33.789 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=34, callback=14, payload=0E 22 

2019-10-17 07:05:33.792 [DEBUG] [equestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction

2019-10-17 07:05:33.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:06:10.612 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@1370297

2019-10-17 07:06:10.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Adding to device queue

2019-10-17 07:06:10.624 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: Added 4717 to queue - size 21

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

2019-10-17 07:06:10.634 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 48 1C 0F A1 

2019-10-17 07:06:10.639 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 48 1C 0F A1 

2019-10-17 07:06:10.646 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

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

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

2019-10-17 07:06:10.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-17 07:06:10.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-10-17 07:06:10.669 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-10-17 07:06:10.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 4717: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 15

2019-10-17 07:06:10.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:06:10.730 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 49 40 1C 10 04 10 03 5E 86 72 5A 85 59 73 80 71 31 70 84 7A EA 

2019-10-17 07:06:10.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=28, callback=64, payload=40 1C 10 04 10 03 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:06:10.748 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=28, callback=64, payload=40 1C 10 04 10 03 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:06:10.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 4717: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 15

2019-10-17 07:06:10.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-10-17 07:06:10.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 4717: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 15

2019-10-17 07:06:10.765 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 4717: (Callback 15)

2019-10-17 07:06:10.772 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=28, callback=64, payload=40 1C 10 04 10 03 5E 86 72 5A 85 59 73 80 71 31 70 84 7A 

2019-10-17 07:06:10.777 [WARN ] [essage.ApplicationUpdateMessageClass] - TODO: Implement Application Update Request Handling of New ID Assigned (64).

2019-10-17 07:06:10.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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

2019-10-17 07:06:15.680 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 28: TID 4717: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-10-17 07:06:15.686 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 4717: Transaction is current transaction, so clearing!!!!!

2019-10-17 07:06:15.689 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 4717: Transaction CANCELLED

2019-10-17 07:06:15.692 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 28: notifyTransactionResponse TID:4717 CANCELLED

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

2019-10-17 07:06:15.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 4717: Transaction event listener: DONE: CANCELLED -> 

2019-10-17 07:06:15.710 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 28: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@1df423d

2019-10-17 07:06:30.739 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 48 0F 23 9E 

2019-10-17 07:06:30.745 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=15, payload=0F 23 

2019-10-17 07:06:30.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=15, payload=0F 23 

2019-10-17 07:06:30.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-10-17 07:06:30.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0

2019-10-17 07:06:30.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null

2019-10-17 07:06:30.780 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeNeighborUpdate[72], type=Request[0], dest=35, callback=15, payload=0F 23 

2019-10-17 07:06:30.783 [DEBUG] [equestNodeNeighborUpdateMessageClass] - NodeNeighborUpdate request without transaction

2019-10-17 07:06:30.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

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