# Copyright (c) 2010 The Chromium OS Authors. All rights reserved. # Use of this source code is governed by a BSD-style license that can be # found in the LICENSE file. """Classes and functions for managing platform_BootPerf results. Results from the platform_BootPerf test in the ChromiumOS autotest package are stored as performance 'keyvals', that is, a mapping of names to numeric values. For each iteration of the test, one set of keyvals is recorded. This module currently tracks four kinds of keyval results: the boot time results, the disk read results, the firmware time results, and reboot time results. These results are stored with keyval names such as 'seconds_kernel_to_login', 'rdbytes_kernel_to_login', and 'seconds_power_on_to_kernel'. These keyvals record an accumulated total measured from a fixed time in the past, e.g. 'seconds_kernel_to_login' records the total seconds from kernel startup to login screen ready. The boot time keyval names all start with the prefix 'seconds_kernel_to_', and record time in seconds since kernel startup. The disk read keyval names all start with the prefix 'rdbytes_kernel_to_', and record bytes read from the boot device since kernel startup. The firmware keyval names all start with the prefix 'seconds_power_on_to_', and record time in seconds since CPU power on. The reboot keyval names are selected from a hard-coded list of keyvals that include both some boot time and some firmware time keyvals, plus specific keyvals keyed to record shutdown and reboot time. """ import math def _ListStats(list_): """Return the mean and sample standard deviation of a list. The returned result is float, even if the input list is full of integers. @param list_ The list over which to calculate. """ sum_ = 0.0 sumsq = 0.0 for v in list_: sum_ += v sumsq += v * v n = len(list_) avg = sum_ / n var = (sumsq - sum_ * avg) / (n - 1) if var < 0.0: var = 0.0 dev = math.sqrt(var) return (avg, dev) class TestResultSet(object): """A set of boot time and disk usage result statistics. Objects of this class consist of three sets of result statistics: the boot time statistics, the disk statistics, and the firmware time statistics. Class TestResultsSet does not interpret or store keyval mappings directly; iteration results are processed by attached _KeySet objects, one for each of the three types of result keyval. The _KeySet objects are kept in a dictionary; they can be obtained by calling the KeySet with the name of the keyset desired. Various methods on the KeySet objects will calculate statistics on the results, and provide the raw data. """ # The names of the available KeySets, to be used as arguments to # KeySet(). BOOTTIME_KEYSET = "boot" DISK_KEYSET = "disk" FIRMWARE_KEYSET = "firmware" REBOOT_KEYSET = "reboot" AVAILABLE_KEYSETS = [ BOOTTIME_KEYSET, DISK_KEYSET, FIRMWARE_KEYSET, REBOOT_KEYSET ] def __init__(self, name): self.name = name self._keysets = { self.BOOTTIME_KEYSET : _TimeKeySet(), self.DISK_KEYSET : _DiskKeySet(), self.FIRMWARE_KEYSET : _FirmwareKeySet(), self.REBOOT_KEYSET : _RebootKeySet(), } def AddIterationResults(self, runkeys): """Add keyval results from a single iteration. A TestResultSet is constructed by repeatedly calling AddIterationResults(), iteration by iteration. Iteration results are passed in as a dictionary mapping keyval attributes to values. When all iteration results have been added, FinalizeResults() makes the results available for analysis. @param runkeys The dictionary of keyvals for the iteration. """ for keyset in self._keysets.itervalues(): keyset.AddIterationResults(runkeys) def FinalizeResults(self): """Make results available for analysis. A TestResultSet is constructed by repeatedly feeding it results, iteration by iteration. Iteration results are passed in as a dictionary mapping keyval attributes to values. When all iteration results have been added, FinalizeResults() makes the results available for analysis. """ for keyset in self._keysets.itervalues(): keyset.FinalizeResults() def KeySet(self, keytype): """Return a selected keyset from the test results. @param keytype Selector for the desired keyset. """ return self._keysets[keytype] class _KeySet(object): """Container for a set of related statistics. _KeySet is an abstract superclass for containing collections of a related set of performance statistics. Statistics are stored as a dictionary (`_keyvals`) mapping keyval names to lists of values. The lists are indexed by the iteration number. The mapped keyval names are shortened by stripping the prefix that identifies the type of keyval (keyvals that don't start with the proper prefix are ignored). So, for example, with boot time keyvals, 'seconds_kernel_to_login' becomes 'login' (and 'rdbytes_kernel_to_login' is ignored). A list of all valid keyval names is stored in the `markers` instance variable. The list is sorted by the ordering of the average of the corresponding values. Each iteration is required to contain the same set of keyvals. This is enforced in FinalizeResults() (see below). """ def __init__(self): self._keyvals = {} def _CheckCounts(self): """Check the validity of the keyvals results dictionary. Each keyval must have occurred the same number of times. When this check succeeds, it returns the total number of occurrences; on failure return `None`. """ check = map(len, self._keyvals.values()) if not check: return None for i in range(1, len(check)): if check[i] != check[i-1]: return None return check[0] def AddIterationResults(self, runkeys): """Add results for one iteration. @param runkeys The dictionary of keyvals for the iteration. """ for key, value in runkeys.iteritems(): if not key.startswith(self.PREFIX): continue shortkey = key[len(self.PREFIX):] keylist = self._keyvals.setdefault(shortkey, []) keylist.append(self._ConvertVal(value)) def FinalizeResults(self): """Finalize this object's results. This method makes available the `markers` and `num_iterations` instance variables. It also ensures that every keyval occurred in every iteration by requiring that all keyvals have the same number of data points. """ count = self._CheckCounts() if count is None: self.num_iterations = 0 self.markers = [] return False self.num_iterations = count keylist = map(lambda k: (sum(self._keyvals[k]), k), self._keyvals.keys()) keylist.sort(key=lambda tp: tp[0]) self.markers = map(lambda tp: tp[1], keylist) return True def RawData(self, key): """Return the list of values for the given key. @param key Key of the list of values to return. """ return self._keyvals[key] def DeltaData(self, key0, key1): """Return the vector difference between two keyvals lists. @param key0 Key of the subtrahend vector. @param key1 Key of the subtractor vector. """ return map(lambda a, b: b - a, self._keyvals[key0], self._keyvals[key1]) def Statistics(self, key): """Return the average and standard deviation for a key. @param key """ return _ListStats(self._keyvals[key]) def DeltaStatistics(self, key0, key1): """Return the average and standard deviation between two keys. Calculates the difference between each matching element in the two key's lists, and returns the average and sample standard deviation of the differences. @param key0 Key of the subtrahend. @param key1 Key of the subtractor. """ return _ListStats(self.DeltaData(key0, key1)) class _TimeKeySet(_KeySet): """Concrete subclass of _KeySet for boot time statistics.""" PREFIX = 'seconds_kernel_to_' # Time-based keyvals are reported in seconds and get converted to # milliseconds TIME_SCALE = 1000 def _ConvertVal(self, value): """Return a keyval value in its 'canonical' form. For boot time values, the input is seconds as a float; the canonical form is milliseconds as an integer. @param value A time statistic in seconds. """ # We want to return the nearest exact integer here. round() # returns a float, and int() truncates its results, so we have # to combine them. return int(round(self.TIME_SCALE * float(value))) def PrintableStatistic(self, value): """Return a keyval in its preferred form for printing. The return value is a tuple of a string to be printed, and value rounded to the precision to which it was printed. Rationale: Some callers of this function total up intermediate results. Returning the rounded value makes those totals more robust against visible rounding anomalies. @param value The value to be printed. """ v = int(round(value)) return ("%d" % v, v) class _FirmwareKeySet(_TimeKeySet): """Concrete subclass of _KeySet for firmware time statistics.""" PREFIX = 'seconds_power_on_to_' # Time-based keyvals are reported in seconds and get converted to # milliseconds TIME_SCALE = 1000 class _RebootKeySet(_TimeKeySet): """Concrete subclass of _KeySet for reboot time statistics.""" PREFIX = '' # Time-based keyvals are reported in seconds and get converted to # milliseconds TIME_SCALE = 1000 def AddIterationResults(self, runkeys): """Add results for one iteration. For _RebootKeySet, we cherry-pick and normalize a hard-coded list of keyvals. @param runkeys The dictionary of keyvals for the iteration. """ # The time values we report are calculated as the time from when # shutdown was requested. However, the actual keyvals from the # test are reported, variously, as "time from shutdown request", # "time from power-on", and "time from kernel start". So, # the values have to be normalized to a common time line. # # The keyvals below capture the time from shutdown request of # the _end_ of a designated phase of reboot, as follows: # shutdown - end of shutdown, start of firmware power-on # sequence. # firmware - end of firmware, transfer to kernel. # startup - end of kernel initialization, Upstart's "startup" # event. # chrome_exec - session_manager initialization complete, # Chrome starts running. # login - Chrome completes initialization of the login screen. # shutdown = float(runkeys["seconds_shutdown_time"]) firmware_time = float(runkeys["seconds_power_on_to_kernel"]) startup = float(runkeys["seconds_kernel_to_startup"]) chrome_exec = float(runkeys["seconds_kernel_to_chrome_exec"]) reboot = float(runkeys["seconds_reboot_time"]) newkeys = {} newkeys["shutdown"] = shutdown newkeys["firmware"] = shutdown + firmware_time newkeys["startup"] = newkeys["firmware"] + startup newkeys["chrome_exec"] = newkeys["firmware"] + chrome_exec newkeys["login"] = reboot super(_RebootKeySet, self).AddIterationResults(newkeys) class _DiskKeySet(_KeySet): """Concrete subclass of _KeySet for disk read statistics.""" PREFIX = 'rdbytes_kernel_to_' # Disk read keyvals are reported in bytes and get converted to # MBytes (1 MByte = 1 million bytes, not 2**20) DISK_SCALE = 1.0e-6 def _ConvertVal(self, value): """Return a keyval value in its 'canonical' form. For disk statistics, the input is bytes as a float; the canonical form is megabytes as a float. @param value A disk data statistic in megabytes. """ return self.DISK_SCALE * float(value) def PrintableStatistic(self, value): """Return a keyval in its preferred form for printing. The return value is a tuple of a string to be printed, and value rounded to the precision to which it was printed. Rationale: Some callers of this function total up intermediate results. Returning the rounded value makes those totals more robust against visible rounding anomalies. @param value The value to be printed. """ v = round(value, 1) return ("%.1fM" % v, v)