[Findit] Use step level analysis to exclude flaky test failures.

appengine/findit/waterfall/extract_signal_pipeline.py

 # Copyright 2015 The Chromium Authors. All rights reserved.
 # Use of this source code is governed by a BSD-style license that can be
 # found in the LICENSE file.
 
+import cStringIO
 import logging
+import json
 
 from google.appengine.api.urlfetch import ResponseTooLargeError
 
 from pipeline_utils.appengine_third_party_pipeline_src_pipeline import pipeline
 
 from common.http_client_appengine import HttpClientAppengine as HttpClient
 from model.wf_step import WfStep
 from waterfall import buildbot
 from waterfall import extractors
 from waterfall import lock_util
@@ skipping 22 matching lines @@
 
     lines = log_data.split('\n')
     size = 0
     for line_index in reversed(range(len(lines))):
       size += len(lines[line_index]) + 1
       if size > ExtractSignalPipeline.LOG_DATA_BYTE_LIMIT:
         return '\n'.join(lines[line_index + 1:])
     else:
       return log_data  # pragma: no cover - this won't be reached.
 
+  @staticmethod
+  def _GetReliableTestFailureLog(gtest_result):
+    """Analyze the archived gtest json results and extract reliable failures.
+
+    Args:
+      gtest_result (str): A JSON file for failed step log.
+
+    Returns:
+      A string contains the names of reliable test failures and related
+      log content.
+    """
+    step_failure_data = json.loads(gtest_result)
+    sio = cStringIO.StringIO()

[stgao, 2015/05/26 18:14:49]

This could be moved to after the check of 'invalid'.

[chanli, 2015/05/26 19:07:38]

Done.

+
+    if step_failure_data['gtest_results'] == 'invalid':  # pragma: no cover
+      return None
+
+    for iteration in step_failure_data['gtest_results']['per_iteration_data']:
+      for test_name in iteration.keys():
+        is_reliable_failure = True
+
+        for test_run in iteration[test_name]:
+          # We will ignore the test if some of the attempts were success.
+          if test_run['status'] == 'SUCCESS':
+            is_reliable_failure = False
+            break
+
+        if is_reliable_failure:  # all attempts failed
+          for test_run in iteration[test_name]:
+            sio.write("'%s': %s\n" % (test_name, test_run['output_snippet']))
+
+    failed_test_log = sio.getvalue()
+    sio.close()
+
+    return failed_test_log
 
   # Arguments number differs from overridden method - pylint: disable=W0221
   def run(self, failure_info):
     """
     Args:
       failure_info (dict): Output of pipeline DetectFirstFailurePipeline.run().
 
     Returns:
       A dict like below:
       {
         'step_name1': waterfall.failure_signal.FailureSignal.ToDict(),
         ...
       }
     """
     signals = {}
 
     master_name = failure_info['master_name']
     builder_name = failure_info['builder_name']
     build_number = failure_info['build_number']
     for step_name in failure_info.get('failed_steps', []):
       step = WfStep.Get(master_name, builder_name, build_number, step_name)
       if step and step.log_data:
-        stdio_log = step.log_data
+        failure_log = step.log_data
       else:
-        if not lock_util.WaitUntilDownloadAllowed(
-            master_name):  # pragma: no cover
-          raise pipeline.Retry('Failed to pull stdio of step %s of master %s'
-                               % (step_name, master_name))
+        # TODO: do test-level analysis instead of step-level.
+        gtest_result = buildbot.GetGtestResultLog(
+              master_name, builder_name, build_number, step_name)
+        if gtest_result:
+          failure_log = self._GetReliableTestFailureLog(gtest_result)

[stgao, 2015/05/26 18:14:49]

If the gtest_result is 'invalid', we should fallback to stdio instead.
However, the code here seems not like that.

[chanli, 2015/05/26 19:07:38]

Now we will both get None as return if we get 'invalid' as gtest_result, or if
all the failures are flaky.

I'm thinking maybe I can return different messages and only fallback to stdio if
we get 'invalid'?

[stgao, 2015/05/26 19:17:11]

Sounds good to me.

[chanli, 2015/05/26 23:33:41]

Done.

+        else:
+          if not lock_util.WaitUntilDownloadAllowed(
+              master_name):  # pragma: no cover
+            raise pipeline.Retry('Failed to pull log of step %s of master %s'
+                                 % (step_name, master_name))
+          try:
+            failure_log = buildbot.GetStepStdio(
+                master_name, builder_name, build_number, step_name,
+                self.HTTP_CLIENT)
+          except ResponseTooLargeError:  # pragma: no cover.
+            logging.exception(
+                'Log of step "%s" is too large for urlfetch.', step_name)
+            # If the stdio log of a step is too large, we don't want to pull it
+            # again in next run, because that might lead to DDoS to the master.
+            # TODO: Use archived stdio logs in Google Storage instead.
+            failure_log = 'Stdio log is too large for urlfetch.'
 
-        # TODO: do test-level analysis instead of step-level.
-        try:
-          stdio_log = buildbot.GetStepStdio(
-              master_name, builder_name, build_number, step_name,
-              self.HTTP_CLIENT)
-        except ResponseTooLargeError:  # pragma: no cover.
-          logging.exception(
-              'Log of step "%s" is too large for urlfetch.', step_name)
-          # If the stdio log of a step is too large, we don't want to pull it
-          # again in next run, because that might lead to DDoS to the master.
-          # TODO: Use archived stdio logs in Google Storage instead.
-          stdio_log = 'Stdio log is too large for urlfetch.'
+          if not failure_log:  # pragma: no cover
+            raise pipeline.Retry('Failed to pull stdio of step %s of master %s'
+                                 % (step_name, master_name))
 
-        if not stdio_log:  # pragma: no cover
-          raise pipeline.Retry('Failed to pull stdio of step %s of master %s'
-                               % (step_name, master_name))
-
-        # Save stdio in datastore and avoid downloading again during retry.
+        # Save step log in datastore and avoid downloading again during retry.
         if not step:  # pragma: no cover
           step = WfStep.Create(
               master_name, builder_name, build_number, step_name)
 
-        step.log_data = self._ExtractStorablePortionOfLog(stdio_log)
+        step.log_data = self._ExtractStorablePortionOfLog(failure_log)
+
         try:
           step.put()
         except Exception as e:  # pragma: no cover
-          # Sometimes, the stdio log is too large to save in datastore.
+          # Sometimes, the step log is too large to save in datastore.
           logging.exception(e)
 
       # TODO: save result in datastore?
       signals[step_name] = extractors.ExtractSignal(
-          master_name, builder_name, step_name, None, stdio_log).ToDict()
+          master_name, builder_name, step_name, None,
+          failure_log).ToDict()
 
     return signals