Still bothered by this… I’ve included another log here that is more detailed and includes debug/info logging for more than just the zigbee binding…
I hope someone has either seen this before and has solved it or at least understands the issue. It’s quite frustrating to watch my controls bouncing back and forth when trying to set a color or any such thing with these lights…
Thanks for any guidance - even if it’s to junk these lights and start over with something other than a zigbee based rgbw solution…
And, yes, I’ve uploaded this to the online logfile viewer but that hasn’t really shed any light on why I seem to get multiple update messages after a request… I’m not sure what to look for in that respect…
FWIW, the Color_Temperature value prior to the request below was 10…
2020-05-04 15:48:32.721 [ome.event.ItemCommandEvent] - Item 'BrooklynLight1_ColorTemperature' received command 89
2020-05-04 15:48:32.777 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000069EEF: Command for channel zigbee:device:stick:f0d1b80000069eef:F0D1B80000069EEF_1_colortemperature --> 89 [PercentType]
2020-05-04 15:48:32.783 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - F0D1B80000069EEF: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=0, state=WAITING, sendCnt=0, command=MoveToColorTemperatureCommand [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=--, colorTemperature=414, transitionTime=10]]
2020-05-04 15:48:32.791 [DEBUG] [transaction.ZigBeeTransactionManager] - 09CC/1: Sending ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=8, state=WAITING, sendCnt=0, command=MoveToColorTemperatureCommand [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=97, colorTemperature=414, transitionTime=10]]
2020-05-04 15:48:32.793 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2020-05-04 15:48:32.797 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToColorTemperatureCommand [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=97, colorTemperature=414, transitionTime=10]
2020-05-04 15:48:32.800 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=151, commandId=10]
2020-05-04 15:48:32.804 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=09CC/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=1D, rssi=--, lqi=--, payload=01 97 0A 9E 01 0A 00]
2020-05-04 15:48:32.822 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=41]
2020-05-04 15:48:32.842 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=09CC, sourceEui=F0D1B80000069EEF, lastHopLqi=255, lastHopRssi=-56, relayList=]
2020-05-04 15:48:32.863 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=09CC, sourceEui=F0D1B80000069EEF, lastHopLqi=255, lastHopRssi=-56, relayList=]
2020-05-04 15:48:32.868 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=09CC, sourceEui=F0D1B80000069EEF, lastHopLqi=255, lastHopRssi=-56, relayList=]
2020-05-04 15:48:32.898 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=91], lastHopLqi=255, lastHopRssi=-56, sender=09CC, bindingIndex=255, addressIndex=255, messageContents=08 97 0B 0A 00]
2020-05-04 15:48:32.900 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=09CC/1, destinationAddress=0000/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=91, rssi=-56, lqi=FF, payload=08 97 0B 0A 00]
2020-05-04 15:48:32.903 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=151, commandId=11]
2020-05-04 15:48:32.906 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=97, commandIdentifier=10, statusCode=SUCCESS]
2020-05-04 15:48:32.909 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=97, commandIdentifier=10, statusCode=SUCCESS]
2020-05-04 15:48:32.912 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=97, commandIdentifier=10, statusCode=SUCCESS] ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=131, state=DISPATCHED, sendCnt=1, command=MoveToColorTemperatureCommand [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=97, colorTemperature=414, transitionTime=10]]
2020-05-04 15:48:32.916 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=134, state=COMPLETE, sendCnt=1, command=MoveToColorTemperatureCommand [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=97, colorTemperature=414, transitionTime=10]]
2020-05-04 15:48:32.918 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2020-05-04 15:48:32.918 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=2508, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=41], messageTag=97, status=EMBER_SUCCESS, messageContents=]
2020-05-04 15:48:32.920 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - F0D1B80000069EEF: transactionComplete COMPLETE 0
2020-05-04 15:48:32.922 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=97 state=RX_ACK
2020-05-04 15:48:32.924 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=92], lastHopLqi=255, lastHopRssi=-56, sender=09CC, bindingIndex=255, addressIndex=255, messageContents=08 0C 0A 07 00 21 A6 00]
2020-05-04 15:48:32.924 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=97, state=RX_ACK, outstanding=0
2020-05-04 15:48:32.926 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=09CC/1, destinationAddress=0000/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=92, rssi=-56, lqi=FF, payload=08 0C 0A 07 00 21 A6 00]
2020-05-04 15:48:32.928 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=12, commandId=10]
2020-05-04 15:48:32.931 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=0C, reports=[AttributeReport [attributeDataType=UNSIGNED_16_BIT_INTEGER, attributeIdentifier=7, attributeValue=166]]]
2020-05-04 15:48:32.933 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=0C, reports=[AttributeReport [attributeDataType=UNSIGNED_16_BIT_INTEGER, attributeIdentifier=7, attributeValue=166]]]
2020-05-04 15:48:32.936 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - F0D1B80000069EEF: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0C, commandIdentifier=10, statusCode=SUCCESS]]
2020-05-04 15:48:32.937 [DEBUG] [rter.ZigBeeConverterColorTemperature] - F0D1B80000069EEF: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=166, lastReportTime=Mon May 04 15:48:32 CDT 2020, implemented=true] on endpoint 1
2020-05-04 15:48:32.936 [DEBUG] [.converter.ZigBeeConverterColorColor] - F0D1B80000069EEF: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=166, lastReportTime=Mon May 04 15:48:32 CDT 2020, implemented=true]
2020-05-04 15:48:32.939 [DEBUG] [transaction.ZigBeeTransactionManager] - 09CC/1: Sending ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=2, state=WAITING, sendCnt=0, command=DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0C, commandIdentifier=10, statusCode=SUCCESS]]
2020-05-04 15:48:32.940 [DEBUG] [converter.ZigBeeBaseChannelConverter] - F0D1B80000069EEF: Channel zigbee:device:stick:f0d1b80000069eef:F0D1B80000069EEF_1_colortemperature updated to 11
2020-05-04 15:48:32.941 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2020-05-04 15:48:32.943 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0C, commandIdentifier=10, statusCode=SUCCESS]
2020-05-04 15:48:32.943 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000069EEF: Updating ZigBee channel state zigbee:device:stick:f0d1b80000069eef:F0D1B80000069EEF_1_colortemperature to 11
2020-05-04 15:48:32.945 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=12, commandId=11]
2020-05-04 15:48:32.947 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=09CC/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=1E, rssi=--, lqi=--, payload=10 0C 0B 0A 00]
2020-05-04 15:48:32.954 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:device:stick:f0d1b80000069eef
2020-05-04 15:48:32.956 [vent.ItemStateChangedEvent] - BrooklynLight1_ColorTemperature changed from 10 to 11
2020-05-04 15:48:32.956 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Canceling timeout task for thingUID=zigbee:device:stick:f0d1b80000069eef
2020-05-04 15:48:32.963 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=42]
2020-05-04 15:48:32.960 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:device:stick:f0d1b80000069eef in 14430 seconds
2020-05-04 15:48:32.985 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=09CC, sourceEui=F0D1B80000069EEF, lastHopLqi=255, lastHopRssi=-56, relayList=]
2020-05-04 15:48:33.028 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=2508, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=42], messageTag=0C, status=EMBER_SUCCESS, messageContents=]
2020-05-04 15:48:33.030 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=0C state=RX_ACK
2020-05-04 15:48:33.032 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=0C, state=RX_ACK, outstanding=1
2020-05-04 15:48:33.034 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=98, state=COMPLETE, sendCnt=1, command=DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0C, commandIdentifier=10, statusCode=SUCCESS]]
2020-05-04 15:48:33.036 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2020-05-04 15:48:33.038 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - F0D1B80000069EEF: transactionComplete COMPLETE 0
2020-05-04 15:48:33.040 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state updated: TID 0C -> RX_ACK == COMPLETE
2020-05-04 15:48:35.668 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=09CC, sourceEui=F0D1B80000069EEF, lastHopLqi=255, lastHopRssi=-56, relayList=]
2020-05-04 15:48:35.717 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=93], lastHopLqi=255, lastHopRssi=-56, sender=09CC, bindingIndex=255, addressIndex=255, messageContents=08 0D 0A 07 00 21 9E 01]
2020-05-04 15:48:35.721 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=09CC/1, destinationAddress=0000/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=93, rssi=-56, lqi=FF, payload=08 0D 0A 07 00 21 9E 01]
2020-05-04 15:48:35.725 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=13, commandId=10]
2020-05-04 15:48:35.729 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=0D, reports=[AttributeReport [attributeDataType=UNSIGNED_16_BIT_INTEGER, attributeIdentifier=7, attributeValue=414]]]
2020-05-04 15:48:35.734 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Color Control: 09CC/1 -> 0000/1, cluster=0300, TID=0D, reports=[AttributeReport [attributeDataType=UNSIGNED_16_BIT_INTEGER, attributeIdentifier=7, attributeValue=414]]]
2020-05-04 15:48:35.738 [DEBUG] [rter.ZigBeeConverterColorTemperature] - F0D1B80000069EEF: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=414, lastReportTime=Mon May 04 15:48:35 CDT 2020, implemented=true] on endpoint 1
2020-05-04 15:48:35.738 [DEBUG] [.converter.ZigBeeConverterColorColor] - F0D1B80000069EEF: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=7, name=Color Temperature, dataType=UNSIGNED_16_BIT_INTEGER, lastValue=414, lastReportTime=Mon May 04 15:48:35 CDT 2020, implemented=true]
2020-05-04 15:48:35.742 [DEBUG] [converter.ZigBeeBaseChannelConverter] - F0D1B80000069EEF: Channel zigbee:device:stick:f0d1b80000069eef:F0D1B80000069EEF_1_colortemperature updated to 89
2020-05-04 15:48:35.743 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - F0D1B80000069EEF: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0D, commandIdentifier=10, statusCode=SUCCESS]]
2020-05-04 15:48:35.746 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - F0D1B80000069EEF: Updating ZigBee channel state zigbee:device:stick:f0d1b80000069eef:F0D1B80000069EEF_1_colortemperature to 89
2020-05-04 15:48:35.747 [DEBUG] [transaction.ZigBeeTransactionManager] - 09CC/1: Sending ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=4, state=WAITING, sendCnt=0, command=DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0D, commandIdentifier=10, statusCode=SUCCESS]]
2020-05-04 15:48:35.750 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
2020-05-04 15:48:35.753 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:device:stick:f0d1b80000069eef
2020-05-04 15:48:35.754 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0D, commandIdentifier=10, statusCode=SUCCESS]
2020-05-04 15:48:35.758 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=13, commandId=11]
2020-05-04 15:48:35.760 [vent.ItemStateChangedEvent] - BrooklynLight1_ColorTemperature changed from 11 to 89
2020-05-04 15:48:35.762 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Canceling timeout task for thingUID=zigbee:device:stick:f0d1b80000069eef
2020-05-04 15:48:35.762 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=09CC/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=31, apsSecurity=false, ackRequest=true, apsCounter=1F, rssi=--, lqi=--, payload=10 0D 0B 0A 00]
2020-05-04 15:48:35.764 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:device:stick:f0d1b80000069eef in 14430 seconds
2020-05-04 15:48:35.778 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=43]
2020-05-04 15:48:35.800 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=09CC, sourceEui=F0D1B80000069EEF, lastHopLqi=255, lastHopRssi=-56, relayList=]
2020-05-04 15:48:35.844 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=2508, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=43], messageTag=0D, status=EMBER_SUCCESS, messageContents=]
2020-05-04 15:48:35.846 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=0D state=RX_ACK
2020-05-04 15:48:35.848 [DEBUG] [transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=0D, state=RX_ACK, outstanding=1
2020-05-04 15:48:35.851 [DEBUG] [transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=F0D1B80000069EEF queueTime=109, state=COMPLETE, sendCnt=1, command=DefaultResponse [Color Control: 0000/0 -> 09CC/1, cluster=0300, TID=0D, commandIdentifier=10, statusCode=SUCCESS]]
2020-05-04 15:48:35.852 [DEBUG] [transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
2020-05-04 15:48:35.854 [DEBUG] [e.transaction.ZigBeeTransactionQueue] - F0D1B80000069EEF: transactionComplete COMPLETE 0
2020-05-04 15:48:35.856 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction state updated: TID 0D -> RX_ACK == COMPLETE