2018-01-24 21:03:44.785 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 48977/1: Setting cluster TOUCHLINK as server
2018-01-24 21:03:44.806 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 48977/1: Setting output clusters [4096]
2018-01-24 21:03:44.806 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 48977/1: Setting cluster TOUCHLINK as client
2018-01-24 21:03:44.807 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 48977: Discovery has completed all endpoints
2018-01-24 21:03:44.807 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00158D0000AF412C: Node 48977 is added to the network
2018-01-24 21:03:44.832 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - 00158D0000AF412C: Adding OTA Server
2018-01-24 21:03:44.833 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0000AF412C: Node updated - ZigBeeNode [IEEE=00158D0000AF412C, NWK=BF51, Type=ROUTER]
2018-01-24 21:03:44.852 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=3, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=65], messageTag=65, messageContents=00 FD 29]
2018-01-24 21:03:44.853 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=5, reTx=false, data=41 00 34 00 FD 29 00 00 03 00 00 00 40 11 00 00 41 41 03 00 FD 29]
2018-01-24 21:03:44.957 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2018-01-24 21:03:44.958 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 48977/1: Ending endpoint discovery
2018-01-24 21:03:44.966 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Start initialising ZigBee Thing handler
2018-01-24 21:03:44.975 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=0, reTx=false, data=41 80 34 00 41]
2018-01-24 21:03:44.975 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=65]
2018-01-24 21:03:44.975 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=65]
2018-01-24 21:03:44.976 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=65]
2018-01-24 21:03:44.976 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:45.081 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Electrical Measurement: 0/0 -> 1150/3, cluster=0B04, TID=42, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:45.083 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=66, commandId=12]
2018-01-24 21:03:45.086 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=1150/3, profile=0104, cluster=2820, addressMode=DEVICE, radius=31, sequence=66, payload=00 42 0C 00 00 0A]
2018-01-24 21:03:45.086 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=66], messageTag=66, messageContents=00 42 0C 00 00 0A]
2018-01-24 21:03:45.087 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2018-01-24 21:03:45.087 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=66], messageTag=66, messageContents=00 42 0C 00 00 0A]
2018-01-24 21:03:45.090 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=42 00 34 00 7E 04 04 01 04 0B 01 03 40 11 00 00 42 42 06 00 42 0C 00 00 0A]
2018-01-24 21:03:45.097 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00011E871A: Start initialising ZigBee Thing handler
2018-01-24 21:03:45.137 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 18191/2, cluster=0300, TID=43, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:45.139 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=67, commandId=12]
2018-01-24 21:03:45.139 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=18191/2, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=67, payload=00 43 0C 00 00 0A]
2018-01-24 21:03:45.140 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=67], messageTag=67, messageContents=00 43 0C 00 00 0A]
2018-01-24 21:03:45.141 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2018-01-24 21:03:45.141 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.216 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880101210D6A: Start initialising ZigBee Thing handler
2018-01-24 21:03:45.252 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 0017880102548F94: Start initialising ZigBee Thing handler
2018-01-24 21:03:45.277 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=41 90 3F 00 FD 29 00 00 03 00 00 00 40 11 00 00 41 41 00 00]
2018-01-24 21:03:45.278 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=3, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=65], messageTag=65, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.279 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=3, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=65], messageTag=65, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.279 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=3, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=65], messageTag=65, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.280 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.280 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:45.282 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=41 90 45 00 00 00 03 80 00 00 40 01 00 00 A3 E6 A4 FD 29 FF FF 06 00 00 FD 29 71 C4]
2018-01-24 21:03:45.283 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32771, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=163], lastHopLqi=230, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 71 C4]
2018-01-24 21:03:45.283 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32771, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=163], lastHopLqi=230, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 71 C4]
2018-01-24 21:03:45.284 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=10749/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, sequence=65, payload=00 00 FD 29 71 C4]
2018-01-24 21:03:45.285 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [10749/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, powerDescriptor=RECEIVER_ON_PERIODICALLY, [RECHARGABLE_BATTERY, MAINS, DISPOSABLE_BATTERY], DISPOSABLE_BATTERY, FULL]
2018-01-24 21:03:45.289 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.290 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:45.290 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749: Power Descriptor returned PowerDescriptorResponse [10749/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, powerDescriptor=RECEIVER_ON_PERIODICALLY, [RECHARGABLE_BATTERY, MAINS, DISPOSABLE_BATTERY], DISPOSABLE_BATTERY, FULL]
2018-01-24 21:03:45.290 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749: Discovery request POWER_DESCRIPTOR successful. Advanced to ACTIVE_ENDPOINTS.
2018-01-24 21:03:45.291 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 10749/0, cluster=0005, TID=44, nwkAddrOfInterest=10749]
2018-01-24 21:03:45.291 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=42 80 34 00 42]
2018-01-24 21:03:45.291 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=66]
2018-01-24 21:03:45.291 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=10749/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, sequence=68, payload=00 FD 29]
2018-01-24 21:03:45.292 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=66]
2018-01-24 21:03:45.292 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=68], messageTag=68, messageContents=00 FD 29]
2018-01-24 21:03:45.292 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=66]
2018-01-24 21:03:45.292 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 2
2018-01-24 21:03:45.293 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=67], messageTag=67, messageContents=00 43 0C 00 00 0A]
2018-01-24 21:03:45.294 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=43 00 34 00 0F 47 04 01 00 03 01 02 40 11 00 00 43 43 06 00 43 0C 00 00 0A]
2018-01-24 21:03:45.299 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=42 90 3F 00 7E 04 04 01 04 0B 01 03 40 11 00 00 42 42 00 00]
2018-01-24 21:03:45.300 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=66], messageTag=66, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.300 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=66], messageTag=66, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.301 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=66], messageTag=66, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.301 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.302 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:45.305 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.304 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 27819/11, cluster=0300, TID=45, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:45.307 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=69, commandId=12]
2018-01-24 21:03:45.308 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=27819/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=69, payload=00 45 0C 00 00 0A]
2018-01-24 21:03:45.308 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=69], messageTag=69, messageContents=00 45 0C 00 00 0A]
2018-01-24 21:03:45.309 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 2
2018-01-24 21:03:45.310 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.305 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 33387/11, cluster=0300, TID=46, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:45.311 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=70, commandId=12]
2018-01-24 21:03:45.312 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=33387/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=70, payload=00 46 0C 00 00 0A]
2018-01-24 21:03:45.336 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=70], messageTag=70, messageContents=00 46 0C 00 00 0A]
2018-01-24 21:03:45.336 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 3
2018-01-24 21:03:45.337 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.405 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=42 90 45 00 04 01 04 0B 03 01 40 00 00 00 1D FF A9 7E 04 FF FF 0A 18 42 0D 01 00 00 1B 0B 05 29]
2018-01-24 21:03:45.406 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=29], lastHopLqi=255, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 42 0D 01 00 00 1B 0B 05 29]
2018-01-24 21:03:45.407 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=2820, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=29], lastHopLqi=255, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 42 0D 01 00 00 1B 0B 05 29]
2018-01-24 21:03:45.408 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=1150/3, destinationAddress=0/1, profile=0104, cluster=2820, addressMode=null, radius=0, sequence=66, payload=18 42 0D 01 00 00 1B 0B 05 29]
2018-01-24 21:03:45.408 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=66, commandId=13]
2018-01-24 21:03:45.410 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Electrical Measurement: 1150/3 -> 0/1, cluster=0B04, TID=42, discoveryComplete=true, attributeInformation=[Attribute Information [dataType=BITMAP_32_BIT, identifier=0], Attribute Information [dataType=SIGNED_16_BIT_INTEGER, identifier=1291]]]
2018-01-24 21:03:45.412 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.412 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:45.420 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=1]
2018-01-24 21:03:45.421 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=43 00 34 00 0F 47 04 01 00 03 01 02 40 11 00 00 43 43 06 00 43 0C 00 00 0A]
2018-01-24 21:03:45.426 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Created 2 channels
2018-01-24 21:03:45.428 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.428 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Updating thing definition as channels have changed
2018-01-24 21:03:45.437 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Initializing channel zigbee:device:3e5f29cf:84182600000c8aa7:84182600000C8AA7_3_switch_onoff with org.openhab.binding.zigbee.converter.ZigBeeConverterSwitchOnoff@b34a2b
2018-01-24 21:03:45.439 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0/0 -> 1150/0, cluster=0021, TID=47, srcAddress=84182600000C8AA7, srcEndpoint=3, bindCluster=6, dstAddrMode=3, dstAddress=000D6F000B1329F8, dstEndpoint=1]
2018-01-24 21:03:45.440 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=1150/0, profile=0000, cluster=33, addressMode=DEVICE, radius=31, sequence=71, payload=00 A7 8A 0C 00 00 26 18 84 03 06 00 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:45.441 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=71], messageTag=71, messageContents=00 A7 8A 0C 00 00 26 18 84 03 06 00 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:45.442 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 4
2018-01-24 21:03:45.442 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.605 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:45.606 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=68], messageTag=68, messageContents=00 FD 29]
2018-01-24 21:03:45.606 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=44 00 34 00 FD 29 00 00 05 00 00 00 40 11 00 00 44 44 03 00 FD 29]
2018-01-24 21:03:45.610 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=43 80 34 00 43]
2018-01-24 21:03:45.611 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=67]
2018-01-24 21:03:45.612 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=67]
2018-01-24 21:03:45.613 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=67]
2018-01-24 21:03:45.613 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.614 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:45.717 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=43 90 3F 00 0F 47 04 01 00 03 01 02 40 11 00 00 43 43 00 00]
2018-01-24 21:03:45.718 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=67], messageTag=67, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.718 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=67], messageTag=67, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.719 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=67], messageTag=67, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:45.719 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.720 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:45.721 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=43 90 45 00 04 01 00 03 02 01 40 01 00 00 82 E8 A4 0F 47 FF FF 05 18 43 0B 0C 82]
2018-01-24 21:03:45.722 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=130], lastHopLqi=232, lastHopRssi=-92, sender=18191, bindingIndex=255, addressIndex=255, messageContents=18 43 0B 0C 82]
2018-01-24 21:03:45.723 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=130], lastHopLqi=232, lastHopRssi=-92, sender=18191, bindingIndex=255, addressIndex=255, messageContents=18 43 0B 0C 82]
2018-01-24 21:03:45.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=18191/2, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=67, payload=18 43 0B 0C 82]
2018-01-24 21:03:45.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=67, commandId=11]
2018-01-24 21:03:45.727 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Color control: 18191/2 -> 0/1, cluster=0300, TID=43, commandIdentifier=12, statusCode=UNSUP_GENERAL_COMMAND]
2018-01-24 21:03:45.727 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.731 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:45.731 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D00011E871A: Failed discovering attributes in color control cluster
2018-01-24 21:03:45.732 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=2]
2018-01-24 21:03:45.733 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=true, data=44 00 34 00 FD 29 00 00 05 00 00 00 40 11 00 00 44 44 03 00 FD 29]
2018-01-24 21:03:45.734 [DEBUG] [verter.ZigBeeChannelConverterFactory] - 00158D00011E871A: Removing channel switch_onoff in favor of switch_level
2018-01-24 21:03:45.737 [DEBUG] [verter.ZigBeeChannelConverterFactory] - 00158D00011E871A: Removing channel switch_level in favor of color_color
2018-01-24 21:03:45.737 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.738 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00011E871A: Created 2 channels
2018-01-24 21:03:45.739 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00011E871A: Updating thing definition as channels have changed
2018-01-24 21:03:45.746 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D00011E871A: Initializing channel zigbee:device:3e5f29cf:00158d00011e871a:00158D00011E871A_2_color_color with org.openhab.binding.zigbee.converter.ZigBeeConverterColorColor@61a648
2018-01-24 21:03:45.758 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 18191/2, cluster=0300, TID=48, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:45.759 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=72, commandId=12]
2018-01-24 21:03:45.760 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=18191/2, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=72, payload=00 48 0C 00 00 0A]
2018-01-24 21:03:45.761 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=72], messageTag=72, messageContents=00 48 0C 00 00 0A]
2018-01-24 21:03:45.761 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 4
2018-01-24 21:03:45.762 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.947 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:45.948 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=69], messageTag=69, messageContents=00 45 0C 00 00 0A]
2018-01-24 21:03:45.948 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=45 00 34 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 45 45 06 00 45 0C 00 00 0A]
2018-01-24 21:03:45.953 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=44 80 34 00 44]
2018-01-24 21:03:45.953 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=68]
2018-01-24 21:03:45.954 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=68]
2018-01-24 21:03:45.954 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=68]
2018-01-24 21:03:45.954 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:45.954 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:46.278 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=3]
2018-01-24 21:03:46.281 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=true, data=45 00 34 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 45 45 06 00 45 0C 00 00 0A]
2018-01-24 21:03:46.297 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.300 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=44 90 3F 00 FD 29 00 00 05 00 00 00 40 11 00 00 44 44 00 00]
2018-01-24 21:03:46.302 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=68], messageTag=68, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.303 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=68], messageTag=68, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.304 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=68], messageTag=68, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.305 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.306 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:46.471 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=44 90 45 00 00 00 05 80 00 00 40 01 00 00 A4 F7 A4 FD 29 FF FF 07 00 00 FD 29 02 02 01]
2018-01-24 21:03:46.473 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=164], lastHopLqi=247, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 02 02 01]
2018-01-24 21:03:46.475 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=164], lastHopLqi=247, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 02 02 01]
2018-01-24 21:03:46.477 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=10749/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=68, payload=00 00 FD 29 02 02 01]
2018-01-24 21:03:46.478 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [10749/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, activeEpList=[2, 1]]
2018-01-24 21:03:46.481 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.482 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:46.492 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749: Active Endpoints returned ActiveEndpointsResponse [10749/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, activeEpList=[2, 1]]
2018-01-24 21:03:46.493 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/2: Scheduling endpoint discovery
2018-01-24 21:03:46.494 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:46.495 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/1: Scheduling endpoint discovery
2018-01-24 21:03:46.497 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749: Discovery request ACTIVE_ENDPOINTS successful. Advanced to DISCOVERY_END.
2018-01-24 21:03:46.497 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=70], messageTag=70, messageContents=00 46 0C 00 00 0A]
2018-01-24 21:03:46.498 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749: Ending node discovery
2018-01-24 21:03:46.500 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/2: Starting endpoint discovery
2018-01-24 21:03:46.500 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=46 00 34 00 6B 82 04 01 00 03 01 0B 40 11 00 00 46 46 06 00 46 0C 00 00 0A]
2018-01-24 21:03:46.501 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 10749/0, cluster=0004, TID=49, nwkAddrOfInterest=10749, endpoint=2]
2018-01-24 21:03:46.502 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/1: Starting endpoint discovery
2018-01-24 21:03:46.503 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=10749/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, sequence=73, payload=00 FD 29 02]
2018-01-24 21:03:46.504 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 10749/0, cluster=0004, TID=4A, nwkAddrOfInterest=10749, endpoint=1]
2018-01-24 21:03:46.506 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=10749/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, sequence=74, payload=00 FD 29 01]
2018-01-24 21:03:46.505 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=73], messageTag=73, messageContents=00 FD 29 02]
2018-01-24 21:03:46.508 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=74], messageTag=74, messageContents=00 FD 29 01]
2018-01-24 21:03:46.509 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 3
2018-01-24 21:03:46.510 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 4
2018-01-24 21:03:46.520 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.521 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.620 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=45 80 34 00 45]
2018-01-24 21:03:46.621 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=69]
2018-01-24 21:03:46.621 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=69]
2018-01-24 21:03:46.621 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=69]
2018-01-24 21:03:46.622 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.622 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:46.624 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=45 90 3F 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 45 45 00 00]
2018-01-24 21:03:46.624 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=69], messageTag=69, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.625 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=69], messageTag=69, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.625 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=69], messageTag=69, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.625 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.626 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:46.628 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=45 90 45 00 04 01 00 03 0B 01 00 01 00 00 00 F4 A4 AB 6C FF FF 22 18 45 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:46.629 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0], lastHopLqi=244, lastHopRssi=-92, sender=27819, bindingIndex=255, addressIndex=255, messageContents=18 45 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:46.629 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0], lastHopLqi=244, lastHopRssi=-92, sender=27819, bindingIndex=255, addressIndex=255, messageContents=18 45 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:46.630 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=27819/11, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=69, payload=18 45 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:46.631 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=69, commandId=13]
2018-01-24 21:03:46.631 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Color control: 27819/11 -> 0/1, cluster=0300, TID=45, discoveryComplete=false, attributeInformation=[Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=0], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=1], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=2], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=3], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=4], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=7], Attribute Information [dataType=ENUMERATION_8_BIT, identifier=8], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=16], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=17], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=18]]]
2018-01-24 21:03:46.632 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.633 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:46.633 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 27819/11, cluster=0300, TID=4B, startAttributeIdentifier=19, maximumAttributeIdentifiers=10]
2018-01-24 21:03:46.634 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=75, commandId=12]
2018-01-24 21:03:46.634 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=4]
2018-01-24 21:03:46.635 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=27819/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=75, payload=00 4B 0C 13 00 0A]
2018-01-24 21:03:46.635 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=75], messageTag=75, messageContents=00 4B 0C 13 00 0A]
2018-01-24 21:03:46.635 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=true, data=46 00 34 00 6B 82 04 01 00 03 01 0B 40 11 00 00 46 46 06 00 46 0C 00 00 0A]
2018-01-24 21:03:46.635 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 5
2018-01-24 21:03:46.639 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.640 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.871 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:46.872 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=71], messageTag=71, messageContents=00 A7 8A 0C 00 00 26 18 84 03 06 00 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:46.873 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=47 00 34 00 7E 04 00 00 21 00 00 00 40 11 00 00 47 47 16 00 A7 8A 0C 00 00 26 18 84 03 06 00 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:46.881 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=46 80 34 00 46]
2018-01-24 21:03:46.882 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=70]
2018-01-24 21:03:46.883 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=70]
2018-01-24 21:03:46.883 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=70]
2018-01-24 21:03:46.884 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.884 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:46.886 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=46 90 3F 00 6B 82 04 01 00 03 01 0B 40 11 00 00 46 46 00 00]
2018-01-24 21:03:46.887 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=70], messageTag=70, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.888 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=70], messageTag=70, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.888 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=70], messageTag=70, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:46.889 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:46.890 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:47.106 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=46 90 45 00 04 01 00 03 0B 01 00 01 00 00 21 F7 A4 6B 82 FF FF 22 18 46 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:47.109 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=33], lastHopLqi=247, lastHopRssi=-92, sender=33387, bindingIndex=255, addressIndex=255, messageContents=18 46 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:47.112 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=33], lastHopLqi=247, lastHopRssi=-92, sender=33387, bindingIndex=255, addressIndex=255, messageContents=18 46 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:47.115 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=33387/11, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=70, payload=18 46 0D 00 00 00 20 01 00 20 02 00 21 03 00 21 04 00 21 07 00 21 08 00 30 10 00 20 11 00 21 12 00 21]
2018-01-24 21:03:47.116 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=70, commandId=13]
2018-01-24 21:03:47.120 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Color control: 33387/11 -> 0/1, cluster=0300, TID=46, discoveryComplete=false, attributeInformation=[Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=0], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=1], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=2], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=3], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=4], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=7], Attribute Information [dataType=ENUMERATION_8_BIT, identifier=8], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=16], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=17], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=18]]]
2018-01-24 21:03:47.121 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.122 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:47.123 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:47.124 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=true, data=47 00 34 00 7E 04 00 00 21 00 00 00 40 11 00 00 47 47 16 00 A7 8A 0C 00 00 26 18 84 03 06 00 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:47.127 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 33387/11, cluster=0300, TID=4C, startAttributeIdentifier=19, maximumAttributeIdentifiers=10]
2018-01-24 21:03:47.128 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=76, commandId=12]
2018-01-24 21:03:47.128 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=33387/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=76, payload=00 4C 0C 13 00 0A]
2018-01-24 21:03:47.129 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=76], messageTag=76, messageContents=00 4C 0C 13 00 0A]
2018-01-24 21:03:47.129 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 5
2018-01-24 21:03:47.131 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.133 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.389 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:47.390 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=72], messageTag=72, messageContents=00 48 0C 00 00 0A]
2018-01-24 21:03:47.390 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=48 00 34 00 0F 47 04 01 00 03 01 02 40 11 00 00 48 48 06 00 48 0C 00 00 0A]
2018-01-24 21:03:47.395 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=47 80 34 00 47]
2018-01-24 21:03:47.396 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=71]
2018-01-24 21:03:47.397 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=71]
2018-01-24 21:03:47.397 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=71]
2018-01-24 21:03:47.398 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.398 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:47.604 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=47 90 3F 00 7E 04 00 00 21 00 00 00 40 11 00 00 47 47 00 00]
2018-01-24 21:03:47.606 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=71], messageTag=71, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:47.607 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=71], messageTag=71, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:47.608 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=71], messageTag=71, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:47.608 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.609 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:47.614 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=47 90 45 00 00 00 21 80 00 00 40 00 00 00 1E FF AA 7E 04 FF FF 02 00 00]
2018-01-24 21:03:47.615 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=30], lastHopLqi=255, lastHopRssi=-86, sender=1150, bindingIndex=255, addressIndex=255, messageContents=00 00]
2018-01-24 21:03:47.616 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=30], lastHopLqi=255, lastHopRssi=-86, sender=1150, bindingIndex=255, addressIndex=255, messageContents=00 00]
2018-01-24 21:03:47.617 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=1150/0, destinationAddress=0/0, profile=0000, cluster=32801, addressMode=null, radius=0, sequence=71, payload=00 00]
2018-01-24 21:03:47.619 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: BindResponse [1150/0 -> 0/0, cluster=8021, TID=NULL, status=SUCCESS]
2018-01-24 21:03:47.620 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.624 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:47.626 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=6]
2018-01-24 21:03:47.627 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ConfigureReportingCommand [On/Off: 0/0 -> 1150/3, cluster=0006, TID=4D, records=[AttributeReportingConfigurationRecord: [attributeDataType=BOOLEAN, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=600]]]
2018-01-24 21:03:47.628 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=true, data=48 00 34 00 0F 47 04 01 00 03 01 02 40 11 00 00 48 48 06 00 48 0C 00 00 0A]
2018-01-24 21:03:47.629 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=77, commandId=6]
2018-01-24 21:03:47.631 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=1150/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, sequence=77, payload=00 4D 06 00 00 00 10 01 00 58 02]
2018-01-24 21:03:47.632 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=77], messageTag=77, messageContents=00 4D 06 00 00 00 10 01 00 58 02]
2018-01-24 21:03:47.633 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 5
2018-01-24 21:03:47.636 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.636 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.785 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0000AF412C: Start initialising ZigBee Thing handler
2018-01-24 21:03:47.793 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 48977/2, cluster=0300, TID=4E, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:47.795 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=78, commandId=12]
2018-01-24 21:03:47.797 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=48977/2, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=78, payload=00 4E 0C 00 00 0A]
2018-01-24 21:03:47.798 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=78], messageTag=78, messageContents=00 4E 0C 00 00 0A]
2018-01-24 21:03:47.799 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 6
2018-01-24 21:03:47.800 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.923 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:47.923 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=73], messageTag=73, messageContents=00 FD 29 02]
2018-01-24 21:03:47.924 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=49 00 34 00 FD 29 00 00 04 00 00 00 40 11 00 00 49 49 04 00 FD 29 02]
2018-01-24 21:03:47.930 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=48 80 34 00 48]
2018-01-24 21:03:47.930 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=72]
2018-01-24 21:03:47.931 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=72]
2018-01-24 21:03:47.931 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=72]
2018-01-24 21:03:47.932 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:47.933 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:48.000 [ERROR] [g.jupnp.protocol.sync.ReceivingEvent] - Invalid subscription ID, no active subscription: (IncomingEventRequestMessage) NOTIFY /upnpcallback/dev/RINCON_5CAAFD080C0401400_MR/svc/upnp-org/AVTransport/event/cb SEQUENCE: 0
2018-01-24 21:03:48.138 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=false, data=48 90 3F 00 0F 47 04 01 00 03 01 02 40 11 00 00 48 48 00 00]
2018-01-24 21:03:48.138 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=72], messageTag=72, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:48.139 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=72], messageTag=72, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:48.139 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=72], messageTag=72, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:48.140 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.140 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:48.144 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=48 90 45 00 04 01 00 03 02 01 40 01 00 00 83 F0 A4 0F 47 FF FF 05 18 48 0B 0C 82]
2018-01-24 21:03:48.145 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=131], lastHopLqi=240, lastHopRssi=-92, sender=18191, bindingIndex=255, addressIndex=255, messageContents=18 48 0B 0C 82]
2018-01-24 21:03:48.146 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=131], lastHopLqi=240, lastHopRssi=-92, sender=18191, bindingIndex=255, addressIndex=255, messageContents=18 48 0B 0C 82]
2018-01-24 21:03:48.147 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=18191/2, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=72, payload=18 48 0B 0C 82]
2018-01-24 21:03:48.148 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=72, commandId=11]
2018-01-24 21:03:48.149 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Color control: 18191/2 -> 0/1, cluster=0300, TID=48, commandIdentifier=12, statusCode=UNSUP_GENERAL_COMMAND]
2018-01-24 21:03:48.153 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.157 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:48.157 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D00011E871A: Cannot determine whether device supports RGB color. Assuming it supports HUE/SAT
2018-01-24 21:03:48.159 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 29299: Node discovery request IEEE_ADDRESS failed. Wait before retry.
2018-01-24 21:03:48.159 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=7]
2018-01-24 21:03:48.160 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0/0 -> 18191/0, cluster=0021, TID=4F, srcAddress=00158D00011E871A, srcEndpoint=2, bindCluster=768, dstAddrMode=3, dstAddress=000D6F000B1329F8, dstEndpoint=1]
2018-01-24 21:03:48.161 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=5, reTx=true, data=49 00 34 00 FD 29 00 00 04 00 00 00 40 11 00 00 49 49 04 00 FD 29 02]
2018-01-24 21:03:48.161 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=18191/0, profile=0000, cluster=33, addressMode=DEVICE, radius=31, sequence=79, payload=00 1A 87 1E 01 00 8D 15 00 02 00 03 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:48.163 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=79], messageTag=79, messageContents=00 1A 87 1E 01 00 8D 15 00 02 00 03 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:48.164 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 6
2018-01-24 21:03:48.169 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.169 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.278 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=48 90 45 00 00 00 05 80 00 00 40 01 00 00 51 FF DE 79 14 FF FF 06 00 00 79 14 01 01]
2018-01-24 21:03:48.279 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=81], lastHopLqi=255, lastHopRssi=-34, sender=5241, bindingIndex=255, addressIndex=255, messageContents=00 00 79 14 01 01]
2018-01-24 21:03:48.280 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=81], lastHopLqi=255, lastHopRssi=-34, sender=5241, bindingIndex=255, addressIndex=255, messageContents=00 00 79 14 01 01]
2018-01-24 21:03:48.281 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=5241/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=72, payload=00 00 79 14 01 01]
2018-01-24 21:03:48.282 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [5241/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=5241, activeEpList=[1]]
2018-01-24 21:03:48.282 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.284 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:48.283 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 5241: Active Endpoints returned ActiveEndpointsResponse [5241/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=5241, activeEpList=[1]]
2018-01-24 21:03:48.285 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 5241/1: Scheduling endpoint discovery
2018-01-24 21:03:48.285 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 5241: Discovery request ACTIVE_ENDPOINTS successful. Advanced to DISCOVERY_END.
2018-01-24 21:03:48.286 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 5241: Ending node discovery
2018-01-24 21:03:48.286 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 5241/1: Starting endpoint discovery
2018-01-24 21:03:48.287 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=48 90 3F 00 79 14 00 00 05 00 00 00 40 11 00 00 39 39 00 00]
2018-01-24 21:03:48.287 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 5241/0, cluster=0004, TID=50, nwkAddrOfInterest=5241, endpoint=1]
2018-01-24 21:03:48.287 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=5241, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=57], messageTag=57, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:48.288 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=5241/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, sequence=80, payload=00 79 14 01]
2018-01-24 21:03:48.288 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=5241, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=57], messageTag=57, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:48.289 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=5241, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=57], messageTag=57, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:48.289 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.289 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=5241, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=80], messageTag=80, messageContents=00 79 14 01]
2018-01-24 21:03:48.290 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:48.290 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 7
2018-01-24 21:03:48.291 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:48.292 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=74], messageTag=74, messageContents=00 FD 29 01]
2018-01-24 21:03:48.293 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=4A 00 34 00 FD 29 00 00 04 00 00 00 40 11 00 00 4A 4A 04 00 FD 29 01]
2018-01-24 21:03:48.298 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=49 80 34 00 49]
2018-01-24 21:03:48.298 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.299 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=73]
2018-01-24 21:03:48.300 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=73]
2018-01-24 21:03:48.300 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=73]
2018-01-24 21:03:48.301 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.301 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:48.405 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=0]
2018-01-24 21:03:48.407 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=true, data=4A 00 34 00 FD 29 00 00 04 00 00 00 40 11 00 00 4A 4A 04 00 FD 29 01]
2018-01-24 21:03:48.413 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:48.820 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=true, data=4A 00 34 00 FD 29 00 00 04 00 00 00 40 11 00 00 4A 4A 04 00 FD 29 01]
2018-01-24 21:03:48.824 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:48.832 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=75], messageTag=75, messageContents=00 4B 0C 13 00 0A]
2018-01-24 21:03:48.833 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=0, reTx=false, data=4B 00 34 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 4B 4B 06 00 4B 0C 13 00 0A]
2018-01-24 21:03:49.091 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=4A 80 34 00 4A]
2018-01-24 21:03:49.092 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=74]
2018-01-24 21:03:49.093 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=74]
2018-01-24 21:03:49.093 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=74]
2018-01-24 21:03:49.093 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.094 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:49.096 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=4A 90 45 00 00 00 05 80 00 00 40 01 00 00 65 FF DD C9 AD FF FF 06 00 00 C9 AD 01 01]
2018-01-24 21:03:49.097 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=101], lastHopLqi=255, lastHopRssi=-35, sender=44489, bindingIndex=255, addressIndex=255, messageContents=00 00 C9 AD 01 01]
2018-01-24 21:03:49.098 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=101], lastHopLqi=255, lastHopRssi=-35, sender=44489, bindingIndex=255, addressIndex=255, messageContents=00 00 C9 AD 01 01]
2018-01-24 21:03:49.099 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=44489/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=74, payload=00 00 C9 AD 01 01]
2018-01-24 21:03:49.099 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [44489/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=44489, activeEpList=[1]]
2018-01-24 21:03:49.100 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.101 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:49.104 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=4A 90 3F 00 C9 AD 00 00 05 00 00 00 40 11 00 00 3A 3A 00 00]
2018-01-24 21:03:49.104 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=44489, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=58], messageTag=58, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.105 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=44489, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=58], messageTag=58, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.105 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 44489: Active Endpoints returned ActiveEndpointsResponse [44489/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=44489, activeEpList=[1]]
2018-01-24 21:03:49.106 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=44489, apsFrame=EmberApsFrame [profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=58], messageTag=58, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.106 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 44489/1: Scheduling endpoint discovery
2018-01-24 21:03:49.106 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.106 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 44489: Discovery request ACTIVE_ENDPOINTS successful. Advanced to DISCOVERY_END.
2018-01-24 21:03:49.106 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:49.107 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 44489: Ending node discovery
2018-01-24 21:03:49.107 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 44489/1: Starting endpoint discovery
2018-01-24 21:03:49.108 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 44489/0, cluster=0004, TID=51, nwkAddrOfInterest=44489, endpoint=1]
2018-01-24 21:03:49.108 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=4A 90 3F 00 FD 29 00 00 04 00 00 00 40 11 00 00 49 49 00 00]
2018-01-24 21:03:49.109 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=44489/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, sequence=81, payload=00 C9 AD 01]
2018-01-24 21:03:49.109 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=73], messageTag=73, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.110 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=73], messageTag=73, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.110 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=73], messageTag=73, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.110 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 9093: Node discovery request IEEE_ADDRESS failed. Wait before retry.
2018-01-24 21:03:49.111 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.109 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=44489, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=81], messageTag=81, messageContents=00 C9 AD 01]
2018-01-24 21:03:49.111 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:49.111 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 6
2018-01-24 21:03:49.112 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.113 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=4A 90 3F 00 FD 29 00 00 04 00 00 00 40 11 00 00 4A 4A 00 00]
2018-01-24 21:03:49.114 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=74], messageTag=74, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.115 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=74], messageTag=74, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.115 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=74], messageTag=74, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.116 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.116 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:49.326 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:49.326 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.327 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=1]
2018-01-24 21:03:49.327 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=true, data=4B 00 34 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 4B 4B 06 00 4B 0C 13 00 0A]
2018-01-24 21:03:49.331 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.332 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=true, data=4A 80 34 00 4A]
2018-01-24 21:03:49.332 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.333 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:49.334 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=true, data=4A 90 45 00 00 00 05 80 00 00 40 01 00 00 65 FF DD C9 AD FF FF 06 00 00 C9 AD 01 01]
2018-01-24 21:03:49.335 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.335 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:49.337 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=true, data=4A 90 3F 00 C9 AD 00 00 05 00 00 00 40 11 00 00 3A 3A 00 00]
2018-01-24 21:03:49.337 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.337 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:49.339 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=true, data=4A 90 3F 00 FD 29 00 00 04 00 00 00 40 11 00 00 49 49 00 00]
2018-01-24 21:03:49.339 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.340 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:49.604 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=true, data=4A 90 3F 00 FD 29 00 00 04 00 00 00 40 11 00 00 4A 4A 00 00]
2018-01-24 21:03:49.605 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.605 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:49.607 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=false, data=4A 90 45 00 00 00 04 80 00 00 40 01 00 00 A5 F7 A4 FD 29 FF FF 1B 00 00 FD 29 16 02 04 01 07 01 00 06 00 00 01 00 03 00 06 04 00 04 02 04 01 19 00]
2018-01-24 21:03:49.608 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=165], lastHopLqi=247, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 16 02 04 01 07 01 00 06 00 00 01 00 03 00 06 04 00 04 02 04 01 19 00]
2018-01-24 21:03:49.609 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=165], lastHopLqi=247, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 16 02 04 01 07 01 00 06 00 00 01 00 03 00 06 04 00 04 02 04 01 19 00]
2018-01-24 21:03:49.609 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=10749/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, sequence=74, payload=00 00 FD 29 16 02 04 01 07 01 00 06 00 00 01 00 03 00 06 04 00 04 02 04 01 19 00]
2018-01-24 21:03:49.610 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [10749/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, length=22, simpleDescriptor=SimpleDescriptor [endpoint=2, profileId=0104, deviceId=263, deviceVersion=0, inputClusterList=[0, 1, 3, 1030, 1024, 1026], outputClusterList=[25]]]
2018-01-24 21:03:49.613 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.613 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:49.613 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/2: Simple Descriptor returned SimpleDescriptorResponse [10749/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, length=22, simpleDescriptor=SimpleDescriptor [endpoint=2, profileId=0104, deviceId=263, deviceVersion=0, inputClusterList=[0, 1, 3, 1030, 1024, 1026], outputClusterList=[25]]]
2018-01-24 21:03:49.614 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting input clusters [0, 1, 3, 1030, 1024, 1026]
2018-01-24 21:03:49.615 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster BASIC as server
2018-01-24 21:03:49.615 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=1, reTx=false, data=4A 90 45 00 00 00 04 80 00 00 40 01 00 00 A6 F8 A4 FD 29 FF FF 1D 00 00 FD 29 18 01 5E C0 50 08 02 01 00 00 07 00 00 03 00 04 00 06 00 08 00 00 03 05 00]
2018-01-24 21:03:49.616 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=166], lastHopLqi=248, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 18 01 5E C0 50 08 02 01 00 00 07 00 00 03 00 04 00 06 00 08 00 00 03 05 00]
2018-01-24 21:03:49.617 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=166], lastHopLqi=248, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=00 00 FD 29 18 01 5E C0 50 08 02 01 00 00 07 00 00 03 00 04 00 06 00 08 00 00 03 05 00]
2018-01-24 21:03:49.617 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=10749/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, sequence=74, payload=00 00 FD 29 18 01 5E C0 50 08 02 01 00 00 07 00 00 03 00 04 00 06 00 08 00 00 03 05 00]
2018-01-24 21:03:49.618 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster POWER_CONFIGURATION as server
2018-01-24 21:03:49.618 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [10749/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, length=24, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=C05E, deviceId=2128, deviceVersion=2, inputClusterList=[0], outputClusterList=[0, 3, 4, 6, 8, 768, 5]]]
2018-01-24 21:03:49.618 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.619 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster IDENTIFY as server
2018-01-24 21:03:49.619 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:49.619 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/1: Simple Descriptor returned SimpleDescriptorResponse [10749/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=10749, length=24, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=C05E, deviceId=2128, deviceVersion=2, inputClusterList=[0], outputClusterList=[0, 3, 4, 6, 8, 768, 5]]]
2018-01-24 21:03:49.621 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting input clusters [0]
2018-01-24 21:03:49.621 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster OCCUPANCY_SENSING as server
2018-01-24 21:03:49.621 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:49.622 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster BASIC as server
2018-01-24 21:03:49.622 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=76], messageTag=76, messageContents=00 4C 0C 13 00 0A]
2018-01-24 21:03:49.622 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting output clusters [0, 3, 4, 6, 8, 768, 5]
2018-01-24 21:03:49.623 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=4C 00 34 00 6B 82 04 01 00 03 01 0B 40 11 00 00 4C 4C 06 00 4C 0C 13 00 0A]
2018-01-24 21:03:49.623 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster BASIC as client
2018-01-24 21:03:49.623 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster ILLUMINANCE_MEASUREMENT as server
2018-01-24 21:03:49.624 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster IDENTIFY as client
2018-01-24 21:03:49.624 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster GROUPS as client
2018-01-24 21:03:49.624 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster TEMPERATURE_MEASUREMENT as server
2018-01-24 21:03:49.625 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster ON_OFF as client
2018-01-24 21:03:49.625 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting output clusters [25]
2018-01-24 21:03:49.626 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster LEVEL_CONTROL as client
2018-01-24 21:03:49.626 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/2: Setting cluster OTA_UPGRADE as client
2018-01-24 21:03:49.626 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/2: Ending endpoint discovery
2018-01-24 21:03:49.627 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster COLOR_CONTROL as client
2018-01-24 21:03:49.627 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 10749/1: Setting cluster SCENES as client
2018-01-24 21:03:49.627 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=4B 80 34 00 4B]
2018-01-24 21:03:49.629 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749: Discovery has completed all endpoints
2018-01-24 21:03:49.629 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=75]
2018-01-24 21:03:49.630 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=75]
2018-01-24 21:03:49.630 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 001788010201C153: Node 10749 is added to the network
2018-01-24 21:03:49.630 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=75]
2018-01-24 21:03:49.630 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.631 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:49.630 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788010201C153: Node updated - ZigBeeNode [IEEE=001788010201C153, NWK=29FD, Type=END_DEVICE]
2018-01-24 21:03:49.632 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=2]
2018-01-24 21:03:49.633 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=true, data=4C 00 34 00 6B 82 04 01 00 03 01 0B 40 11 00 00 4C 4C 06 00 4C 0C 13 00 0A]
2018-01-24 21:03:49.637 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.638 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=false, data=4B 90 3F 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 4B 4B 00 00]
2018-01-24 21:03:49.639 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=75], messageTag=75, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.640 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=75], messageTag=75, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.640 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=75], messageTag=75, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.640 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.641 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:49.643 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=4B 90 45 00 04 01 00 03 0B 01 00 01 00 00 01 E9 A4 AB 6C FF FF 22 18 4B 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:49.643 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 001788010201C153: Starting ZigBee device discovery
2018-01-24 21:03:49.644 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=1], lastHopLqi=233, lastHopRssi=-92, sender=27819, bindingIndex=255, addressIndex=255, messageContents=18 4B 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:49.645 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=1], lastHopLqi=233, lastHopRssi=-92, sender=27819, bindingIndex=255, addressIndex=255, messageContents=18 4B 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:49.646 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=27819/11, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=75, payload=18 4B 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:49.646 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=75, commandId=13]
2018-01-24 21:03:49.647 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Color control: 27819/11 -> 0/1, cluster=0300, TID=4B, discoveryComplete=false, attributeInformation=[Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=19], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=21], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=22], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=23], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=25], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=26], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=27], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=48], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=49], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=50]]]
2018-01-24 21:03:49.647 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.649 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:49.650 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 27819/11, cluster=0300, TID=52, startAttributeIdentifier=51, maximumAttributeIdentifiers=10]
2018-01-24 21:03:49.651 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=82, commandId=12]
2018-01-24 21:03:49.651 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=27819/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=82, payload=00 52 0C 33 00 0A]
2018-01-24 21:03:49.652 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 001788010201C153: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_ember:3e5f29cf
2018-01-24 21:03:49.652 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=82], messageTag=82, messageContents=00 52 0C 33 00 0A]
2018-01-24 21:03:49.653 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 6
2018-01-24 21:03:49.653 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.656 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788010201C153: ZigBee node property discovery start
2018-01-24 21:03:49.657 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788010201C153: ZigBee node property discovery using 10749/1
2018-01-24 21:03:49.658 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=null]
2018-01-24 21:03:49.659 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 10749/1, cluster=0000, TID=53, identifiers=[4]]
2018-01-24 21:03:49.660 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=83, commandId=0]
2018-01-24 21:03:49.660 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=10749/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, sequence=83, payload=00 53 00 04 00]
2018-01-24 21:03:49.661 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 29299/0, cluster=0001, TID=54, nwkAddrOfInterest=29299, requestType=1, startIndex=0]
2018-01-24 21:03:49.661 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=83], messageTag=83, messageContents=00 53 00 04 00]
2018-01-24 21:03:49.662 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=29299/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=84, payload=00 73 72 01 00]
2018-01-24 21:03:49.662 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 7
2018-01-24 21:03:49.662 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=29299, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=84], messageTag=84, messageContents=00 73 72 01 00]
2018-01-24 21:03:49.662 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.663 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:49.663 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.669 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2018-01-24 21:03:49.670 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 10749/1: Ending endpoint discovery
2018-01-24 21:03:49.808 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:49.810 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=77], messageTag=77, messageContents=00 4D 06 00 00 00 10 01 00 58 02]
2018-01-24 21:03:49.813 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=4D 00 34 00 7E 04 04 01 06 00 01 03 40 11 00 00 4D 4D 0B 00 4D 06 00 00 00 10 01 00 58 02]
2018-01-24 21:03:49.824 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=4C 80 34 00 4C]
2018-01-24 21:03:49.825 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=76]
2018-01-24 21:03:49.825 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=76]
2018-01-24 21:03:49.825 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=76]
2018-01-24 21:03:49.825 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.826 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:49.827 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=4C 90 3F 00 6B 82 04 01 00 03 01 0B 40 11 00 00 4C 4C 00 00]
2018-01-24 21:03:49.828 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=76], messageTag=76, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.828 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=76], messageTag=76, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.828 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=76], messageTag=76, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:49.828 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:49.829 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:50.032 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=4C 90 45 00 04 01 00 03 0B 01 00 01 00 00 22 F4 A4 6B 82 FF FF 22 18 4C 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:50.034 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=34], lastHopLqi=244, lastHopRssi=-92, sender=33387, bindingIndex=255, addressIndex=255, messageContents=18 4C 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:50.035 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=34], lastHopLqi=244, lastHopRssi=-92, sender=33387, bindingIndex=255, addressIndex=255, messageContents=18 4C 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:50.036 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=33387/11, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=76, payload=18 4C 0D 00 13 00 20 15 00 21 16 00 21 17 00 20 19 00 21 1A 00 21 1B 00 20 30 00 21 31 00 21 32 00 21]
2018-01-24 21:03:50.036 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=76, commandId=13]
2018-01-24 21:03:50.037 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Color control: 33387/11 -> 0/1, cluster=0300, TID=4C, discoveryComplete=false, attributeInformation=[Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=19], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=21], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=22], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=23], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=25], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=26], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=27], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=48], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=49], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=50]]]
2018-01-24 21:03:50.039 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.041 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 33387/11, cluster=0300, TID=55, startAttributeIdentifier=51, maximumAttributeIdentifiers=10]
2018-01-24 21:03:50.041 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:50.042 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=85, commandId=12]
2018-01-24 21:03:50.043 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=3]
2018-01-24 21:03:50.043 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=33387/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=85, payload=00 55 0C 33 00 0A]
2018-01-24 21:03:50.044 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=5, reTx=true, data=4D 00 34 00 7E 04 04 01 06 00 01 03 40 11 00 00 4D 4D 0B 00 4D 06 00 00 00 10 01 00 58 02]
2018-01-24 21:03:50.044 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=85], messageTag=85, messageContents=00 55 0C 33 00 0A]
2018-01-24 21:03:50.045 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:50.052 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.053 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.388 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:50.390 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=78], messageTag=78, messageContents=00 4E 0C 00 00 0A]
2018-01-24 21:03:50.393 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=4E 00 34 00 51 BF 04 01 00 03 01 02 40 11 00 00 4E 4E 06 00 4E 0C 00 00 0A]
2018-01-24 21:03:50.412 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=4D 80 34 00 4D]
2018-01-24 21:03:50.414 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=77]
2018-01-24 21:03:50.416 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=77]
2018-01-24 21:03:50.417 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=77]
2018-01-24 21:03:50.419 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.420 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:50.515 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=4D 90 3F 00 7E 04 04 01 06 00 01 03 40 11 00 00 4D 4D 00 00]
2018-01-24 21:03:50.516 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=77], messageTag=77, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:50.517 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=77], messageTag=77, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:50.517 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=77], messageTag=77, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:50.518 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.518 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:50.520 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=false, data=4D 90 45 00 04 01 06 00 03 01 40 00 00 00 1F FF A9 7E 04 FF FF 04 18 4D 07 00]
2018-01-24 21:03:50.520 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=31], lastHopLqi=255, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 4D 07 00]
2018-01-24 21:03:50.521 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=31], lastHopLqi=255, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 4D 07 00]
2018-01-24 21:03:50.521 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=1150/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, sequence=77, payload=18 4D 07 00]
2018-01-24 21:03:50.522 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=77, commandId=7]
2018-01-24 21:03:50.523 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ConfigureReportingResponse [On/Off: 1150/3 -> 0/1, cluster=0006, TID=4D, status=SUCCESS, records=null]
2018-01-24 21:03:50.525 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.526 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=null]
2018-01-24 21:03:50.527 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:50.527 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 1150/3, cluster=0006, TID=56, identifiers=[0]]
2018-01-24 21:03:50.528 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=86, commandId=0]
2018-01-24 21:03:50.528 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=4]
2018-01-24 21:03:50.528 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=1150/3, profile=0104, cluster=6, addressMode=DEVICE, radius=31, sequence=86, payload=00 56 00 00 00]
2018-01-24 21:03:50.529 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=0, reTx=true, data=4E 00 34 00 51 BF 04 01 00 03 01 02 40 11 00 00 4E 4E 06 00 4E 0C 00 00 0A]
2018-01-24 21:03:50.529 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=86], messageTag=86, messageContents=00 56 00 00 00]
2018-01-24 21:03:50.529 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:50.533 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.534 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.611 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 9093/0, cluster=0001, TID=57, nwkAddrOfInterest=9093, requestType=1, startIndex=0]
2018-01-24 21:03:50.612 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=9093/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=87, payload=00 85 23 01 00]
2018-01-24 21:03:50.613 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=9093, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=87], messageTag=87, messageContents=00 85 23 01 00]
2018-01-24 21:03:50.613 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 9
2018-01-24 21:03:50.614 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.701 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:50.702 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=79], messageTag=79, messageContents=00 1A 87 1E 01 00 8D 15 00 02 00 03 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:50.703 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=0, reTx=false, data=4F 00 34 00 0F 47 00 00 21 00 00 00 40 11 00 00 4F 4F 16 00 1A 87 1E 01 00 8D 15 00 02 00 03 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:50.712 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=5, reTx=false, data=4E 80 34 00 4E]
2018-01-24 21:03:50.713 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=78]
2018-01-24 21:03:50.713 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=78]
2018-01-24 21:03:50.714 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=78]
2018-01-24 21:03:50.715 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.715 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:50.920 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=false, data=4E 90 3F 00 51 BF 04 01 00 03 01 02 40 11 00 00 4E 4E 00 00]
2018-01-24 21:03:50.921 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=78], messageTag=78, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:50.921 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=78], messageTag=78, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:50.921 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=78], messageTag=78, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:50.922 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.922 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:50.924 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=5, reTx=false, data=4E 90 45 00 04 01 00 03 02 01 40 01 00 00 9B F8 A4 51 BF FF FF 05 18 4E 0B 0C 82]
2018-01-24 21:03:50.924 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=155], lastHopLqi=248, lastHopRssi=-92, sender=48977, bindingIndex=255, addressIndex=255, messageContents=18 4E 0B 0C 82]
2018-01-24 21:03:50.925 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=155], lastHopLqi=248, lastHopRssi=-92, sender=48977, bindingIndex=255, addressIndex=255, messageContents=18 4E 0B 0C 82]
2018-01-24 21:03:50.925 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=48977/2, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=78, payload=18 4E 0B 0C 82]
2018-01-24 21:03:50.925 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=78, commandId=11]
2018-01-24 21:03:50.926 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Color control: 48977/2 -> 0/1, cluster=0300, TID=4E, commandIdentifier=12, statusCode=UNSUP_GENERAL_COMMAND]
2018-01-24 21:03:50.929 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.929 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D0000AF412C: Failed discovering attributes in color control cluster
2018-01-24 21:03:50.929 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:50.930 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:50.931 [DEBUG] [verter.ZigBeeChannelConverterFactory] - 00158D0000AF412C: Removing channel switch_onoff in favor of switch_level
2018-01-24 21:03:50.931 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=true, data=4F 00 34 00 0F 47 00 00 21 00 00 00 40 11 00 00 4F 4F 16 00 1A 87 1E 01 00 8D 15 00 02 00 03 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:50.932 [DEBUG] [verter.ZigBeeChannelConverterFactory] - 00158D0000AF412C: Removing channel switch_level in favor of color_color
2018-01-24 21:03:50.932 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0000AF412C: Created 2 channels
2018-01-24 21:03:50.933 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0000AF412C: Updating thing definition as channels have changed
2018-01-24 21:03:50.938 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 00158D0000AF412C: Initializing channel zigbee:device:3e5f29cf:00158d0000af412c:00158D0000AF412C_2_color_color with org.openhab.binding.zigbee.converter.ZigBeeConverterColorColor@825da
2018-01-24 21:03:50.941 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:50.941 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 48977/2, cluster=0300, TID=58, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]
2018-01-24 21:03:50.942 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=88, commandId=12]
2018-01-24 21:03:50.943 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=48977/2, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=88, payload=00 58 0C 00 00 0A]
2018-01-24 21:03:50.943 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=88], messageTag=88, messageContents=00 58 0C 00 00 0A]
2018-01-24 21:03:50.943 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 9
2018-01-24 21:03:50.944 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.214 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:51.216 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=5241, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=80], messageTag=80, messageContents=00 79 14 01]
2018-01-24 21:03:51.219 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=50 00 34 00 79 14 00 00 04 00 00 00 40 11 00 00 50 50 04 00 79 14 01]
2018-01-24 21:03:51.235 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=4F 80 34 00 4F]
2018-01-24 21:03:51.236 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=79]
2018-01-24 21:03:51.238 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=79]
2018-01-24 21:03:51.239 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=79]
2018-01-24 21:03:51.241 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.242 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:51.343 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=4F 90 3F 00 0F 47 00 00 21 00 00 00 40 11 00 00 4F 4F 00 00]
2018-01-24 21:03:51.344 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=79], messageTag=79, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:51.346 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=79], messageTag=79, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:51.347 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=79], messageTag=79, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:51.349 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.350 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:51.357 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=4F 90 45 00 00 00 21 80 00 00 40 01 00 00 85 F2 A4 0F 47 FF FF 02 00 84]
2018-01-24 21:03:51.359 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=133], lastHopLqi=242, lastHopRssi=-92, sender=18191, bindingIndex=255, addressIndex=255, messageContents=00 84]
2018-01-24 21:03:51.361 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=133], lastHopLqi=242, lastHopRssi=-92, sender=18191, bindingIndex=255, addressIndex=255, messageContents=00 84]
2018-01-24 21:03:51.363 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=18191/0, destinationAddress=0/0, profile=0000, cluster=32801, addressMode=null, radius=0, sequence=79, payload=00 84]
2018-01-24 21:03:51.365 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: BindResponse [18191/0 -> 0/0, cluster=8021, TID=NULL, status=NOT_SUPPORTED]
2018-01-24 21:03:51.372 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.373 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=COLOR_CONTROL, id=0, name=CurrentHue, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null]
2018-01-24 21:03:51.374 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:51.375 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Color control: 0/0 -> 18191/2, cluster=0300, TID=59, identifiers=[0]]
2018-01-24 21:03:51.377 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=89, commandId=0]
2018-01-24 21:03:51.378 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=6]
2018-01-24 21:03:51.379 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=18191/2, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=89, payload=00 59 00 00 00]
2018-01-24 21:03:51.380 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=true, data=50 00 34 00 79 14 00 00 04 00 00 00 40 11 00 00 50 50 04 00 79 14 01]
2018-01-24 21:03:51.381 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=89], messageTag=89, messageContents=00 59 00 00 00]
2018-01-24 21:03:51.382 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 9
2018-01-24 21:03:51.396 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.397 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.606 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:51.608 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=44489, apsFrame=EmberApsFrame [profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=81], messageTag=81, messageContents=00 C9 AD 01]
2018-01-24 21:03:51.610 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=51 00 34 00 C9 AD 00 00 04 00 00 00 40 11 00 00 51 51 04 00 C9 AD 01]
2018-01-24 21:03:51.628 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=50 80 34 00 50]
2018-01-24 21:03:51.629 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=80]
2018-01-24 21:03:51.631 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=80]
2018-01-24 21:03:51.632 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=80]
2018-01-24 21:03:51.633 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.635 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:51.735 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=7]
2018-01-24 21:03:51.736 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=true, data=51 00 34 00 C9 AD 00 00 04 00 00 00 40 11 00 00 51 51 04 00 C9 AD 01]
2018-01-24 21:03:51.740 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.950 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:51.952 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=82], messageTag=82, messageContents=00 52 0C 33 00 0A]
2018-01-24 21:03:51.955 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=52 00 34 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 52 52 06 00 52 0C 33 00 0A]
2018-01-24 21:03:51.974 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=51 80 34 00 51]
2018-01-24 21:03:51.976 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=81]
2018-01-24 21:03:51.977 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=81]
2018-01-24 21:03:51.979 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=81]
2018-01-24 21:03:51.980 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:51.981 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:52.079 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=0]
2018-01-24 21:03:52.081 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=true, data=52 00 34 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 52 52 06 00 52 0C 33 00 0A]
2018-01-24 21:03:52.102 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.383 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:52.385 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=83], messageTag=83, messageContents=00 53 00 04 00]
2018-01-24 21:03:52.388 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=0, reTx=false, data=53 00 34 00 FD 29 04 01 00 00 01 01 40 11 00 00 53 53 05 00 53 00 04 00]
2018-01-24 21:03:52.410 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=52 80 34 00 52]
2018-01-24 21:03:52.412 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=82]
2018-01-24 21:03:52.413 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=82]
2018-01-24 21:03:52.415 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=82]
2018-01-24 21:03:52.416 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.417 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:52.516 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=52 90 3F 00 AB 6C 04 01 00 03 01 0B 40 11 00 00 52 52 00 00]
2018-01-24 21:03:52.517 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=82], messageTag=82, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:52.517 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=82], messageTag=82, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:52.518 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=82], messageTag=82, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:52.518 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.518 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:52.520 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=52 90 45 00 04 01 00 03 0B 01 00 01 00 00 02 F3 A4 AB 6C FF FF 22 18 52 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:52.521 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2], lastHopLqi=243, lastHopRssi=-92, sender=27819, bindingIndex=255, addressIndex=255, messageContents=18 52 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:52.522 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=2], lastHopLqi=243, lastHopRssi=-92, sender=27819, bindingIndex=255, addressIndex=255, messageContents=18 52 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:52.523 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=27819/11, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=82, payload=18 52 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:52.523 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=82, commandId=13]
2018-01-24 21:03:52.523 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Color control: 27819/11 -> 0/1, cluster=0300, TID=52, discoveryComplete=false, attributeInformation=[Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=51], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=52], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=54], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=55], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=56], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=58], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=59], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=60], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=16384], Attribute Information [dataType=ENUMERATION_8_BIT, identifier=16385]]]
2018-01-24 21:03:52.525 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.525 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:52.525 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 27819/11, cluster=0300, TID=5A, startAttributeIdentifier=16386, maximumAttributeIdentifiers=10]
2018-01-24 21:03:52.526 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=90, commandId=12]
2018-01-24 21:03:52.527 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=1]
2018-01-24 21:03:52.527 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=27819/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=90, payload=00 5A 0C 02 40 0A]
2018-01-24 21:03:52.527 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=53 00 34 00 FD 29 04 01 00 00 01 01 40 11 00 00 53 53 05 00 53 00 04 00]
2018-01-24 21:03:52.528 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=27819, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=90], messageTag=90, messageContents=00 5A 0C 02 40 0A]
2018-01-24 21:03:52.528 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 7
2018-01-24 21:03:52.532 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.533 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.918 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:52.919 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=29299, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=84], messageTag=84, messageContents=00 73 72 01 00]
2018-01-24 21:03:52.919 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=54 00 34 00 73 72 00 00 01 00 00 00 40 11 00 00 54 54 05 00 73 72 01 00]
2018-01-24 21:03:52.924 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=53 80 34 00 53]
2018-01-24 21:03:52.925 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=83]
2018-01-24 21:03:52.925 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=83]
2018-01-24 21:03:52.926 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=83]
2018-01-24 21:03:52.926 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:52.927 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:53.030 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=53 90 3F 00 FD 29 04 01 00 00 01 01 40 11 00 00 53 53 00 00]
2018-01-24 21:03:53.031 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=83], messageTag=83, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:53.032 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=83], messageTag=83, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:53.033 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=83], messageTag=83, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:53.033 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.034 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:53.036 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=53 90 45 00 04 01 00 00 01 01 00 01 00 00 A7 E9 A4 FD 29 FF FF 0F 18 53 01 04 00 00 42 07 50 68 69 6C 69 70 73]
2018-01-24 21:03:53.037 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=167], lastHopLqi=233, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=18 53 01 04 00 00 42 07 50 68 69 6C 69 70 73]
2018-01-24 21:03:53.038 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=167], lastHopLqi=233, lastHopRssi=-92, sender=10749, bindingIndex=255, addressIndex=255, messageContents=18 53 01 04 00 00 42 07 50 68 69 6C 69 70 73]
2018-01-24 21:03:53.038 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=10749/1, destinationAddress=0/1, profile=0104, cluster=0, addressMode=null, radius=0, sequence=83, payload=18 53 01 04 00 00 42 07 50 68 69 6C 69 70 73]
2018-01-24 21:03:53.039 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=83, commandId=1]
2018-01-24 21:03:53.041 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 10749/1 -> 0/1, cluster=0000, TID=53, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=Philips]]]
2018-01-24 21:03:53.041 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.042 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:53.055 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=2]
2018-01-24 21:03:53.056 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=true, data=54 00 34 00 73 72 00 00 01 00 00 00 40 11 00 00 54 54 05 00 73 72 01 00]
2018-01-24 21:03:53.061 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.064 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=BASIC, id=5, name=ModelIdentifier, dataType=CHARACTER_STRING, lastValue=null]
2018-01-24 21:03:53.065 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 10749/1, cluster=0000, TID=5B, identifiers=[5]]
2018-01-24 21:03:53.066 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=91, commandId=0]
2018-01-24 21:03:53.067 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=10749/1, profile=0104, cluster=0, addressMode=DEVICE, radius=31, sequence=91, payload=00 5B 00 05 00]
2018-01-24 21:03:53.068 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=10749, apsFrame=EmberApsFrame [profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=91], messageTag=91, messageContents=00 5B 00 05 00]
2018-01-24 21:03:53.069 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 7
2018-01-24 21:03:53.069 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.415 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:53.416 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=85], messageTag=85, messageContents=00 55 0C 33 00 0A]
2018-01-24 21:03:53.419 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=55 00 34 00 6B 82 04 01 00 03 01 0B 40 11 00 00 55 55 06 00 55 0C 33 00 0A]
2018-01-24 21:03:53.438 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=54 80 34 00 54]
2018-01-24 21:03:53.440 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=84]
2018-01-24 21:03:53.441 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=84]
2018-01-24 21:03:53.443 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=84]
2018-01-24 21:03:53.444 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.445 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:53.548 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=54 90 3F 00 73 72 00 00 01 00 00 00 40 11 00 00 54 54 00 00]
2018-01-24 21:03:53.550 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=29299, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=84], messageTag=84, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:53.554 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=29299, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=84], messageTag=84, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:53.556 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=29299, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=84], messageTag=84, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:53.558 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.560 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:53.570 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=54 90 45 00 00 00 01 80 00 00 40 01 00 00 5C E9 A4 73 72 FF FF 10 00 00 8C AE F1 02 01 88 17 00 73 72 01 00 92 B0]
2018-01-24 21:03:53.572 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32769, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=92], lastHopLqi=233, lastHopRssi=-92, sender=29299, bindingIndex=255, addressIndex=255, messageContents=00 00 8C AE F1 02 01 88 17 00 73 72 01 00 92 B0]
2018-01-24 21:03:53.573 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32769, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=92], lastHopLqi=233, lastHopRssi=-92, sender=29299, bindingIndex=255, addressIndex=255, messageContents=00 00 8C AE F1 02 01 88 17 00 73 72 01 00 92 B0]
2018-01-24 21:03:53.575 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=29299/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=84, payload=00 00 8C AE F1 02 01 88 17 00 73 72 01 00 92 B0]
2018-01-24 21:03:53.576 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [29299/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=0017880102F1AE8C, nwkAddrRemoteDev=29299, startIndex=0, nwkAddrAssocDevList=[45202]]
2018-01-24 21:03:53.577 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.586 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 29299: Ieee Address returned IeeeAddressResponse [29299/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=0017880102F1AE8C, nwkAddrRemoteDev=29299, startIndex=0, nwkAddrAssocDevList=[45202]]
2018-01-24 21:03:53.586 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:53.587 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 0017880102F1AE8C: Associated devices table updated: [45202]
2018-01-24 21:03:53.588 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 45202: Scheduling node discovery
2018-01-24 21:03:53.588 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 29299: Discovery request IEEE_ADDRESS successful. Advanced to NODE_DESCRIPTOR.
2018-01-24 21:03:53.590 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NodeDescriptorRequest [0/0 -> 29299/0, cluster=0002, TID=5C, nwkAddrOfInterest=29299]
2018-01-24 21:03:53.591 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 45202: Starting node discovery
2018-01-24 21:03:53.591 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=29299/0, profile=0000, cluster=2, addressMode=DEVICE, radius=31, sequence=92, payload=00 73 72]
2018-01-24 21:03:53.592 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=29299, apsFrame=EmberApsFrame [profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=92], messageTag=92, messageContents=00 73 72]
2018-01-24 21:03:53.592 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 45202/0, cluster=0001, TID=5D, nwkAddrOfInterest=45202, requestType=1, startIndex=0]
2018-01-24 21:03:53.594 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 7
2018-01-24 21:03:53.594 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=45202/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=93, payload=00 92 B0 01 00]
2018-01-24 21:03:53.595 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=45202, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=93], messageTag=93, messageContents=00 92 B0 01 00]
2018-01-24 21:03:53.590 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=false, data=54 90 45 04 00 00 06 00 00 00 08 00 00 00 60 FF A9 85 23 FF FF 09 05 FD FF 5E C0 01 19 00 00]
2018-01-24 21:03:53.596 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:53.595 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.597 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0, clusterId=6, sourceEndpoint=0, destinationEndpoint=0, options=[], groupId=0, sequence=96], lastHopLqi=255, lastHopRssi=-87, sender=9093, bindingIndex=255, addressIndex=255, messageContents=05 FD FF 5E C0 01 19 00 00]
2018-01-24 21:03:53.598 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.599 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST, apsFrame=EmberApsFrame [profileId=0, clusterId=6, sourceEndpoint=0, destinationEndpoint=0, options=[], groupId=0, sequence=96], lastHopLqi=255, lastHopRssi=-87, sender=9093, bindingIndex=255, addressIndex=255, messageContents=05 FD FF 5E C0 01 19 00 00]
2018-01-24 21:03:53.600 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=9093/0, destinationAddress=0/0, profile=0000, cluster=6, addressMode=null, radius=0, sequence=84, payload=05 FD FF 5E C0 01 19 00 00]
2018-01-24 21:03:53.602 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: MatchDescriptorRequest [9093/0 -> 0/0, cluster=0006, TID=NULL, nwkAddrOfInterest=65533, profileId=49246, inClusterList=[25], outClusterList=[]]
2018-01-24 21:03:53.603 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.604 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:53.608 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 824AB3EDD3867E08: ClusterMatcher received request MatchDescriptorRequest [9093/0 -> 0/0, cluster=0006, TID=NULL, nwkAddrOfInterest=65533, profileId=49246, inClusterList=[25], outClusterList=[]]
2018-01-24 21:03:53.611 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=3]
2018-01-24 21:03:53.612 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=true, data=55 00 34 00 6B 82 04 01 00 03 01 0B 40 11 00 00 55 55 06 00 55 0C 33 00 0A]
2018-01-24 21:03:53.617 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.885 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:53.886 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=86], messageTag=86, messageContents=00 56 00 00 00]
2018-01-24 21:03:53.887 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=56 00 34 00 7E 04 04 01 06 00 01 03 40 11 00 00 56 56 05 00 56 00 00 00]
2018-01-24 21:03:53.895 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=55 80 34 00 55]
2018-01-24 21:03:53.896 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=85]
2018-01-24 21:03:53.896 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=85]
2018-01-24 21:03:53.897 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=85]
2018-01-24 21:03:53.897 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:53.898 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:54.001 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=55 90 3F 00 6B 82 04 01 00 03 01 0B 40 11 00 00 55 55 00 00]
2018-01-24 21:03:54.003 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=85], messageTag=85, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:54.005 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=85], messageTag=85, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:54.006 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=85], messageTag=85, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:54.007 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.009 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:54.019 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=false, data=55 90 45 00 04 01 00 03 0B 01 00 01 00 00 23 EC A4 6B 82 FF FF 22 18 55 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:54.022 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=35], lastHopLqi=236, lastHopRssi=-92, sender=33387, bindingIndex=255, addressIndex=255, messageContents=18 55 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:54.025 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=11, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=35], lastHopLqi=236, lastHopRssi=-92, sender=33387, bindingIndex=255, addressIndex=255, messageContents=18 55 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:54.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=33387/11, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=85, payload=18 55 0D 00 33 00 21 34 00 20 36 00 21 37 00 21 38 00 20 3A 00 21 3B 00 21 3C 00 20 00 40 21 01 40 30]
2018-01-24 21:03:54.029 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=85, commandId=13]
2018-01-24 21:03:54.031 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DiscoverAttributesResponse [Color control: 33387/11 -> 0/1, cluster=0300, TID=55, discoveryComplete=false, attributeInformation=[Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=51], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=52], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=54], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=55], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=56], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=58], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=59], Attribute Information [dataType=UNSIGNED_8_BIT_INTEGER, identifier=60], Attribute Information [dataType=UNSIGNED_16_BIT_INTEGER, identifier=16384], Attribute Information [dataType=ENUMERATION_8_BIT, identifier=16385]]]
2018-01-24 21:03:54.033 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.034 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:54.034 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DiscoverAttributesCommand [Color control: 0/0 -> 33387/11, cluster=0300, TID=5E, startAttributeIdentifier=16386, maximumAttributeIdentifiers=10]
2018-01-24 21:03:54.036 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=94, commandId=12]
2018-01-24 21:03:54.038 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=33387/11, profile=0104, cluster=768, addressMode=DEVICE, radius=31, sequence=94, payload=00 5E 0C 02 40 0A]
2018-01-24 21:03:54.038 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=4]
2018-01-24 21:03:54.039 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=33387, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=11, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=94], messageTag=94, messageContents=00 5E 0C 02 40 0A]
2018-01-24 21:03:54.041 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=true, data=56 00 34 00 7E 04 04 01 06 00 01 03 40 11 00 00 56 56 05 00 56 00 00 00]
2018-01-24 21:03:54.041 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:54.054 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.055 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.267 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:54.268 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=9093, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=87], messageTag=87, messageContents=00 85 23 01 00]
2018-01-24 21:03:54.269 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=57 00 34 00 85 23 00 00 01 00 00 00 40 11 00 00 57 57 05 00 85 23 01 00]
2018-01-24 21:03:54.277 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=56 80 34 00 56]
2018-01-24 21:03:54.277 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=86]
2018-01-24 21:03:54.278 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=86]
2018-01-24 21:03:54.278 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=86]
2018-01-24 21:03:54.278 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.279 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:54.280 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=56 90 45 00 04 01 06 00 03 01 00 00 00 00 20 FE A9 7E 04 FF FF 07 18 87 0A 00 00 10 00]
2018-01-24 21:03:54.281 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=3, destinationEndpoint=1, options=[], groupId=0, sequence=32], lastHopLqi=254, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 87 0A 00 00 10 00]
2018-01-24 21:03:54.282 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=3, destinationEndpoint=1, options=[], groupId=0, sequence=32], lastHopLqi=254, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 87 0A 00 00 10 00]
2018-01-24 21:03:54.282 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=1150/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, sequence=86, payload=18 87 0A 00 00 10 00]
2018-01-24 21:03:54.282 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=135, commandId=10]
2018-01-24 21:03:54.283 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 1150/3 -> 0/1, cluster=0006, TID=87, reports=[Attribute Report: attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=false]]
2018-01-24 21:03:54.286 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.287 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:54.288 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 84182600000C8AA7: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false]
2018-01-24 21:03:54.290 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 84182600000C8AA7: Channel zigbee:device:3e5f29cf:84182600000c8aa7:84182600000C8AA7_3_switch_onoff updated to OFF
2018-01-24 21:03:54.487 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=5, reTx=false, data=56 90 3F 00 7E 04 04 01 06 00 01 03 40 11 00 00 56 56 00 00]
2018-01-24 21:03:54.489 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=86], messageTag=86, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:54.491 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=86], messageTag=86, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:54.492 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=1, destinationEndpoint=3, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=86], messageTag=86, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:54.493 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.494 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2018-01-24 21:03:54.501 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=5, reTx=false, data=56 90 45 00 04 01 06 00 03 01 40 00 00 00 21 FE A9 7E 04 FF FF 08 18 56 01 00 00 00 10 00]
2018-01-24 21:03:54.503 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=33], lastHopLqi=254, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 56 01 00 00 00 10 00]
2018-01-24 21:03:54.505 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=6, sourceEndpoint=3, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=33], lastHopLqi=254, lastHopRssi=-87, sender=1150, bindingIndex=255, addressIndex=255, messageContents=18 56 01 00 00 00 10 00]
2018-01-24 21:03:54.507 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=1150/3, destinationAddress=0/1, profile=0104, cluster=6, addressMode=null, radius=0, sequence=86, payload=18 56 01 00 00 00 10 00]
2018-01-24 21:03:54.508 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=86, commandId=1]
2018-01-24 21:03:54.510 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [On/Off: 1150/3 -> 0/1, cluster=0006, TID=56, records=[ReadAttributeStatusRecord [attributeDataType=BOOLEAN, attributeIdentifier=0, status=SUCCESS, attributeValue=false]]]
2018-01-24 21:03:54.512 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.513 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2018-01-24 21:03:54.516 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 84182600000C8AA7: Initializing channel zigbee:device:3e5f29cf:84182600000c8aa7:84182600000C8AA7_3_electrical_activepower with org.openhab.binding.zigbee.converter.ZigBeeConverterMeasurementPower@165ce22
2018-01-24 21:03:54.517 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=5]
2018-01-24 21:03:54.518 [DEBUG] [rter.ZigBeeConverterMeasurementPower] - 84182600000C8AA7: Initialising electrical measurement cluster
2018-01-24 21:03:54.520 [DEBUG] [converter.ZigBeeConverterSwitchOnoff] - 84182600000C8AA7: ZigBee attribute reports ZclAttribute [cluster=ON_OFF, id=0, name=OnOff, dataType=BOOLEAN, lastValue=false]
2018-01-24 21:03:54.521 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: BindRequest [0/0 -> 1150/0, cluster=0021, TID=5F, srcAddress=84182600000C8AA7, srcEndpoint=3, bindCluster=2820, dstAddrMode=3, dstAddress=000D6F000B1329F8, dstEndpoint=1]
2018-01-24 21:03:54.523 [DEBUG] [converter.ZigBeeBaseChannelConverter] - 84182600000C8AA7: Channel zigbee:device:3e5f29cf:84182600000c8aa7:84182600000C8AA7_3_switch_onoff updated to OFF
2018-01-24 21:03:54.524 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=1150/0, profile=0000, cluster=33, addressMode=DEVICE, radius=31, sequence=95, payload=00 A7 8A 0C 00 00 26 18 84 03 04 0B 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:54.525 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=1, reTx=true, data=57 00 34 00 85 23 00 00 01 00 00 00 40 11 00 00 57 57 05 00 85 23 01 00]
2018-01-24 21:03:54.526 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=1150, apsFrame=EmberApsFrame [profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=95], messageTag=95, messageContents=00 A7 8A 0C 00 00 26 18 84 03 04 0B 03 F8 29 13 0B 00 6F 0D 00 01]
2018-01-24 21:03:54.528 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:54.533 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.534 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.794 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:54.795 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=88], messageTag=88, messageContents=00 58 0C 00 00 0A]
2018-01-24 21:03:54.796 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=1, reTx=false, data=58 00 34 00 51 BF 04 01 00 03 01 02 40 11 00 00 58 58 06 00 58 0C 00 00 0A]
2018-01-24 21:03:54.804 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=57 80 34 00 57]
2018-01-24 21:03:54.805 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=87]
2018-01-24 21:03:54.806 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=87]
2018-01-24 21:03:54.806 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=87]
2018-01-24 21:03:54.807 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:54.807 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2018-01-24 21:03:55.008 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=57 90 3F 00 85 23 00 00 01 00 00 00 40 11 00 00 57 57 00 00]
2018-01-24 21:03:55.009 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=9093, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=87], messageTag=87, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:55.009 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=9093, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=87], messageTag=87, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:55.011 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=9093, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=87], messageTag=87, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:55.012 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.013 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2018-01-24 21:03:55.021 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=57 90 45 00 00 00 01 80 00 00 40 01 00 00 63 EF A4 85 23 FF FF 0E 00 00 C4 C6 1D 02 01 88 17 00 85 23 00 00]
2018-01-24 21:03:55.023 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32769, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=99], lastHopLqi=239, lastHopRssi=-92, sender=9093, bindingIndex=255, addressIndex=255, messageContents=00 00 C4 C6 1D 02 01 88 17 00 85 23 00 00]
2018-01-24 21:03:55.025 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0, clusterId=32769, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=99], lastHopLqi=239, lastHopRssi=-92, sender=9093, bindingIndex=255, addressIndex=255, messageContents=00 00 C4 C6 1D 02 01 88 17 00 85 23 00 00]
2018-01-24 21:03:55.027 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=9093/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=87, payload=00 00 C4 C6 1D 02 01 88 17 00 85 23 00 00]
2018-01-24 21:03:55.029 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [9093/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00178801021DC6C4, nwkAddrRemoteDev=9093, startIndex=0, nwkAddrAssocDevList=[]]
2018-01-24 21:03:55.032 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.041 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 9093: Ieee Address returned IeeeAddressResponse [9093/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00178801021DC6C4, nwkAddrRemoteDev=9093, startIndex=0, nwkAddrAssocDevList=[]]
2018-01-24 21:03:55.041 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2018-01-24 21:03:55.043 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00178801021DC6C4: Associated devices table unchanged
2018-01-24 21:03:55.043 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=6]
2018-01-24 21:03:55.045 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 9093: Discovery request IEEE_ADDRESS successful. Advanced to NODE_DESCRIPTOR.
2018-01-24 21:03:55.046 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=true, data=58 00 34 00 51 BF 04 01 00 03 01 02 40 11 00 00 58 58 06 00 58 0C 00 00 0A]
2018-01-24 21:03:55.046 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NodeDescriptorRequest [0/0 -> 9093/0, cluster=0002, TID=60, nwkAddrOfInterest=9093]
2018-01-24 21:03:55.047 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=9093/0, profile=0000, cluster=2, addressMode=DEVICE, radius=31, sequence=96, payload=00 85 23]
2018-01-24 21:03:55.049 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=9093, apsFrame=EmberApsFrame [profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=96], messageTag=96, messageContents=00 85 23]
2018-01-24 21:03:55.050 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 8
2018-01-24 21:03:55.056 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.057 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.233 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:55.235 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=18191, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=89], messageTag=89, messageContents=00 59 00 00 00]
2018-01-24 21:03:55.238 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=false, data=59 00 34 00 0F 47 04 01 00 03 01 02 40 11 00 00 59 59 05 00 59 00 00 00]
2018-01-24 21:03:55.257 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=58 80 34 00 58]
2018-01-24 21:03:55.259 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=88]
2018-01-24 21:03:55.260 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=88]
2018-01-24 21:03:55.262 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_SUCCESS, sequence=88]
2018-01-24 21:03:55.263 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.264 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]
2018-01-24 21:03:55.465 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=58 90 3F 00 51 BF 04 01 00 03 01 02 40 11 00 00 58 58 00 00]
2018-01-24 21:03:55.468 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=88], messageTag=88, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:55.469 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=88], messageTag=88, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:55.470 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_DIRECT, indexOrDestination=48977, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=1, destinationEndpoint=2, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=88], messageTag=88, status=EMBER_SUCCESS, messageContents=]
2018-01-24 21:03:55.472 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.474 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2018-01-24 21:03:55.480 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=58 90 45 00 04 01 00 03 02 01 40 01 00 00 9D F4 A4 51 BF FF FF 05 18 58 0B 0C 82]
2018-01-24 21:03:55.482 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=157], lastHopLqi=244, lastHopRssi=-92, sender=48977, bindingIndex=255, addressIndex=255, messageContents=18 58 0B 0C 82]
2018-01-24 21:03:55.484 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=260, clusterId=768, sourceEndpoint=2, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=157], lastHopLqi=244, lastHopRssi=-92, sender=48977, bindingIndex=255, addressIndex=255, messageContents=18 58 0B 0C 82]
2018-01-24 21:03:55.486 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=48977/2, destinationAddress=0/1, profile=0104, cluster=768, addressMode=null, radius=0, sequence=88, payload=18 58 0B 0C 82]
2018-01-24 21:03:55.487 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=88, commandId=11]
2018-01-24 21:03:55.489 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Color control: 48977/2 -> 0/1, cluster=0300, TID=58, commandIdentifier=12, statusCode=UNSUP_GENERAL_COMMAND]
2018-01-24 21:03:55.491 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2018-01-24 21:03:55.497 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2018-01-24 21:03:55.497 [WARN ] [.converter.ZigBeeConverterColorColor] - 00158D0000AF412C: Cannot determine whether device supports RGB color. Assuming it supports HUE/SAT