17:27:40.462 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=27 00 01 18 00]
17:27:40.489 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=27 80 01 18 00 02]
17:27:40.490 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=2, reTx=false, data=27 00 01 18 00]
17:27:40.490 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:40.490 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:40.491 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:27:40.853 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:40.854 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:40.854 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:40.854 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:27:40.855 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:40.931 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=27 90 01 9B 00 DF 5C 61 FE FF AC 33 BC 06]
17:27:40.932 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:40.933 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:40.933 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:27:40.933 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspZigbeeKeyEstablishmentHandler [networkId=0, partner=BC33ACFFFE615CDF, status=EMBER_EEPROM_MFG_VERSION_MISMATCH]
17:27:41.263 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.264 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.264 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.265 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:27:41.265 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.267 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.268 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.268 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.269 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:27:41.269 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.284 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.285 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.285 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.286 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:27:41.286 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.287 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.287 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.287 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.288 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:27:41.288 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.289 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.290 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.290 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.290 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:27:41.290 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.304 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.305 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.305 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.305 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:27:41.306 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.324 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.325 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.325 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.326 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:27:41.326 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.371 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.372 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.372 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.373 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:27:41.374 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.436 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E4 D5 01 00 00]
17:27:41.437 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.437 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.438 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:27:41.439 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=228, lastHopRssi=-43, relayList=0000]
17:27:41.439 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=27 90 01 59 00 B6 71 DF 5C 61 FE FF AC 33 BC E8 D6 01 00 00]
17:27:41.440 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.440 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.441 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:27:41.441 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=71B6, sourceEui=BC33ACFFFE615CDF, lastHopLqi=232, lastHopRssi=-42, relayList=0000]
17:27:41.463 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:41.464 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=28 00 01 18 00]
17:27:41.490 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=28 80 01 18 00 02]
17:27:41.490 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=7, reTx=false, data=28 00 01 18 00]
17:27:41.491 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.491 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.491 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:27:41.581 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=28 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 23 00 00 00]
17:27:41.582 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:41.582 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:41.583 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:27:41.583 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=23], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:27:41.584 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:27:41.585 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:27:42.465 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:42.466 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=29 00 01 18 00]
17:27:42.492 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=29 80 01 18 00 02]
17:27:42.493 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=1, reTx=false, data=29 00 01 18 00]
17:27:42.495 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:42.495 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:42.495 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:27:43.291 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=29 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 26 00 00 00]
17:27:43.292 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:43.292 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:43.293 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:27:43.293 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=26], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:27:43.294 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:27:43.295 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:27:43.467 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:43.468 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=2A 00 01 18 00]
17:27:43.521 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=5, reTx=false, data=2A 80 01 18 00 02]
17:27:43.522 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=3, reTx=false, data=2A 00 01 18 00]
17:27:43.523 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:43.523 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:43.524 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:27:44.469 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:44.470 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=2B 00 01 18 00]
17:27:44.497 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=2B 80 01 18 00 02]
17:27:44.498 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=4, reTx=false, data=2B 00 01 18 00]
17:27:44.498 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:44.498 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:44.499 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:27:45.161 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=2B 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 27 00 00 00]
17:27:45.162 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:45.162 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:45.162 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:27:45.163 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=27], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:27:45.163 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:27:45.164 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:27:45.471 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:45.472 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=2C 00 01 18 00]
17:27:45.499 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=2C 80 01 18 00 02]
17:27:45.500 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=6, reTx=false, data=2C 00 01 18 00]
17:27:45.501 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:45.501 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:45.501 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:27:46.474 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:46.475 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=2D 00 01 18 00]
17:27:46.501 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=2D 80 01 18 00 02]
17:27:46.502 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=7, reTx=false, data=2D 00 01 18 00]
17:27:46.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:46.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:46.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:27:47.461 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=2D 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 28 00 00 00]
17:27:47.461 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:47.461 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:47.462 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:27:47.462 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=28], messageTag=00, status=EMBER_SUCCESS, messageContents=]
17:27:47.462 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
17:27:47.463 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
17:27:47.476 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:47.476 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=2E 00 01 18 00]
17:27:47.502 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=2E 80 01 18 00 02]
17:27:47.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=1, reTx=false, data=2E 00 01 18 00]
17:27:47.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:47.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:47.504 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:27:48.477 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:48.478 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=2F 00 01 18 00]
17:27:48.505 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=2F 80 01 18 00 02]
17:27:48.505 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=2, reTx=false, data=2F 00 01 18 00]
17:27:48.506 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:48.506 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:48.506 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:27:49.479 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
17:27:49.480 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=30 00 01 18 00]
17:27:49.531 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=false, data=30 80 01 18 00 02]
17:27:49.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=3, reTx=false, data=30 00 01 18 00]
17:27:49.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
17:27:49.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
17:27:49.533 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]