events.log:

2019-06-28 03:59:00.013 [ome.event.ItemCommandEvent] - Item 'ZWaveNode035UpstairsShutter_BlindsControl' received command UP
2019-06-28 03:59:00.016 [ome.event.ItemCommandEvent] - Item 'ZWaveNode035UpstairsShutter_BlindsControl' received command UP
2019-06-28 03:59:00.018 [ome.event.ItemCommandEvent] - Item 'ZWaveNode049ShutterBathroom_BlindsControl' received command UP
2019-06-28 03:59:00.019 [ome.event.ItemCommandEvent] - Item 'ZWaveNode049ShutterBathroom_BlindsControl' received command UP
2019-06-28 03:59:00.020 [ome.event.ItemCommandEvent] - Item 'ZWaveNode048ShutterLarge_BlindsControl' received command UP
2019-06-28 03:59:00.021 [ome.event.ItemCommandEvent] - Item 'ZWaveNode045ShutterSmall_BlindsControl' received command UP
2019-06-28 03:59:00.021 [ome.event.ItemCommandEvent] - Item 'ZWaveNode044ShutterKitchen_BlindsControl' received command UP
2019-06-28 03:59:00.022 [ome.event.ItemCommandEvent] - Item 'ZWaveNode050ShutterLivingroomBlindsControl' received command UP
2019-06-28 03:59:00.023 [ome.event.ItemCommandEvent] - Item 'ZWaveNode048ShutterLarge_BlindsControl' received command UP
2019-06-28 03:59:00.025 [ome.event.ItemCommandEvent] - Item 'ZWaveNode045ShutterSmall_BlindsControl' received command UP
2019-06-28 03:59:00.026 [ome.event.ItemCommandEvent] - Item 'ZWaveNode044ShutterKitchen_BlindsControl' received command UP
2019-06-28 03:59:00.027 [ome.event.ItemCommandEvent] - Item 'ZWaveNode050ShutterLivingroomBlindsControl' received command UP
2019-06-28 03:59:00.027 [nt.ItemStatePredictedEvent] - ZWaveNode035UpstairsShutter_BlindsControl predicted to become UP
2019-06-28 03:59:00.027 [nt.ItemStatePredictedEvent] - ZWaveNode035UpstairsShutter_BlindsControl predicted to become UP
2019-06-28 03:59:00.028 [nt.ItemStatePredictedEvent] - ZWaveNode049ShutterBathroom_BlindsControl predicted to become UP
2019-06-28 03:59:00.031 [vent.ItemStateChangedEvent] - ZWaveNode035UpstairsShutter_BlindsControl changed from 93 to 0
2019-06-28 03:59:00.033 [nt.ItemStatePredictedEvent] - ZWaveNode049ShutterBathroom_BlindsControl predicted to become UP
2019-06-28 03:59:00.036 [vent.ItemStateChangedEvent] - ZWaveNode049ShutterBathroom_BlindsControl changed from 57 to 0
2019-06-28 03:59:00.037 [nt.ItemStatePredictedEvent] - ZWaveNode048ShutterLarge_BlindsControl predicted to become UP
2019-06-28 03:59:00.039 [vent.ItemStateChangedEvent] - ZWaveNode048ShutterLarge_BlindsControl changed from 28 to 0
2019-06-28 03:59:00.040 [nt.ItemStatePredictedEvent] - ZWaveNode045ShutterSmall_BlindsControl predicted to become UP
2019-06-28 03:59:00.043 [nt.ItemStatePredictedEvent] - ZWaveNode044ShutterKitchen_BlindsControl predicted to become UP
2019-06-28 03:59:00.044 [nt.ItemStatePredictedEvent] - ZWaveNode050ShutterLivingroomBlindsControl predicted to become UP
2019-06-28 03:59:00.045 [nt.ItemStatePredictedEvent] - ZWaveNode048ShutterLarge_BlindsControl predicted to become UP
2019-06-28 03:59:00.046 [nt.ItemStatePredictedEvent] - ZWaveNode045ShutterSmall_BlindsControl predicted to become UP
2019-06-28 03:59:00.046 [nt.ItemStatePredictedEvent] - ZWaveNode044ShutterKitchen_BlindsControl predicted to become UP
2019-06-28 03:59:00.046 [nt.ItemStatePredictedEvent] - ZWaveNode050ShutterLivingroomBlindsControl predicted to become UP

2019-06-28 04:04:07.211 [vent.ItemStateChangedEvent] - ZWaveNode048ShutterLarge_SwitchBinary changed from ON to OFF
2019-06-28 04:04:07.414 [vent.ItemStateChangedEvent] - ZWaveNode045ShutterSmall_SwitchBinary changed from ON to OFF
2019-06-28 04:04:08.089 [vent.ItemStateChangedEvent] - ZWaveNode045ShutterSmall_SensorPower changed from 50.1 to 0
2019-06-28 04:04:08.266 [vent.ItemStateChangedEvent] - ZWaveNode048ShutterLarge_SensorPower changed from 126 to 0.7
2019-06-28 04:04:08.690 [vent.ItemStateChangedEvent] - ZWaveNode045ShutterSmall_MeterWatts changed from 50.1 to 0
2019-06-28 04:04:09.124 [vent.ItemStateChangedEvent] - ZWaveNode048ShutterLarge_MeterWatts changed from 126 to 0.7

2019-06-28 04:04:25.832 [vent.ItemStateChangedEvent] - ZWaveNode044ShutterKitchen_BlindsControl changed from 0 to 100

2019-06-28 04:08:20.195 [vent.ItemStateChangedEvent] - ZWaveNode066BenedekLampa_MeterKwh changed from 0 to 237.74



opehhab.log:

// probably here is the most important part: 
// 2019-06-28 03:59:55.362 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 45: Sending REQUEST Message = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D4 C4 
// 2019-06-28 03:59:55.565 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
// 2019-06-28 03:59:55.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21491: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 212
// 2019-06-28 03:59:57.565 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 45: TID 21491: Timeout at state WAIT_RESPONSE. 3 retries remaining.
// 2019-06-28 03:59:57.565 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
// 2019-06-28 03:59:57.565 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21491: Transaction ABORTED
// 2019-06-28 03:59:57.565 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
// 2019-06-28 03:59:57.565 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 

Complete openhab.log:

2019-06-28 03:59:00.017 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Command received zwave:device:14f03b7e:node35:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.018 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 35: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.018 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:00.019 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:00.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21482 to queue - size 31
2019-06-28 03:59:00.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.020 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling intialised at 86400 seconds - start in 1500 milliseconds.
2019-06-28 03:59:00.022 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Command received zwave:device:14f03b7e:node35:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.022 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 35: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:00.023 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.023 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Command received zwave:device:14f03b7e:node49:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.023 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:00.023 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 49: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Transaction already in queue - removed original
2019-06-28 03:59:00.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:00.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21483 to queue - size 31
2019-06-28 03:59:00.024 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.025 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling intialised at 86400 seconds - start in 1500 milliseconds.
2019-06-28 03:59:00.025 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:00.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21484 to queue - size 32
2019-06-28 03:59:00.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.027 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.027 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Command received zwave:device:14f03b7e:node49:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.027 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 49: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:00.028 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Command received zwave:device:14f03b7e:node48:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.029 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 48: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.028 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:00.029 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:00.029 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.029 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Transaction already in queue - removed original
2019-06-28 03:59:00.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21485 to queue - size 32
2019-06-28 03:59:00.030 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Command received zwave:device:14f03b7e:node45:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.030 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 45: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.031 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:00.031 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.031 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:00.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21486 to queue - size 33
2019-06-28 03:59:00.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.032 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:00.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21487 to queue - size 34
2019-06-28 03:59:00.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.033 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Command received zwave:device:14f03b7e:node44:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.034 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 44: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:00.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:00.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21488 to queue - size 35
2019-06-28 03:59:00.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.036 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Command received zwave:device:14f03b7e:node50:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.037 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 50: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.037 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:00.038 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Command received zwave:device:14f03b7e:node48:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.038 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.038 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 48: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:00.038 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:00.038 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21489 to queue - size 36
2019-06-28 03:59:00.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.040 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Command received zwave:device:14f03b7e:node45:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.040 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.040 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 45: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.040 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:00.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:00.040 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Transaction already in queue - removed original
2019-06-28 03:59:00.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21490 to queue - size 36
2019-06-28 03:59:00.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.042 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Command received zwave:device:14f03b7e:node44:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.042 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:00.042 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 44: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Transaction already in queue - removed original
2019-06-28 03:59:00.043 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:00.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21491 to queue - size 36
2019-06-28 03:59:00.043 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:00.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Command received zwave:device:14f03b7e:node50:blinds_control --> UP [UpDownType]
2019-06-28 03:59:00.044 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 50: Creating new message for command SWITCH_MULTILEVEL_START_LEVEL_CHANGE
2019-06-28 03:59:00.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Transaction already in queue - removed original
2019-06-28 03:59:00.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:00.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21492 to queue - size 36
2019-06-28 03:59:00.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:00.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:00.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Transaction already in queue - removed original
2019-06-28 03:59:00.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21493 to queue - size 36
2019-06-28 03:59:00.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:00.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling intialised at 86400 seconds - start in 3000 milliseconds.
2019-06-28 03:59:00.948 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21478: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 03:59:00.948 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 03:59:00.948 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21478: Transaction ABORTED
2019-06-28 03:59:00.948 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 03:59:00.949 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 03:59:00.949 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 03:59:00.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling...
2019-06-28 03:59:01.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:switch_binary
2019-06-28 03:59:01.526 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 35: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2019-06-28 03:59:01.526 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 35: Creating new message for application command SWITCH_BINARY_GET
2019-06-28 03:59:01.526 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:01.526 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2019-06-28 03:59:01.527 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:blinds_control
2019-06-28 03:59:01.527 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 35: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 03:59:01.527 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 35: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 03:59:01.527 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:01.527 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:01.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:scene_number
2019-06-28 03:59:01.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:sensor_power
2019-06-28 03:59:01.528 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 35: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 03:59:01.528 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 35: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 03:59:01.528 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:01.529 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:01.529 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:meter_watts
2019-06-28 03:59:01.529 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 35: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:01.529 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 35: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:01.529 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 35: Creating new message for application command METER_GET
2019-06-28 03:59:01.529 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:01.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:01.530 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:meter_kwh
2019-06-28 03:59:01.530 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 35: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:01.530 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 35: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:01.530 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 35: Creating new message for application command METER_GET
2019-06-28 03:59:01.531 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: SECURITY not supported
2019-06-28 03:59:01.531 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 35: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:01.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:blinds_shutter
2019-06-28 03:59:01.531 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:01.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Polling zwave:device:14f03b7e:node35:blinds_lamella
2019-06-28 03:59:01.532 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:01.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21494 to queue - size 37
2019-06-28 03:59:01.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21495 to queue - size 38
2019-06-28 03:59:01.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21496 to queue - size 39
2019-06-28 03:59:01.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21497 to queue - size 40
2019-06-28 03:59:01.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21498 to queue - size 41
2019-06-28 03:59:01.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.535 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21499 to queue - size 42
2019-06-28 03:59:01.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:01.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 03:59:01.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Transaction already in queue - removed original
2019-06-28 03:59:01.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21500 to queue - size 42
2019-06-28 03:59:01.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.031 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling...
2019-06-28 03:59:03.031 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:switch_binary
2019-06-28 03:59:03.031 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 49: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2019-06-28 03:59:03.031 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 49: Creating new message for application command SWITCH_BINARY_GET
2019-06-28 03:59:03.032 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:03.032 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2019-06-28 03:59:03.032 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:blinds_control
2019-06-28 03:59:03.032 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 49: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.032 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 49: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 03:59:03.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:03.033 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.033 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:scene_number
2019-06-28 03:59:03.033 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:sensor_power
2019-06-28 03:59:03.033 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 49: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.034 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 49: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 03:59:03.034 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:03.034 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:meter_watts
2019-06-28 03:59:03.034 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 49: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.034 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 49: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.035 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 49: Creating new message for application command METER_GET
2019-06-28 03:59:03.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:03.035 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.035 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:meter_kwh
2019-06-28 03:59:03.035 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 49: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.035 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 49: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.036 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 49: Creating new message for application command METER_GET
2019-06-28 03:59:03.036 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: SECURITY not supported
2019-06-28 03:59:03.036 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 49: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.036 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:blinds_shutter
2019-06-28 03:59:03.036 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.036 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Polling zwave:device:14f03b7e:node49:blinds_lamella
2019-06-28 03:59:03.037 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21501 to queue - size 43
2019-06-28 03:59:03.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21502 to queue - size 44
2019-06-28 03:59:03.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21503 to queue - size 45
2019-06-28 03:59:03.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21504 to queue - size 46
2019-06-28 03:59:03.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21505 to queue - size 47
2019-06-28 03:59:03.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21506 to queue - size 48
2019-06-28 03:59:03.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 03:59:03.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Transaction already in queue - removed original
2019-06-28 03:59:03.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21507 to queue - size 48
2019-06-28 03:59:03.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling...
2019-06-28 03:59:03.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling...
2019-06-28 03:59:03.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:switch_binary
2019-06-28 03:59:03.044 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:switch_binary
2019-06-28 03:59:03.044 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 45: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2019-06-28 03:59:03.044 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 48: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2019-06-28 03:59:03.044 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 45: Creating new message for application command SWITCH_BINARY_GET
2019-06-28 03:59:03.044 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 48: Creating new message for application command SWITCH_BINARY_GET
2019-06-28 03:59:03.044 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:03.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:03.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2019-06-28 03:59:03.045 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2019-06-28 03:59:03.045 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:blinds_control
2019-06-28 03:59:03.045 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:blinds_control
2019-06-28 03:59:03.045 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 48: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.045 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 45: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.045 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 48: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 03:59:03.045 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 45: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 03:59:03.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:03.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:03.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:scene_number
2019-06-28 03:59:03.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:scene_number
2019-06-28 03:59:03.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:sensor_power
2019-06-28 03:59:03.046 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:sensor_power
2019-06-28 03:59:03.046 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 45: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.046 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 48: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.047 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 45: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 03:59:03.047 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 48: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 03:59:03.047 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling...
2019-06-28 03:59:03.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:03.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:03.047 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:switch_binary
2019-06-28 03:59:03.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.047 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.047 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 50: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2019-06-28 03:59:03.047 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:meter_watts
2019-06-28 03:59:03.047 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:meter_watts
2019-06-28 03:59:03.047 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 50: Creating new message for application command SWITCH_BINARY_GET
2019-06-28 03:59:03.048 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 48: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.048 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 45: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:03.048 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 48: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.048 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 45: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2019-06-28 03:59:03.048 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 48: Creating new message for application command METER_GET
2019-06-28 03:59:03.048 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 45: Creating new message for application command METER_GET
2019-06-28 03:59:03.048 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:blinds_control
2019-06-28 03:59:03.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:03.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.048 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:03.048 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 50: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.048 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:meter_kwh
2019-06-28 03:59:03.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.049 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 50: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 03:59:03.049 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 48: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.049 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:meter_kwh
2019-06-28 03:59:03.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:03.049 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 48: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.049 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 45: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.049 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 48: Creating new message for application command METER_GET
2019-06-28 03:59:03.049 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:scene_number
2019-06-28 03:59:03.049 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 45: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.049 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: SECURITY not supported
2019-06-28 03:59:03.049 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:sensor_power
2019-06-28 03:59:03.050 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 45: Creating new message for application command METER_GET
2019-06-28 03:59:03.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 48: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: SECURITY not supported
2019-06-28 03:59:03.050 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 50: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.050 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:blinds_shutter
2019-06-28 03:59:03.050 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 50: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 03:59:03.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 45: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.050 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.050 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:blinds_shutter
2019-06-28 03:59:03.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:03.050 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Polling zwave:device:14f03b7e:node48:blinds_lamella
2019-06-28 03:59:03.050 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.050 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.051 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.051 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:meter_watts
2019-06-28 03:59:03.051 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Polling zwave:device:14f03b7e:node45:blinds_lamella
2019-06-28 03:59:03.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.051 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.051 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 50: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21508 to queue - size 49
2019-06-28 03:59:03.051 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 50: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.051 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 50: Creating new message for application command METER_GET
2019-06-28 03:59:03.051 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21509 to queue - size 50
2019-06-28 03:59:03.052 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:03.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.052 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.052 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:meter_kwh
2019-06-28 03:59:03.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.052 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 50: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.053 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 50: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21510 to queue - size 51
2019-06-28 03:59:03.053 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 50: Creating new message for application command METER_GET
2019-06-28 03:59:03.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: SECURITY not supported
2019-06-28 03:59:03.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 50: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.053 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:blinds_shutter
2019-06-28 03:59:03.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21511 to queue - size 52
2019-06-28 03:59:03.053 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.054 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Polling zwave:device:14f03b7e:node50:blinds_lamella
2019-06-28 03:59:03.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.054 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21512 to queue - size 53
2019-06-28 03:59:03.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.054 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21514 to queue - size 54
2019-06-28 03:59:03.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21513 to queue - size 55
2019-06-28 03:59:03.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21516 to queue - size 56
2019-06-28 03:59:03.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21515 to queue - size 57
2019-06-28 03:59:03.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21518 to queue - size 58
2019-06-28 03:59:03.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21517 to queue - size 59
2019-06-28 03:59:03.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21520 to queue - size 60
2019-06-28 03:59:03.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21519 to queue - size 61
2019-06-28 03:59:03.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21522 to queue - size 62
2019-06-28 03:59:03.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21521 to queue - size 63
2019-06-28 03:59:03.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21524 to queue - size 64
2019-06-28 03:59:03.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Adding to device queue
2019-06-28 03:59:03.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Transaction already in queue - removed original
2019-06-28 03:59:03.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: Added 21526 to queue - size 64
2019-06-28 03:59:03.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.063 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling...
2019-06-28 03:59:03.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.063 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:switch_binary
2019-06-28 03:59:03.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21523 to queue - size 65
2019-06-28 03:59:03.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.063 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 44: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
2019-06-28 03:59:03.064 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 44: Creating new message for application command SWITCH_BINARY_GET
2019-06-28 03:59:03.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.064 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:03.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21525 to queue - size 66
2019-06-28 03:59:03.064 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
2019-06-28 03:59:03.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:blinds_control
2019-06-28 03:59:03.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.064 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 44: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 03:59:03.065 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 44: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 03:59:03.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Transaction already in queue - removed original
2019-06-28 03:59:03.065 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:03.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21527 to queue - size 66
2019-06-28 03:59:03.065 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.065 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.065 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:scene_number
2019-06-28 03:59:03.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Adding to device queue
2019-06-28 03:59:03.066 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:sensor_power
2019-06-28 03:59:03.066 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 44: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 03:59:03.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Transaction already in queue - removed original
2019-06-28 03:59:03.066 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 44: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 03:59:03.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: Added 21528 to queue - size 66
2019-06-28 03:59:03.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:03.066 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 03:59:03.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:meter_watts
2019-06-28 03:59:03.067 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 44: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.067 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 44: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.067 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 44: Creating new message for application command METER_GET
2019-06-28 03:59:03.068 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:03.068 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:meter_kwh
2019-06-28 03:59:03.068 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 44: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.069 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 44: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 03:59:03.069 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 44: Creating new message for application command METER_GET
2019-06-28 03:59:03.069 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: SECURITY not supported
2019-06-28 03:59:03.069 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 44: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 03:59:03.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:blinds_shutter
2019-06-28 03:59:03.070 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Polling zwave:device:14f03b7e:node44:blinds_lamella
2019-06-28 03:59:03.070 [DEBUG] [nufacturerProprietaryFibaroFgrm222V1] - Creating command message FGRM222_GET version 1
2019-06-28 03:59:03.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21529 to queue - size 67
2019-06-28 03:59:03.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21530 to queue - size 68
2019-06-28 03:59:03.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21531 to queue - size 69
2019-06-28 03:59:03.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21532 to queue - size 70
2019-06-28 03:59:03.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21533 to queue - size 71
2019-06-28 03:59:03.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21534 to queue - size 72
2019-06-28 03:59:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Adding to device queue
2019-06-28 03:59:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Transaction already in queue - removed original
2019-06-28 03:59:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: Added 21535 to queue - size 72
2019-06-28 03:59:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:12.948 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21478: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 03:59:12.948 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21478: Transaction is current transaction, so clearing!!!!!
2019-06-28 03:59:12.948 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21478: Transaction CANCELLED
2019-06-28 03:59:12.948 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 03:59:12.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:21478 CANCELLED
2019-06-28 03:59:12.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 03:59:12.949 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 23 05 26 04 20 00 FF 25 D1 CF 
2019-06-28 03:59:12.950 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 0C 00 13 23 05 26 04 20 00 FF 25 D1 CF 
2019-06-28 03:59:12.951 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 03:59:12.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21483: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 209
2019-06-28 03:59:14.951 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21483: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 03:59:14.951 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 03:59:14.951 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21483: Transaction ABORTED
2019-06-28 03:59:14.952 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 03:59:14.952 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 03:59:14.953 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 03:59:14.953 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:26.951 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21483: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 03:59:26.951 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21483: Transaction is current transaction, so clearing!!!!!
2019-06-28 03:59:26.951 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21483: Transaction CANCELLED
2019-06-28 03:59:26.951 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 03:59:26.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21483 CANCELLED
2019-06-28 03:59:26.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 03:59:26.952 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 31 05 26 04 20 00 FF 25 D2 DE 
2019-06-28 03:59:26.953 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 0C 00 13 31 05 26 04 20 00 FF 25 D2 DE 
2019-06-28 03:59:27.155 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 03:59:27.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21485: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 210
2019-06-28 03:59:29.156 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 49: TID 21485: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 03:59:29.156 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 03:59:29.156 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21485: Transaction ABORTED
2019-06-28 03:59:29.156 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 03:59:29.157 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 03:59:29.361 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 03:59:29.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:41.156 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 49: TID 21485: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 03:59:41.156 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21485: Transaction is current transaction, so clearing!!!!!
2019-06-28 03:59:41.156 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21485: Transaction CANCELLED
2019-06-28 03:59:41.156 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 03:59:41.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: notifyTransactionResponse TID:21485 CANCELLED
2019-06-28 03:59:41.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 03:59:41.158 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 30 05 26 04 20 00 FF 25 D3 DE 
2019-06-28 03:59:41.158 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 48: Sending REQUEST Message = 01 0C 00 13 30 05 26 04 20 00 FF 25 D3 DE 
2019-06-28 03:59:41.360 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 03:59:41.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21490: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 211
2019-06-28 03:59:43.361 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 48: TID 21490: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 03:59:43.361 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 03:59:43.361 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21490: Transaction ABORTED
2019-06-28 03:59:43.361 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 03:59:43.361 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 03:59:43.564 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 03:59:43.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 03:59:55.361 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 48: TID 21490: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 03:59:55.361 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21490: Transaction is current transaction, so clearing!!!!!
2019-06-28 03:59:55.361 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21490: Transaction CANCELLED
2019-06-28 03:59:55.361 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 48: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 03:59:55.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 48: notifyTransactionResponse TID:21490 CANCELLED
2019-06-28 03:59:55.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 03:59:55.362 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D4 C4 
2019-06-28 03:59:55.362 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 45: Sending REQUEST Message = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D4 C4 
2019-06-28 03:59:55.565 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 03:59:55.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21491: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 212
2019-06-28 03:59:57.565 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 45: TID 21491: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 03:59:57.565 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 03:59:57.565 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21491: Transaction ABORTED
2019-06-28 03:59:57.565 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 03:59:57.565 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 03:59:57.768 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 03:59:57.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:01.331 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:00:01.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:01.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:01.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21491: [ABORTED] priority=Set, requiresResponse=true, callback: 212
2019-06-28 04:00:01.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:00:01.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:01.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:01.333 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:00:01.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:01.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:01.334 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:01.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21491: [ABORTED] priority=Set, requiresResponse=true, callback: 212
2019-06-28 04:00:01.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:00:01.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21491: [ABORTED] priority=Set, requiresResponse=true, callback: 212
2019-06-28 04:00:01.335 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:01.335 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:01.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.335 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 45: sentData was not placed on stack.
2019-06-28 04:00:01.335 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:01.335 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21491: Transaction CANCELLED
2019-06-28 04:00:01.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.336 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:01.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: CANCEL while sending message. Requeueing - 2 attempts left!
2019-06-28 04:00:01.336 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:01.336 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21491: Transaction RESET with 2 retries remaining.
2019-06-28 04:00:01.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 04:00:01.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 3<>125 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21491 to queue - size 69
2019-06-28 04:00:01.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:01.337 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:01.337 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D5 C5 
2019-06-28 04:00:01.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 4<>124 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.337 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 45: Sending REQUEST Message = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D5 C5 
2019-06-28 04:00:01.337 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:01.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21491: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213
2019-06-28 04:00:01.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: TID 21491: Transaction not completed
2019-06-28 04:00:01.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (4): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21491: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213
2019-06-28 04:00:01.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:00:01.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21491: Resetting transaction
2019-06-28 04:00:01.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: notifyTransactionResponse TID:21491 WAIT_RESPONSE
2019-06-28 04:00:01.339 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:00:01.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Adding to device queue
2019-06-28 04:00:01.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 4<>124 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:01.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: Added 21491 to queue - size 69
2019-06-28 04:00:01.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:01.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (4): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.340 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:00:01.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:01.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (3): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 3<>125 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:01.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (3): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:01.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:00:01.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:01.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:01.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2019-06-28 04:00:01.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2019-06-28 04:00:01.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:01.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:01.589 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:01.589 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D5 C5 
2019-06-28 04:00:01.589 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 45: Sending REQUEST Message = 01 0C 00 13 2D 05 26 04 20 00 FF 25 D5 C5 
2019-06-28 04:00:01.590 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:01.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21491: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 213
2019-06-28 04:00:03.590 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 45: TID 21491: Timeout at state WAIT_RESPONSE. 2 retries remaining.
2019-06-28 04:00:03.590 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:00:03.590 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21491: Transaction ABORTED
2019-06-28 04:00:03.590 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:00:03.590 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:00:03.591 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:03.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:15.590 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 45: TID 21491: Timeout at state ABORTED. 2 retries remaining.
2019-06-28 04:00:15.590 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21491: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:00:15.590 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21491: Transaction CANCELLED
2019-06-28 04:00:15.590 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 45: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:15.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 45: notifyTransactionResponse TID:21491 CANCELLED
2019-06-28 04:00:15.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:15.591 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 2C 05 26 04 20 00 FF 25 D6 C7 
2019-06-28 04:00:15.592 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 44: Sending REQUEST Message = 01 0C 00 13 2C 05 26 04 20 00 FF 25 D6 C7 
2019-06-28 04:00:15.592 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:15.592 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21492: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 214
2019-06-28 04:00:17.592 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 44: TID 21492: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:00:17.592 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:00:17.592 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21492: Transaction ABORTED
2019-06-28 04:00:17.592 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:00:17.592 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:00:17.593 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:17.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:29.592 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 44: TID 21492: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:00:29.592 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21492: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:00:29.592 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21492: Transaction CANCELLED
2019-06-28 04:00:29.592 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 44: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:29.592 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 44: notifyTransactionResponse TID:21492 CANCELLED
2019-06-28 04:00:29.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:29.593 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 32 05 26 04 20 00 FF 25 D7 D8 
2019-06-28 04:00:29.593 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 50: Sending REQUEST Message = 01 0C 00 13 32 05 26 04 20 00 FF 25 D7 D8 
2019-06-28 04:00:29.594 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:29.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21493: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 215
2019-06-28 04:00:31.594 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 21493: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:00:31.594 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:00:31.594 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21493: Transaction ABORTED
2019-06-28 04:00:31.594 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:00:31.595 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:00:31.595 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:31.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:43.594 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 50: TID 21493: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:00:43.594 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21493: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:00:43.594 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21493: Transaction CANCELLED
2019-06-28 04:00:43.594 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 50: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:43.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 50: notifyTransactionResponse TID:21493 CANCELLED
2019-06-28 04:00:43.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:43.595 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 D8 55 
2019-06-28 04:00:43.595 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 D8 55 
2019-06-28 04:00:43.798 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:00:43.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 216
2019-06-28 04:00:45.798 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21479: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:00:45.798 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:00:45.798 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21479: Transaction ABORTED
2019-06-28 04:00:45.798 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:00:45.799 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:00:45.800 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:45.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:46.910 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:00:46.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:46.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:46.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [ABORTED] priority=Get, requiresResponse=true, callback: 216
2019-06-28 04:00:46.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:00:46.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:46.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:46.912 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:00:46.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:46.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:46.913 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:46.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [ABORTED] priority=Get, requiresResponse=true, callback: 216
2019-06-28 04:00:46.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:00:46.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21479: [ABORTED] priority=Get, requiresResponse=true, callback: 216
2019-06-28 04:00:46.914 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:46.914 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:46.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.914 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData was not placed on stack.
2019-06-28 04:00:46.914 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21479: Transaction CANCELLED
2019-06-28 04:00:46.914 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:46.914 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:46.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: CANCEL while sending message. Requeueing - 2 attempts left!
2019-06-28 04:00:46.915 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:46.915 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21479: Transaction RESET with 2 retries remaining.
2019-06-28 04:00:46.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue
2019-06-28 04:00:46.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 3<>125 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 21479 to queue - size 66
2019-06-28 04:00:46.915 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:46.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:46.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 4<>124 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.916 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 D9 54 
2019-06-28 04:00:46.916 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:00:46.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.916 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 D9 54 
2019-06-28 04:00:46.917 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:46.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217
2019-06-28 04:00:46.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 21479: Transaction not completed
2019-06-28 04:00:46.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (5): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217
2019-06-28 04:00:46.918 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:00:46.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:00:46.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:46.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21479: Resetting transaction
2019-06-28 04:00:46.918 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:00:46.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:21479 WAIT_RESPONSE
2019-06-28 04:00:46.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 6<>122 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:46.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue
2019-06-28 04:00:46.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 21479 to queue - size 66
2019-06-28 04:00:46.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:46.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (6): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (5): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (4): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (3): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:00:46.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:46.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:00:46.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:46.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:00:46.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2019-06-28 04:00:46.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2019-06-28 04:00:46.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:46.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:47.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:47.168 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 D9 54 
2019-06-28 04:00:47.168 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 D9 54 
2019-06-28 04:00:47.169 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:47.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217
2019-06-28 04:00:47.205 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:00:47.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:47.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:47.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217
2019-06-28 04:00:47.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:00:47.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:47.206 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:47.207 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:00:47.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:47.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:47.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217
2019-06-28 04:00:47.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:00:47.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 217
2019-06-28 04:00:47.209 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:47.209 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData was not placed on stack.
2019-06-28 04:00:47.209 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21479: Transaction CANCELLED
2019-06-28 04:00:47.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:00:47.210 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:47.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: CANCEL while sending message. Requeueing - 1 attempts left!
2019-06-28 04:00:47.210 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21479: Transaction RESET with 1 retries remaining.
2019-06-28 04:00:47.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue
2019-06-28 04:00:47.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 21479 to queue - size 66
2019-06-28 04:00:47.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:47.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 21479: Transaction not completed
2019-06-28 04:00:47.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:47.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:47.459 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:47.459 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 DA 57 
2019-06-28 04:00:47.459 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0E 00 13 13 07 60 0D 01 01 32 01 00 25 DA 57 
2019-06-28 04:00:47.460 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:47.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218
2019-06-28 04:00:47.461 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:00:47.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:47.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:00:47.461 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218
2019-06-28 04:00:47.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:00:47.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:47.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:00:47.462 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:00:47.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:47.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:47.463 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218
2019-06-28 04:00:47.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:00:47.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21479: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 218
2019-06-28 04:00:47.464 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:00:47.464 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData was not placed on stack.
2019-06-28 04:00:47.464 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21479: Transaction CANCELLED
2019-06-28 04:00:47.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:00:47.465 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:47.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Retry count exceeded. Discarding message: TID 21479: [CANCELLED] priority=Get, requiresResponse=true, callback: 218
2019-06-28 04:00:47.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 21479: Transaction completed
2019-06-28 04:00:47.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:21479 CANCELLED
2019-06-28 04:00:47.466 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:00:47.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:00:47.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:00:47.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:00:47.715 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 13 07 60 0D 01 01 32 01 10 25 DB 46 
2019-06-28 04:00:47.715 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0E 00 13 13 07 60 0D 01 01 32 01 10 25 DB 46 
2019-06-28 04:00:47.716 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:47.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21480: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 219
2019-06-28 04:00:49.716 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21480: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:00:49.716 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:00:49.716 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21480: Transaction ABORTED
2019-06-28 04:00:49.716 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:00:49.718 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:00:49.718 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:00:49.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:01.716 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21480: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:01:01.716 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21480: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:01:01.716 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21480: Transaction CANCELLED
2019-06-28 04:01:01.717 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:01:01.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:21480 CANCELLED
2019-06-28 04:01:01.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:01:01.718 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 13 06 60 0D 01 02 26 02 25 DC 47 
2019-06-28 04:01:01.718 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0D 00 13 13 06 60 0D 01 02 26 02 25 DC 47 
2019-06-28 04:01:01.718 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:01:01.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21481: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 220
2019-06-28 04:01:03.622 [WARN ] [nal.protocol.MilightV6SessionManager] - Session timeout!
2019-06-28 04:01:03.719 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21481: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:01:03.719 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:01:03.719 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21481: Transaction ABORTED
2019-06-28 04:01:03.719 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:01:03.720 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:01:03.720 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:01:03.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:15.720 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 19: TID 21481: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:01:15.720 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21481: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:01:15.720 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21481: Transaction CANCELLED
2019-06-28 04:01:15.720 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:01:15.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:21481 CANCELLED
2019-06-28 04:01:15.721 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:01:15.721 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 23 02 25 02 25 DD 1B 
2019-06-28 04:01:15.722 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 09 00 13 23 02 25 02 25 DD 1B 
2019-06-28 04:01:15.722 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:01:15.722 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21494: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 221
2019-06-28 04:01:17.722 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21494: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:01:17.722 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:01:17.722 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21494: Transaction ABORTED
2019-06-28 04:01:17.722 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:01:17.723 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:01:17.724 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:01:17.724 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.735 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling...
2019-06-28 04:01:22.735 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:switch_dimmer
2019-06-28 04:01:22.735 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 27: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2019-06-28 04:01:22.736 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 27: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 04:01:22.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL
2019-06-28 04:01:22.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2019-06-28 04:01:22.736 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling skipped for zwave:device:14f03b7e:node27:switch_dimmer on COMMAND_CLASS_BASIC
2019-06-28 04:01:22.736 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:scene_number
2019-06-28 04:01:22.737 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:sensor_power
2019-06-28 04:01:22.737 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 27: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2019-06-28 04:01:22.737 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 27: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 04:01:22.737 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2019-06-28 04:01:22.737 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_SENSOR_MULTILEVEL is NOT required to be secured
2019-06-28 04:01:22.738 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:meter_kwh
2019-06-28 04:01:22.738 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 04:01:22.738 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 04:01:22.738 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 27: Creating new message for application command METER_GET
2019-06-28 04:01:22.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_METER
2019-06-28 04:01:22.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 04:01:22.739 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:meter_watts
2019-06-28 04:01:22.739 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 04:01:22.739 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 04:01:22.739 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 27: Creating new message for application command METER_GET
2019-06-28 04:01:22.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_METER
2019-06-28 04:01:22.740 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_METER is NOT required to be secured
2019-06-28 04:01:22.740 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:meter_reset
2019-06-28 04:01:22.740 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2019-06-28 04:01:22.740 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:config_decimal_param19
2019-06-28 04:01:22.740 [DEBUG] [onverter.ZWaveConfigurationConverter] - NODE 27: Generating poll message for COMMAND_CLASS_CONFIGURATION, endpoint 0
2019-06-28 04:01:22.740 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 27: Creating new message for application command CONFIGURATIONCMD_GET
2019-06-28 04:01:22.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_CONFIGURATION
2019-06-28 04:01:22.741 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2019-06-28 04:01:22.741 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:alarm_general
2019-06-28 04:01:22.741 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:switch_dimmer1
2019-06-28 04:01:22.741 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 27: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 1
2019-06-28 04:01:22.742 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 27: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 04:01:22.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Encapsulating message, instance / endpoint 1
2019-06-28 04:01:22.742 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 27: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2019-06-28 04:01:22.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-06-28 04:01:22.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-06-28 04:01:22.743 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling skipped for zwave:device:14f03b7e:node27:switch_dimmer1 on COMMAND_CLASS_BASIC
2019-06-28 04:01:22.743 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:sensor_power1
2019-06-28 04:01:22.743 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 27: Generating poll message for COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 1
2019-06-28 04:01:22.743 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 27: Creating new message for command SENSOR_MULTILEVEL_GET
2019-06-28 04:01:22.743 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Encapsulating message, instance / endpoint 1
2019-06-28 04:01:22.743 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 27: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2019-06-28 04:01:22.744 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-06-28 04:01:22.744 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-06-28 04:01:22.744 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:meter_kwh1
2019-06-28 04:01:22.744 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2019-06-28 04:01:22.744 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2019-06-28 04:01:22.745 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 27: Creating new message for application command METER_GET
2019-06-28 04:01:22.745 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Encapsulating message, instance / endpoint 1
2019-06-28 04:01:22.745 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 27: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2019-06-28 04:01:22.745 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-06-28 04:01:22.745 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-06-28 04:01:22.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:meter_watts1
2019-06-28 04:01:22.746 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2019-06-28 04:01:22.746 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 27: Generating poll message for COMMAND_CLASS_METER, endpoint 1
2019-06-28 04:01:22.746 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 27: Creating new message for application command METER_GET
2019-06-28 04:01:22.746 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Encapsulating message, instance / endpoint 1
2019-06-28 04:01:22.746 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 27: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2019-06-28 04:01:22.746 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-06-28 04:01:22.747 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-06-28 04:01:22.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:alarm_general1
2019-06-28 04:01:22.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:notification_send1
2019-06-28 04:01:22.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling zwave:device:14f03b7e:node27:switch_dimmer2
2019-06-28 04:01:22.747 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 27: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 2
2019-06-28 04:01:22.748 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 27: Creating new message for command SWITCH_MULTILEVEL_GET
2019-06-28 04:01:22.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Encapsulating message, instance / endpoint 2
2019-06-28 04:01:22.748 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 27: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2019-06-28 04:01:22.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2019-06-28 04:01:22.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2019-06-28 04:01:22.748 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 27: Polling skipped for zwave:device:14f03b7e:node27:switch_dimmer2 on COMMAND_CLASS_BASIC
2019-06-28 04:01:22.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21536 to queue - size 63
2019-06-28 04:01:22.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.749 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21537 to queue - size 64
2019-06-28 04:01:22.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21538 to queue - size 65
2019-06-28 04:01:22.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21539 to queue - size 66
2019-06-28 04:01:22.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21540 to queue - size 67
2019-06-28 04:01:22.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21541 to queue - size 68
2019-06-28 04:01:22.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21542 to queue - size 69
2019-06-28 04:01:22.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21543 to queue - size 70
2019-06-28 04:01:22.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21544 to queue - size 71
2019-06-28 04:01:22.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:22.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Adding to device queue
2019-06-28 04:01:22.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Added 21545 to queue - size 72
2019-06-28 04:01:22.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:29.722 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21494: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:01:29.722 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21494: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:01:29.722 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21494: Transaction CANCELLED
2019-06-28 04:01:29.722 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:01:29.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21494 CANCELLED
2019-06-28 04:01:29.723 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:01:29.724 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 23 02 26 02 25 DE 1B 
2019-06-28 04:01:29.724 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 09 00 13 23 02 26 02 25 DE 1B 
2019-06-28 04:01:29.926 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:01:29.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21495: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 222
2019-06-28 04:01:31.926 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21495: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:01:31.926 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:01:31.926 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21495: Transaction ABORTED
2019-06-28 04:01:31.927 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:01:31.927 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:01:31.927 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:01:31.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:34.706 [WARN ] [nal.protocol.MilightV6SessionManager] - Session timeout!
2019-06-28 04:01:34.788 [INFO ] [nal.protocol.MilightV6SessionManager] - Confirmation received for unsend command. Sequence number: 75
2019-06-28 04:01:43.927 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21495: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:01:43.927 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21495: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:01:43.927 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21495: Transaction CANCELLED
2019-06-28 04:01:43.927 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:01:43.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21495 CANCELLED
2019-06-28 04:01:43.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:01:43.929 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 23 02 31 04 25 DF 0B 
2019-06-28 04:01:43.929 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 09 00 13 23 02 31 04 25 DF 0B 
2019-06-28 04:01:44.131 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:01:44.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21496: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 223
2019-06-28 04:01:45.104 [WARN ] [nal.protocol.MilightV6SessionManager] - Session timeout!
2019-06-28 04:01:45.172 [INFO ] [nal.protocol.MilightV6SessionManager] - Confirmation received for unsend command. Sequence number: 75
2019-06-28 04:01:46.131 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21496: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:01:46.131 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:01:46.131 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21496: Transaction ABORTED
2019-06-28 04:01:46.131 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:01:46.132 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:01:46.335 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:01:46.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:01:55.293 [WARN ] [nal.protocol.MilightV6SessionManager] - Session timeout!
2019-06-28 04:01:55.615 [INFO ] [nal.protocol.MilightV6SessionManager] - Confirmation received for unsend command. Sequence number: 331
2019-06-28 04:01:55.630 [INFO ] [nal.protocol.MilightV6SessionManager] - Confirmation received for unsend command. Sequence number: 75
2019-06-28 04:01:58.131 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21496: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:01:58.131 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21496: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:01:58.131 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21496: Transaction CANCELLED
2019-06-28 04:01:58.132 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:01:58.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21496 CANCELLED
2019-06-28 04:01:58.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:01:58.133 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 23 03 32 01 10 25 E0 20 
2019-06-28 04:01:58.133 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 0A 00 13 23 03 32 01 10 25 E0 20 
2019-06-28 04:01:58.335 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:01:58.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21497: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 224
2019-06-28 04:02:00.335 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21497: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:02:00.335 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:02:00.335 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21497: Transaction ABORTED
2019-06-28 04:02:00.336 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:02:00.336 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:02:00.538 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:02:00.538 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:04.067 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:02:04.067 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:04.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:04.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21497: [ABORTED] priority=Get, requiresResponse=true, callback: 224
2019-06-28 04:02:04.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:02:04.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:04.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:04.069 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:02:04.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:04.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:04.070 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:04.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21497: [ABORTED] priority=Get, requiresResponse=true, callback: 224
2019-06-28 04:02:04.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.070 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:02:04.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21497: [ABORTED] priority=Get, requiresResponse=true, callback: 224
2019-06-28 04:02:04.071 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:04.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:04.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.071 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 35: sentData was not placed on stack.
2019-06-28 04:02:04.071 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:04.071 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21497: Transaction CANCELLED
2019-06-28 04:02:04.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:04.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: CANCEL while sending message. Requeueing - 2 attempts left!
2019-06-28 04:02:04.072 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:04.072 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21497: Transaction RESET with 2 retries remaining.
2019-06-28 04:02:04.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 3<>125 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 04:02:04.072 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:04.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 4<>124 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21497 to queue - size 70
2019-06-28 04:02:04.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:04.073 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:04.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.074 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 23 03 32 01 10 25 E1 21 
2019-06-28 04:02:04.074 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 0A 00 13 23 03 32 01 10 25 E1 21 
2019-06-28 04:02:04.074 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:04.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21497: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 225
2019-06-28 04:02:04.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: TID 21497: Transaction not completed
2019-06-28 04:02:04.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (5): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21497: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 225
2019-06-28 04:02:04.075 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:02:04.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:02:04.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:04.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21497: Resetting transaction
2019-06-28 04:02:04.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21497 WAIT_RESPONSE
2019-06-28 04:02:04.076 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:02:04.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Adding to device queue
2019-06-28 04:02:04.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 6<>122 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:04.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: Added 21497 to queue - size 70
2019-06-28 04:02:04.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:04.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (6): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (5): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (4): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (3): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:04.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:04.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:02:04.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:04.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:04.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2019-06-28 04:02:04.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2019-06-28 04:02:04.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:04.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:04.326 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:04.326 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 23 03 32 01 10 25 E1 21 
2019-06-28 04:02:04.327 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 0A 00 13 23 03 32 01 10 25 E1 21 
2019-06-28 04:02:04.327 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:04.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21497: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 225
2019-06-28 04:02:05.983 [WARN ] [nal.protocol.MilightV6SessionManager] - Session timeout!
2019-06-28 04:02:06.053 [INFO ] [nal.protocol.MilightV6SessionManager] - Confirmation received for unsend command. Sequence number: 75
2019-06-28 04:02:06.327 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21497: Timeout at state WAIT_RESPONSE. 2 retries remaining.
2019-06-28 04:02:06.327 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:02:06.327 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21497: Transaction ABORTED
2019-06-28 04:02:06.327 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:02:06.328 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:02:06.328 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:06.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:12.344 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Polling...
2019-06-28 04:02:12.344 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 51: Polling deferred until initialisation complete
2019-06-28 04:02:18.327 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21497: Timeout at state ABORTED. 2 retries remaining.
2019-06-28 04:02:18.327 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21497: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:02:18.327 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21497: Transaction CANCELLED
2019-06-28 04:02:18.327 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:18.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21497 CANCELLED
2019-06-28 04:02:18.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:18.328 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 23 03 32 01 00 25 E2 32 
2019-06-28 04:02:18.329 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 0A 00 13 23 03 32 01 00 25 E2 32 
2019-06-28 04:02:18.329 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:18.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21498: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 226
2019-06-28 04:02:20.329 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21498: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:02:20.329 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:02:20.329 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21498: Transaction ABORTED
2019-06-28 04:02:20.329 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:02:20.330 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:02:20.331 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:20.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:32.329 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21498: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:02:32.329 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21498: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:02:32.329 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21498: Transaction CANCELLED
2019-06-28 04:02:32.329 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:32.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21498 CANCELLED
2019-06-28 04:02:32.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:32.330 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 23 02 91 02 25 E3 91 
2019-06-28 04:02:32.331 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 35: Sending REQUEST Message = 01 09 00 13 23 02 91 02 25 E3 91 
2019-06-28 04:02:32.331 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:32.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21500: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 227
2019-06-28 04:02:34.331 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21500: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:02:34.331 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:02:34.331 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21500: Transaction ABORTED
2019-06-28 04:02:34.331 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:02:34.332 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:02:34.332 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:34.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:46.332 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 35: TID 21500: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:02:46.332 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21500: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:02:46.332 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21500: Transaction CANCELLED
2019-06-28 04:02:46.332 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 35: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:46.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 35: notifyTransactionResponse TID:21500 CANCELLED
2019-06-28 04:02:46.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:46.333 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 31 02 25 02 25 E4 30 
2019-06-28 04:02:46.333 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 09 00 13 31 02 25 02 25 E4 30 
2019-06-28 04:02:46.334 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:46.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 228
2019-06-28 04:02:48.334 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 49: TID 21501: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:02:48.334 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:02:48.334 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21501: Transaction ABORTED
2019-06-28 04:02:48.334 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:02:48.335 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:02:48.537 [ERROR] [ing.zwave.handler.ZWaveSerialHandler] - Got I/O exception Input/output error in writeArray during sending. exiting thread.
2019-06-28 04:02:48.537 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:49.646 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:02:49.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:49.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:49.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [ABORTED] priority=Get, requiresResponse=true, callback: 228
2019-06-28 04:02:49.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:02:49.648 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:02:49.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:49.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:49.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [ABORTED] priority=Get, requiresResponse=true, callback: 228
2019-06-28 04:02:49.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:02:49.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21501: [ABORTED] priority=Get, requiresResponse=true, callback: 228
2019-06-28 04:02:49.650 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.650 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:49.650 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 49: sentData was not placed on stack.
2019-06-28 04:02:49.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.650 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21501: Transaction CANCELLED
2019-06-28 04:02:49.651 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:49.651 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:49.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: CANCEL while sending message. Requeueing - 2 attempts left!
2019-06-28 04:02:49.651 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21501: Transaction RESET with 2 retries remaining.
2019-06-28 04:02:49.651 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:49.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 04:02:49.651 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 2<>126 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21501 to queue - size 67
2019-06-28 04:02:49.652 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:49.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:49.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 3<>125 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.652 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 31 02 25 02 25 E5 31 
2019-06-28 04:02:49.652 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:49.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 4<>124 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.653 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 09 00 13 31 02 25 02 25 E5 31 
2019-06-28 04:02:49.653 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 18
2019-06-28 04:02:49.653 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:49.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 229
2019-06-28 04:02:49.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: TID 21501: Transaction not completed
2019-06-28 04:02:49.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (5): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.654 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:02:49.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 229
2019-06-28 04:02:49.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:02:49.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:49.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 21501: Resetting transaction
2019-06-28 04:02:49.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: notifyTransactionResponse TID:21501 WAIT_RESPONSE
2019-06-28 04:02:49.655 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:02:49.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 04:02:49.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 6<>122 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21501 to queue - size 67
2019-06-28 04:02:49.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:49.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (6): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (5): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (4): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (3): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.657 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (2): Message: class=null[0], type=CAN[4], dest=255, callback=0, payload=
2019-06-28 04:02:49.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:49.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:02:49.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-06-28 04:02:49.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2019-06-28 04:02:49.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2019-06-28 04:02:49.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:49.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:49.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:49.905 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 31 02 25 02 25 E5 31 
2019-06-28 04:02:49.905 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 09 00 13 31 02 25 02 25 E5 31 
2019-06-28 04:02:49.906 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:49.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 229
2019-06-28 04:02:49.942 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:02:49.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:49.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:49.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 229
2019-06-28 04:02:49.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:02:49.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:49.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:49.944 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:02:49.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 229
2019-06-28 04:02:49.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:02:49.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 229
2019-06-28 04:02:49.946 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:49.946 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 49: sentData was not placed on stack.
2019-06-28 04:02:49.946 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21501: Transaction CANCELLED
2019-06-28 04:02:49.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:02:49.947 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:49.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: CANCEL while sending message. Requeueing - 1 attempts left!
2019-06-28 04:02:49.947 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21501: Transaction RESET with 1 retries remaining.
2019-06-28 04:02:49.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Adding to device queue
2019-06-28 04:02:49.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Added 21501 to queue - size 67
2019-06-28 04:02:49.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:49.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: TID 21501: Transaction not completed
2019-06-28 04:02:49.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:49.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:50.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:50.197 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 31 02 25 02 25 E6 32 
2019-06-28 04:02:50.197 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 09 00 13 31 02 25 02 25 E6 32 
2019-06-28 04:02:50.198 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:50.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 230
2019-06-28 04:02:50.199 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-06-28 04:02:50.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:50.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-06-28 04:02:50.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 230
2019-06-28 04:02:50.200 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 00 E9 
2019-06-28 04:02:50.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-06-28 04:02:50.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:50.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:50.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:50.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:50.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 230
2019-06-28 04:02:50.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-06-28 04:02:50.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21501: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 230
2019-06-28 04:02:50.201 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=00 
2019-06-28 04:02:50.201 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 49: sentData was not placed on stack.
2019-06-28 04:02:50.201 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21501: Transaction CANCELLED
2019-06-28 04:02:50.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Holdoff Timer started...
2019-06-28 04:02:50.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:50.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: Retry count exceeded. Discarding message: TID 21501: [CANCELLED] priority=Get, requiresResponse=true, callback: 230
2019-06-28 04:02:50.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: TID 21501: Transaction completed
2019-06-28 04:02:50.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: notifyTransactionResponse TID:21501 CANCELLED
2019-06-28 04:02:50.203 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:02:50.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-06-28 04:02:50.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff true.
2019-06-28 04:02:50.452 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:02:50.452 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 31 02 26 02 25 E7 30 
2019-06-28 04:02:50.452 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 09 00 13 31 02 26 02 25 E7 30 
2019-06-28 04:02:50.453 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:50.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21502: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 231
2019-06-28 04:02:52.454 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 49: TID 21502: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:02:52.454 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:02:52.454 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21502: Transaction ABORTED
2019-06-28 04:02:52.454 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:02:52.455 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:02:52.455 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:02:52.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-06-28 04:02:57.886 [WARN ] [nal.protocol.MilightV6SessionManager] - Session timeout!
2019-06-28 04:02:57.969 [INFO ] [nal.protocol.MilightV6SessionManager] - Confirmation received for unsend command. Sequence number: 75
2019-06-28 04:03:04.454 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 49: TID 21502: Timeout at state ABORTED. 3 retries remaining.
2019-06-28 04:03:04.454 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 21502: Transaction is current transaction, so clearing!!!!!
2019-06-28 04:03:04.454 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21502: Transaction CANCELLED
2019-06-28 04:03:04.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 49: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2019-06-28 04:03:04.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 49: notifyTransactionResponse TID:21502 CANCELLED
2019-06-28 04:03:04.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-06-28 04:03:04.456 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 31 02 31 04 25 E8 2E 
2019-06-28 04:03:04.456 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 49: Sending REQUEST Message = 01 09 00 13 31 02 31 04 25 E8 2E 
2019-06-28 04:03:04.456 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:03:04.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21503: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 232
2019-06-28 04:03:06.457 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 49: TID 21503: Timeout at state WAIT_RESPONSE. 3 retries remaining.
2019-06-28 04:03:06.457 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2019-06-28 04:03:06.457 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21503: Transaction ABORTED
2019-06-28 04:03:06.457 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2019-06-28 04:03:06.458 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2019-06-28 04:03:06.458 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-06-28 04:03:06.458 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.