#!/usr/bin/env python # Copyright (C) 2010 Google Inc. All rights reserved. # # Redistribution and use in source and binary forms, with or without # modification, are permitted provided that the following conditions are # met: # # * Redistributions of source code must retain the above copyright # notice, this list of conditions and the following disclaimer. # * Redistributions in binary form must reproduce the above # copyright notice, this list of conditions and the following disclaimer # in the documentation and/or other materials provided with the # distribution. # * Neither the name of Google Inc. nor the names of its # contributors may be used to endorse or promote products derived from # this software without specific prior written permission. # # THIS SOFTWARE IS PROVIDED BY THE COPYRIGHT HOLDERS AND CONTRIBUTORS # "AS IS" AND ANY EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT # LIMITED TO, THE IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS FOR # A PARTICULAR PURPOSE ARE DISCLAIMED. IN NO EVENT SHALL THE COPYRIGHT # OWNER OR CONTRIBUTORS BE LIABLE FOR ANY DIRECT, INDIRECT, INCIDENTAL, # SPECIAL, EXEMPLARY, OR CONSEQUENTIAL DAMAGES (INCLUDING, BUT NOT # LIMITED TO, PROCUREMENT OF SUBSTITUTE GOODS OR SERVICES; LOSS OF USE, # DATA, OR PROFITS; OR BUSINESS INTERRUPTION) HOWEVER CAUSED AND ON ANY # THEORY OF LIABILITY, WHETHER IN CONTRACT, STRICT LIABILITY, OR TORT # (INCLUDING NEGLIGENCE OR OTHERWISE) ARISING IN ANY WAY OUT OF THE USE # OF THIS SOFTWARE, EVEN IF ADVISED OF THE POSSIBILITY OF SUCH DAMAGE. """Run layout tests. This is a port of the existing webkit test script run-webkit-tests. The TestRunner class runs a series of tests (TestType interface) against a set of test files. If a test file fails a TestType, it returns a list TestFailure objects to the TestRunner. The TestRunner then aggregates the TestFailures to create a final report. This script reads several files, if they exist in the test_lists subdirectory next to this script itself. Each should contain a list of paths to individual tests or entire subdirectories of tests, relative to the outermost test directory. Entire lines starting with '//' (comments) will be ignored. For details of the files' contents and purposes, see test_lists/README. """ from __future__ import with_statement import codecs import errno import glob import logging import math import optparse import os import platform import Queue import random import re import shutil import sys import time import traceback from layout_package import dump_render_tree_thread from layout_package import json_layout_results_generator from layout_package import printing from layout_package import test_expectations from layout_package import test_failures from layout_package import test_files from layout_package import test_results_uploader from test_types import fuzzy_image_diff from test_types import image_diff from test_types import text_diff from test_types import test_type_base from webkitpy.common.system.executive import Executive from webkitpy.thirdparty import simplejson import port _log = logging.getLogger("webkitpy.layout_tests.run_webkit_tests") # Builder base URL where we have the archived test results. BUILDER_BASE_URL = "http://build.chromium.org/buildbot/layout_test_results/" TestExpectationsFile = test_expectations.TestExpectationsFile class TestInfo: """Groups information about a test for easy passing of data.""" def __init__(self, port, filename, timeout): """Generates the URI and stores the filename and timeout for this test. Args: filename: Full path to the test. timeout: Timeout for running the test in TestShell. """ self.filename = filename self.uri = port.filename_to_uri(filename) self.timeout = timeout # FIXME: Confusing that the file is .checksum and we call it "hash" self._expected_hash_path = port.expected_filename(filename, '.checksum') self._have_read_expected_hash = False self._image_hash = None def _read_image_hash(self): try: with codecs.open(self._expected_hash_path, "r", "ascii") as hash_file: return hash_file.read() except IOError, e: if errno.ENOENT != e.errno: raise def image_hash(self): # Read the image_hash lazily to reduce startup time. # This class is accessed across threads, but only one thread should # ever be dealing with any given TestInfo so no locking is needed. if not self._have_read_expected_hash: self._have_read_expected_hash = True self._image_hash = self._read_image_hash() return self._image_hash class ResultSummary(object): """A class for partitioning the test results we get into buckets. This class is basically a glorified struct and it's private to this file so we don't bother with any information hiding.""" def __init__(self, expectations, test_files): self.total = len(test_files) self.remaining = self.total self.expectations = expectations self.expected = 0 self.unexpected = 0 self.tests_by_expectation = {} self.tests_by_timeline = {} self.results = {} self.unexpected_results = {} self.failures = {} self.tests_by_expectation[test_expectations.SKIP] = set() for expectation in TestExpectationsFile.EXPECTATIONS.values(): self.tests_by_expectation[expectation] = set() for timeline in TestExpectationsFile.TIMELINES.values(): self.tests_by_timeline[timeline] = ( expectations.get_tests_with_timeline(timeline)) def add(self, result, expected): """Add a TestResult into the appropriate bin. Args: result: TestResult from dump_render_tree_thread. expected: whether the result was what we expected it to be. """ self.tests_by_expectation[result.type].add(result.filename) self.results[result.filename] = result self.remaining -= 1 if len(result.failures): self.failures[result.filename] = result.failures if expected: self.expected += 1 else: self.unexpected_results[result.filename] = result.type self.unexpected += 1 def summarize_unexpected_results(port_obj, expectations, result_summary, retry_summary): """Summarize any unexpected results as a dict. FIXME: split this data structure into a separate class? Args: port_obj: interface to port-specific hooks expectations: test_expectations.TestExpectations object result_summary: summary object from initial test runs retry_summary: summary object from final test run of retried tests Returns: A dictionary containing a summary of the unexpected results from the run, with the following fields: 'version': a version indicator (1 in this version) 'fixable': # of fixable tests (NOW - PASS) 'skipped': # of skipped tests (NOW & SKIPPED) 'num_regressions': # of non-flaky failures 'num_flaky': # of flaky failures 'num_passes': # of unexpected passes 'tests': a dict of tests -> {'expected': '...', 'actual': '...'} """ results = {} results['version'] = 1 tbe = result_summary.tests_by_expectation tbt = result_summary.tests_by_timeline results['fixable'] = len(tbt[test_expectations.NOW] - tbe[test_expectations.PASS]) results['skipped'] = len(tbt[test_expectations.NOW] & tbe[test_expectations.SKIP]) num_passes = 0 num_flaky = 0 num_regressions = 0 keywords = {} for k, v in TestExpectationsFile.EXPECTATIONS.iteritems(): keywords[v] = k.upper() tests = {} for filename, result in result_summary.unexpected_results.iteritems(): # Note that if a test crashed in the original run, we ignore # whether or not it crashed when we retried it (if we retried it), # and always consider the result not flaky. test = port_obj.relative_test_filename(filename) expected = expectations.get_expectations_string(filename) actual = [keywords[result]] if result == test_expectations.PASS: num_passes += 1 elif result == test_expectations.CRASH: num_regressions += 1 else: if filename not in retry_summary.unexpected_results: actual.extend(expectations.get_expectations_string( filename).split(" ")) num_flaky += 1 else: retry_result = retry_summary.unexpected_results[filename] if result != retry_result: actual.append(keywords[retry_result]) num_flaky += 1 else: num_regressions += 1 tests[test] = {} tests[test]['expected'] = expected tests[test]['actual'] = " ".join(actual) results['tests'] = tests results['num_passes'] = num_passes results['num_flaky'] = num_flaky results['num_regressions'] = num_regressions return results class TestRunner: """A class for managing running a series of tests on a series of layout test files.""" HTTP_SUBDIR = os.sep.join(['', 'http', '']) WEBSOCKET_SUBDIR = os.sep.join(['', 'websocket', '']) # The per-test timeout in milliseconds, if no --time-out-ms option was # given to run_webkit_tests. This should correspond to the default timeout # in DumpRenderTree. DEFAULT_TEST_TIMEOUT_MS = 6 * 1000 def __init__(self, port, options, printer): """Initialize test runner data structures. Args: port: an object implementing port-specific options: a dictionary of command line options printer: a Printer object to record updates to. """ self._port = port self._options = options self._printer = printer # disable wss server. need to install pyOpenSSL on buildbots. # self._websocket_secure_server = websocket_server.PyWebSocket( # options.results_directory, use_tls=True, port=9323) # a list of TestType objects self._test_types = [] # a set of test files, and the same tests as a list self._test_files = set() self._test_files_list = None self._result_queue = Queue.Queue() self._retrying = False # Hack for dumping threads on the bots self._last_thread_dump = None def __del__(self): _log.debug("flushing stdout") sys.stdout.flush() _log.debug("flushing stderr") sys.stderr.flush() _log.debug("stopping http server") self._port.stop_http_server() _log.debug("stopping websocket server") self._port.stop_websocket_server() def gather_file_paths(self, paths): """Find all the files to test. Args: paths: a list of globs to use instead of the defaults.""" self._test_files = test_files.gather_test_files(self._port, paths) def parse_expectations(self, test_platform_name, is_debug_mode): """Parse the expectations from the test_list files and return a data structure holding them. Throws an error if the test_list files have invalid syntax.""" if self._options.lint_test_files: test_files = None else: test_files = self._test_files try: expectations_str = self._port.test_expectations() overrides_str = self._port.test_expectations_overrides() self._expectations = test_expectations.TestExpectations( self._port, test_files, expectations_str, test_platform_name, is_debug_mode, self._options.lint_test_files, tests_are_present=True, overrides=overrides_str) return self._expectations except SyntaxError, err: if self._options.lint_test_files: print str(err) else: raise err def prepare_lists_and_print_output(self): """Create appropriate subsets of test lists and returns a ResultSummary object. Also prints expected test counts. """ # Remove skipped - both fixable and ignored - files from the # top-level list of files to test. num_all_test_files = len(self._test_files) self._printer.print_expected("Found: %d tests" % (len(self._test_files))) if not num_all_test_files: _log.critical("No tests to run.") sys.exit(1) skipped = set() if num_all_test_files > 1 and not self._options.force: skipped = self._expectations.get_tests_with_result_type( test_expectations.SKIP) self._test_files -= skipped # Create a sorted list of test files so the subset chunk, # if used, contains alphabetically consecutive tests. self._test_files_list = list(self._test_files) if self._options.randomize_order: random.shuffle(self._test_files_list) else: self._test_files_list.sort() # If the user specifies they just want to run a subset of the tests, # just grab a subset of the non-skipped tests. if self._options.run_chunk or self._options.run_part: chunk_value = self._options.run_chunk or self._options.run_part test_files = self._test_files_list try: (chunk_num, chunk_len) = chunk_value.split(":") chunk_num = int(chunk_num) assert(chunk_num >= 0) test_size = int(chunk_len) assert(test_size > 0) except: _log.critical("invalid chunk '%s'" % chunk_value) sys.exit(1) # Get the number of tests num_tests = len(test_files) # Get the start offset of the slice. if self._options.run_chunk: chunk_len = test_size # In this case chunk_num can be really large. We need # to make the slave fit in the current number of tests. slice_start = (chunk_num * chunk_len) % num_tests else: # Validate the data. assert(test_size <= num_tests) assert(chunk_num <= test_size) # To count the chunk_len, and make sure we don't skip # some tests, we round to the next value that fits exactly # all the parts. rounded_tests = num_tests if rounded_tests % test_size != 0: rounded_tests = (num_tests + test_size - (num_tests % test_size)) chunk_len = rounded_tests / test_size slice_start = chunk_len * (chunk_num - 1) # It does not mind if we go over test_size. # Get the end offset of the slice. slice_end = min(num_tests, slice_start + chunk_len) files = test_files[slice_start:slice_end] tests_run_msg = 'Running: %d tests (chunk slice [%d:%d] of %d)' % ( (slice_end - slice_start), slice_start, slice_end, num_tests) self._printer.print_expected(tests_run_msg) # If we reached the end and we don't have enough tests, we run some # from the beginning. if (self._options.run_chunk and (slice_end - slice_start < chunk_len)): extra = 1 + chunk_len - (slice_end - slice_start) extra_msg = (' last chunk is partial, appending [0:%d]' % extra) self._printer.print_expected(extra_msg) tests_run_msg += "\n" + extra_msg files.extend(test_files[0:extra]) tests_run_filename = os.path.join(self._options.results_directory, "tests_run.txt") with codecs.open(tests_run_filename, "w", "utf-8") as file: file.write(tests_run_msg + "\n") len_skip_chunk = int(len(files) * len(skipped) / float(len(self._test_files))) skip_chunk_list = list(skipped)[0:len_skip_chunk] skip_chunk = set(skip_chunk_list) # Update expectations so that the stats are calculated correctly. # We need to pass a list that includes the right # of skipped files # to ParseExpectations so that ResultSummary() will get the correct # stats. So, we add in the subset of skipped files, and then # subtract them back out. self._test_files_list = files + skip_chunk_list self._test_files = set(self._test_files_list) self._expectations = self.parse_expectations( self._port.test_platform_name(), self._options.configuration == 'Debug') self._test_files = set(files) self._test_files_list = files else: skip_chunk = skipped result_summary = ResultSummary(self._expectations, self._test_files | skip_chunk) self._print_expected_results_of_type(result_summary, test_expectations.PASS, "passes") self._print_expected_results_of_type(result_summary, test_expectations.FAIL, "failures") self._print_expected_results_of_type(result_summary, test_expectations.FLAKY, "flaky") self._print_expected_results_of_type(result_summary, test_expectations.SKIP, "skipped") if self._options.force: self._printer.print_expected('Running all tests, including ' 'skips (--force)') else: # Note that we don't actually run the skipped tests (they were # subtracted out of self._test_files, above), but we stub out the # results here so the statistics can remain accurate. for test in skip_chunk: result = dump_render_tree_thread.TestResult(test, failures=[], test_run_time=0, total_time_for_all_diffs=0, time_for_diffs=0) result.type = test_expectations.SKIP result_summary.add(result, expected=True) self._printer.print_expected('') return result_summary def add_test_type(self, test_type): """Add a TestType to the TestRunner.""" self._test_types.append(test_type) def _get_dir_for_test_file(self, test_file): """Returns the highest-level directory by which to shard the given test file.""" index = test_file.rfind(os.sep + 'LayoutTests' + os.sep) test_file = test_file[index + len('LayoutTests/'):] test_file_parts = test_file.split(os.sep, 1) directory = test_file_parts[0] test_file = test_file_parts[1] # The http tests are very stable on mac/linux. # TODO(ojan): Make the http server on Windows be apache so we can # turn shard the http tests there as well. Switching to apache is # what made them stable on linux/mac. return_value = directory while ((directory != 'http' or sys.platform in ('darwin', 'linux2')) and test_file.find(os.sep) >= 0): test_file_parts = test_file.split(os.sep, 1) directory = test_file_parts[0] return_value = os.path.join(return_value, directory) test_file = test_file_parts[1] return return_value def _get_test_info_for_file(self, test_file): """Returns the appropriate TestInfo object for the file. Mostly this is used for looking up the timeout value (in ms) to use for the given test.""" if self._expectations.has_modifier(test_file, test_expectations.SLOW): return TestInfo(self._port, test_file, self._options.slow_time_out_ms) return TestInfo(self._port, test_file, self._options.time_out_ms) def _get_test_file_queue(self, test_files): """Create the thread safe queue of lists of (test filenames, test URIs) tuples. Each TestShellThread pulls a list from this queue and runs those tests in order before grabbing the next available list. Shard the lists by directory. This helps ensure that tests that depend on each other (aka bad tests!) continue to run together as most cross-tests dependencies tend to occur within the same directory. Return: The Queue of lists of TestInfo objects. """ if (self._options.experimental_fully_parallel or self._is_single_threaded()): filename_queue = Queue.Queue() for test_file in test_files: filename_queue.put( ('.', [self._get_test_info_for_file(test_file)])) return filename_queue tests_by_dir = {} for test_file in test_files: directory = self._get_dir_for_test_file(test_file) tests_by_dir.setdefault(directory, []) tests_by_dir[directory].append( self._get_test_info_for_file(test_file)) # Sort by the number of tests in the dir so that the ones with the # most tests get run first in order to maximize parallelization. # Number of tests is a good enough, but not perfect, approximation # of how long that set of tests will take to run. We can't just use # a PriorityQueue until we move # to Python 2.6. test_lists = [] http_tests = None for directory in tests_by_dir: test_list = tests_by_dir[directory] # Keep the tests in alphabetical order. # TODO: Remove once tests are fixed so they can be run in any # order. test_list.reverse() test_list_tuple = (directory, test_list) if directory == 'LayoutTests' + os.sep + 'http': http_tests = test_list_tuple else: test_lists.append(test_list_tuple) test_lists.sort(lambda a, b: cmp(len(b[1]), len(a[1]))) # Put the http tests first. There are only a couple hundred of them, # but each http test takes a very long time to run, so sorting by the # number of tests doesn't accurately capture how long they take to run. if http_tests: test_lists.insert(0, http_tests) filename_queue = Queue.Queue() for item in test_lists: filename_queue.put(item) return filename_queue def _get_dump_render_tree_args(self, index): """Returns the tuple of arguments for tests and for DumpRenderTree.""" shell_args = [] test_args = test_type_base.TestArguments() png_path = None if self._options.pixel_tests: png_path = os.path.join(self._options.results_directory, "png_result%s.png" % index) shell_args.append("--pixel-tests=" + png_path) test_args.png_path = png_path test_args.new_baseline = self._options.new_baseline test_args.show_sources = self._options.sources if self._options.startup_dialog: shell_args.append('--testshell-startup-dialog') if self._options.gp_fault_error_box: shell_args.append('--gp-fault-error-box') return test_args, png_path, shell_args def _contains_tests(self, subdir): for test_file in self._test_files: if test_file.find(subdir) >= 0: return True return False def _instantiate_dump_render_tree_threads(self, test_files, result_summary): """Instantitates and starts the TestShellThread(s). Return: The list of threads. """ filename_queue = self._get_test_file_queue(test_files) # Instantiate TestShellThreads and start them. threads = [] for i in xrange(int(self._options.child_processes)): # Create separate TestTypes instances for each thread. test_types = [] for test_type in self._test_types: test_types.append(test_type(self._port, self._options.results_directory)) test_args, png_path, shell_args = \ self._get_dump_render_tree_args(i) thread = dump_render_tree_thread.TestShellThread(self._port, filename_queue, self._result_queue, test_types, test_args, png_path, shell_args, self._options) if self._is_single_threaded(): thread.run_in_main_thread(self, result_summary) else: thread.start() threads.append(thread) return threads def _is_single_threaded(self): """Returns whether we should run all the tests in the main thread.""" return int(self._options.child_processes) == 1 def _dump_thread_states(self): for thread_id, stack in sys._current_frames().items(): # FIXME: Python 2.6 has thread.ident which we could # use to map from thread_id back to thread.name print "\n# Thread: %d" % thread_id for filename, lineno, name, line in traceback.extract_stack(stack): print 'File: "%s", line %d, in %s' % (filename, lineno, name) if line: print " %s" % (line.strip()) def _dump_thread_states_if_necessary(self): # HACK: Dump thread states every minute to figure out what's # hanging on the bots. if not self._options.verbose: return dump_threads_every = 60 # Dump every minute if not self._last_thread_dump: self._last_thread_dump = time.time() time_since_last_dump = time.time() - self._last_thread_dump if time_since_last_dump > dump_threads_every: self._dump_thread_states() self._last_thread_dump = time.time() def _run_tests(self, file_list, result_summary): """Runs the tests in the file_list. Return: A tuple (failures, thread_timings, test_timings, individual_test_timings) failures is a map from test to list of failure types thread_timings is a list of dicts with the total runtime of each thread with 'name', 'num_tests', 'total_time' properties test_timings is a list of timings for each sharded subdirectory of the form [time, directory_name, num_tests] individual_test_timings is a list of run times for each test in the form {filename:filename, test_run_time:test_run_time} result_summary: summary object to populate with the results """ # FIXME: We should use webkitpy.tool.grammar.pluralize here. plural = "" if self._options.child_processes > 1: plural = "s" self._printer.print_update('Starting %s%s ...' % (self._port.driver_name(), plural)) threads = self._instantiate_dump_render_tree_threads(file_list, result_summary) self._printer.print_update("Starting testing ...") # Wait for the threads to finish and collect test failures. failures = {} test_timings = {} individual_test_timings = [] thread_timings = [] try: # Loop through all the threads waiting for them to finish. for thread in threads: # FIXME: We'll end up waiting on the first thread the whole # time. That means we won't notice exceptions on other # threads until the first one exits. # We should instead while True: in the outer loop # and then loop through threads joining and checking # isAlive and get_exception_info. Exiting on any exception. while thread.isAlive(): # Wake the main thread every 0.1 seconds so we # can call update_summary in a timely fashion. thread.join(0.1) # HACK: Used for debugging threads on the bots. self._dump_thread_states_if_necessary() self.update_summary(result_summary) # This thread is done, save off the timing information. thread_timings.append({'name': thread.getName(), 'num_tests': thread.get_num_tests(), 'total_time': thread.get_total_time()}) test_timings.update(thread.get_directory_timing_stats()) individual_test_timings.extend(thread.get_test_results()) except KeyboardInterrupt: for thread in threads: thread.cancel() raise for thread in threads: # Check whether a TestShellThread died before normal completion. exception_info = thread.get_exception_info() if exception_info is not None: # Re-raise the thread's exception here to make it clear that # testing was aborted. Otherwise, the tests that did not run # would be assumed to have passed. raise exception_info[0], exception_info[1], exception_info[2] # FIXME: This update_summary call seems unecessary. # Calls are already made right after join() above, # as well as from the individual threads themselves. self.update_summary(result_summary) return (thread_timings, test_timings, individual_test_timings) def needs_http(self): """Returns whether the test runner needs an HTTP server.""" return self._contains_tests(self.HTTP_SUBDIR) def run(self, result_summary): """Run all our tests on all our test files. For each test file, we run each test type. If there are any failures, we collect them for reporting. Args: result_summary: a summary object tracking the test results. Return: The number of unexpected results (0 == success) """ if not self._test_files: return 0 start_time = time.time() if self.needs_http(): self._printer.print_update('Starting HTTP server ...') self._port.start_http_server() if self._contains_tests(self.WEBSOCKET_SUBDIR): self._printer.print_update('Starting WebSocket server ...') self._port.start_websocket_server() # self._websocket_secure_server.Start() thread_timings, test_timings, individual_test_timings = ( self._run_tests(self._test_files_list, result_summary)) # We exclude the crashes from the list of results to retry, because # we want to treat even a potentially flaky crash as an error. failures = self._get_failures(result_summary, include_crashes=False) retry_summary = result_summary while (len(failures) and self._options.retry_failures and not self._retrying): _log.info('') _log.info("Retrying %d unexpected failure(s) ..." % len(failures)) _log.info('') self._retrying = True retry_summary = ResultSummary(self._expectations, failures.keys()) self._run_tests(failures.keys(), retry_summary) failures = self._get_failures(retry_summary, include_crashes=True) end_time = time.time() self._print_timing_statistics(end_time - start_time, thread_timings, test_timings, individual_test_timings, result_summary) self._print_result_summary(result_summary) sys.stdout.flush() sys.stderr.flush() self._printer.print_one_line_summary(result_summary.total, result_summary.expected) unexpected_results = summarize_unexpected_results(self._port, self._expectations, result_summary, retry_summary) self._printer.print_unexpected_results(unexpected_results) # Write the same data to log files. self._write_json_files(unexpected_results, result_summary, individual_test_timings) # Upload generated JSON files to appengine server. self._upload_json_files() # Write the summary to disk (results.html) and display it if requested. wrote_results = self._write_results_html_file(result_summary) if self._options.show_results and wrote_results: self._show_results_html_file() # Ignore flaky failures and unexpected passes so we don't turn the # bot red for those. return unexpected_results['num_regressions'] def update_summary(self, result_summary): """Update the summary and print results with any completed tests.""" while True: try: result = self._result_queue.get_nowait() except Queue.Empty: return expected = self._expectations.matches_an_expected_result( result.filename, result.type, self._options.pixel_tests) result_summary.add(result, expected) exp_str = self._expectations.get_expectations_string( result.filename) got_str = self._expectations.expectation_to_string(result.type) self._printer.print_test_result(result, expected, exp_str, got_str) self._printer.print_progress(result_summary, self._retrying, self._test_files_list) def _get_failures(self, result_summary, include_crashes): """Filters a dict of results and returns only the failures. Args: result_summary: the results of the test run include_crashes: whether crashes are included in the output. We use False when finding the list of failures to retry to see if the results were flaky. Although the crashes may also be flaky, we treat them as if they aren't so that they're not ignored. Returns: a dict of files -> results """ failed_results = {} for test, result in result_summary.unexpected_results.iteritems(): if (result == test_expectations.PASS or result == test_expectations.CRASH and not include_crashes): continue failed_results[test] = result return failed_results def _write_json_files(self, unexpected_results, result_summary, individual_test_timings): """Writes the results of the test run as JSON files into the results dir. There are three different files written into the results dir: unexpected_results.json: A short list of any unexpected results. This is used by the buildbots to display results. expectations.json: This is used by the flakiness dashboard. results.json: A full list of the results - used by the flakiness dashboard and the aggregate results dashboard. Args: unexpected_results: dict of unexpected results result_summary: full summary object individual_test_timings: list of test times (used by the flakiness dashboard). """ results_directory = self._options.results_directory _log.debug("Writing JSON files in %s." % results_directory) unexpected_json_path = os.path.join(results_directory, "unexpected_results.json") with codecs.open(unexpected_json_path, "w", "utf-8") as file: simplejson.dump(unexpected_results, file, sort_keys=True, indent=2) # Write a json file of the test_expectations.txt file for the layout # tests dashboard. expectations_path = os.path.join(results_directory, "expectations.json") expectations_json = \ self._expectations.get_expectations_json_for_all_platforms() with codecs.open(expectations_path, "w", "utf-8") as file: file.write(u"ADD_EXPECTATIONS(%s);" % expectations_json) json_layout_results_generator.JSONLayoutResultsGenerator( self._port, self._options.builder_name, self._options.build_name, self._options.build_number, self._options.results_directory, BUILDER_BASE_URL, individual_test_timings, self._expectations, result_summary, self._test_files_list) _log.debug("Finished writing JSON files.") def _upload_json_files(self): if not self._options.test_results_server: return _log.info("Uploading JSON files for builder: %s", self._options.builder_name) attrs = [('builder', self._options.builder_name)] json_files = ["expectations.json", "results.json"] files = [(file, os.path.join(self._options.results_directory, file)) for file in json_files] uploader = test_results_uploader.TestResultsUploader( self._options.test_results_server) try: # Set uploading timeout in case appengine server is having problem. # 120 seconds are more than enough to upload test results. uploader.upload(attrs, files, 120) except Exception, err: _log.error("Upload failed: %s" % err) return _log.info("JSON files uploaded.") def _print_expected_results_of_type(self, result_summary, result_type, result_type_str): """Print the number of the tests in a given result class. Args: result_summary - the object containing all the results to report on result_type - the particular result type to report in the summary. result_type_str - a string description of the result_type. """ tests = self._expectations.get_tests_with_result_type(result_type) now = result_summary.tests_by_timeline[test_expectations.NOW] wontfix = result_summary.tests_by_timeline[test_expectations.WONTFIX] defer = result_summary.tests_by_timeline[test_expectations.DEFER] # We use a fancy format string in order to print the data out in a # nicely-aligned table. fmtstr = ("Expect: %%5d %%-8s (%%%dd now, %%%dd defer, %%%dd wontfix)" % (self._num_digits(now), self._num_digits(defer), self._num_digits(wontfix))) self._printer.print_expected(fmtstr % (len(tests), result_type_str, len(tests & now), len(tests & defer), len(tests & wontfix))) def _num_digits(self, num): """Returns the number of digits needed to represent the length of a sequence.""" ndigits = 1 if len(num): ndigits = int(math.log10(len(num))) + 1 return ndigits def _print_timing_statistics(self, total_time, thread_timings, directory_test_timings, individual_test_timings, result_summary): """Record timing-specific information for the test run. Args: total_time: total elapsed time (in seconds) for the test run thread_timings: wall clock time each thread ran for directory_test_timings: timing by directory individual_test_timings: timing by file result_summary: summary object for the test run """ self._printer.print_timing("Test timing:") self._printer.print_timing(" %6.2f total testing time" % total_time) self._printer.print_timing("") self._printer.print_timing("Thread timing:") cuml_time = 0 for t in thread_timings: self._printer.print_timing(" %10s: %5d tests, %6.2f secs" % (t['name'], t['num_tests'], t['total_time'])) cuml_time += t['total_time'] self._printer.print_timing(" %6.2f cumulative, %6.2f optimal" % (cuml_time, cuml_time / int(self._options.child_processes))) self._printer.print_timing("") self._print_aggregate_test_statistics(individual_test_timings) self._print_individual_test_times(individual_test_timings, result_summary) self._print_directory_timings(directory_test_timings) def _print_aggregate_test_statistics(self, individual_test_timings): """Prints aggregate statistics (e.g. median, mean, etc.) for all tests. Args: individual_test_timings: List of dump_render_tree_thread.TestStats for all tests. """ test_types = [] # Unit tests don't actually produce any timings. if individual_test_timings: test_types = individual_test_timings[0].time_for_diffs.keys() times_for_dump_render_tree = [] times_for_diff_processing = [] times_per_test_type = {} for test_type in test_types: times_per_test_type[test_type] = [] for test_stats in individual_test_timings: times_for_dump_render_tree.append(test_stats.test_run_time) times_for_diff_processing.append( test_stats.total_time_for_all_diffs) time_for_diffs = test_stats.time_for_diffs for test_type in test_types: times_per_test_type[test_type].append( time_for_diffs[test_type]) self._print_statistics_for_test_timings( "PER TEST TIME IN TESTSHELL (seconds):", times_for_dump_render_tree) self._print_statistics_for_test_timings( "PER TEST DIFF PROCESSING TIMES (seconds):", times_for_diff_processing) for test_type in test_types: self._print_statistics_for_test_timings( "PER TEST TIMES BY TEST TYPE: %s" % test_type, times_per_test_type[test_type]) def _print_individual_test_times(self, individual_test_timings, result_summary): """Prints the run times for slow, timeout and crash tests. Args: individual_test_timings: List of dump_render_tree_thread.TestStats for all tests. result_summary: summary object for test run """ # Reverse-sort by the time spent in DumpRenderTree. individual_test_timings.sort(lambda a, b: cmp(b.test_run_time, a.test_run_time)) num_printed = 0 slow_tests = [] timeout_or_crash_tests = [] unexpected_slow_tests = [] for test_tuple in individual_test_timings: filename = test_tuple.filename is_timeout_crash_or_slow = False if self._expectations.has_modifier(filename, test_expectations.SLOW): is_timeout_crash_or_slow = True slow_tests.append(test_tuple) if filename in result_summary.failures: result = result_summary.results[filename].type if (result == test_expectations.TIMEOUT or result == test_expectations.CRASH): is_timeout_crash_or_slow = True timeout_or_crash_tests.append(test_tuple) if (not is_timeout_crash_or_slow and num_printed < printing.NUM_SLOW_TESTS_TO_LOG): num_printed = num_printed + 1 unexpected_slow_tests.append(test_tuple) self._printer.print_timing("") self._print_test_list_timing("%s slowest tests that are not " "marked as SLOW and did not timeout/crash:" % printing.NUM_SLOW_TESTS_TO_LOG, unexpected_slow_tests) self._printer.print_timing("") self._print_test_list_timing("Tests marked as SLOW:", slow_tests) self._printer.print_timing("") self._print_test_list_timing("Tests that timed out or crashed:", timeout_or_crash_tests) self._printer.print_timing("") def _print_test_list_timing(self, title, test_list): """Print timing info for each test. Args: title: section heading test_list: tests that fall in this section """ if self._printer.disabled('slowest'): return self._printer.print_timing(title) for test_tuple in test_list: filename = test_tuple.filename[len( self._port.layout_tests_dir()) + 1:] filename = filename.replace('\\', '/') test_run_time = round(test_tuple.test_run_time, 1) self._printer.print_timing(" %s took %s seconds" % (filename, test_run_time)) def _print_directory_timings(self, directory_test_timings): """Print timing info by directory for any directories that take > 10 seconds to run. Args: directory_test_timing: time info for each directory """ timings = [] for directory in directory_test_timings: num_tests, time_for_directory = directory_test_timings[directory] timings.append((round(time_for_directory, 1), directory, num_tests)) timings.sort() self._printer.print_timing("Time to process slowest subdirectories:") min_seconds_to_print = 10 for timing in timings: if timing[0] > min_seconds_to_print: self._printer.print_timing( " %s took %s seconds to run %s tests." % (timing[1], timing[0], timing[2])) self._printer.print_timing("") def _print_statistics_for_test_timings(self, title, timings): """Prints the median, mean and standard deviation of the values in timings. Args: title: Title for these timings. timings: A list of floats representing times. """ self._printer.print_timing(title) timings.sort() num_tests = len(timings) if not num_tests: return percentile90 = timings[int(.9 * num_tests)] percentile99 = timings[int(.99 * num_tests)] if num_tests % 2 == 1: median = timings[((num_tests - 1) / 2) - 1] else: lower = timings[num_tests / 2 - 1] upper = timings[num_tests / 2] median = (float(lower + upper)) / 2 mean = sum(timings) / num_tests for time in timings: sum_of_deviations = math.pow(time - mean, 2) std_deviation = math.sqrt(sum_of_deviations / num_tests) self._printer.print_timing(" Median: %6.3f" % median) self._printer.print_timing(" Mean: %6.3f" % mean) self._printer.print_timing(" 90th percentile: %6.3f" % percentile90) self._printer.print_timing(" 99th percentile: %6.3f" % percentile99) self._printer.print_timing(" Standard dev: %6.3f" % std_deviation) self._printer.print_timing("") def _print_result_summary(self, result_summary): """Print a short summary about how many tests passed. Args: result_summary: information to log """ failed = len(result_summary.failures) skipped = len( result_summary.tests_by_expectation[test_expectations.SKIP]) total = result_summary.total passed = total - failed - skipped pct_passed = 0.0 if total > 0: pct_passed = float(passed) * 100 / total self._printer.print_actual("") self._printer.print_actual("=> Results: %d/%d tests passed (%.1f%%)" % (passed, total, pct_passed)) self._printer.print_actual("") self._print_result_summary_entry(result_summary, test_expectations.NOW, "Tests to be fixed for the current release") self._printer.print_actual("") self._print_result_summary_entry(result_summary, test_expectations.DEFER, "Tests we'll fix in the future if they fail (DEFER)") self._printer.print_actual("") self._print_result_summary_entry(result_summary, test_expectations.WONTFIX, "Tests that will only be fixed if they crash (WONTFIX)") self._printer.print_actual("") def _print_result_summary_entry(self, result_summary, timeline, heading): """Print a summary block of results for a particular timeline of test. Args: result_summary: summary to print results for timeline: the timeline to print results for (NOT, WONTFIX, etc.) heading: a textual description of the timeline """ total = len(result_summary.tests_by_timeline[timeline]) not_passing = (total - len(result_summary.tests_by_expectation[test_expectations.PASS] & result_summary.tests_by_timeline[timeline])) self._printer.print_actual("=> %s (%d):" % (heading, not_passing)) for result in TestExpectationsFile.EXPECTATION_ORDER: if result == test_expectations.PASS: continue results = (result_summary.tests_by_expectation[result] & result_summary.tests_by_timeline[timeline]) desc = TestExpectationsFile.EXPECTATION_DESCRIPTIONS[result] if not_passing and len(results): pct = len(results) * 100.0 / not_passing self._printer.print_actual(" %5d %-24s (%4.1f%%)" % (len(results), desc[len(results) != 1], pct)) def _results_html(self, test_files, failures, title="Test Failures", override_time=None): """ test_files = a list of file paths failures = dictionary mapping test paths to failure objects title = title printed at top of test override_time = current time (used by unit tests) """ page = """
%s
\n" % (test_url, test_name)
test_failures = failures.get(test_file, [])
for failure in test_failures:
page += u" %s
" % failure.result_html_output(test_name)
page += "