diff options
Diffstat (limited to 'yocto-poky/meta/classes/buildstats.bbclass')
-rw-r--r-- | yocto-poky/meta/classes/buildstats.bbclass | 243 |
1 files changed, 79 insertions, 164 deletions
diff --git a/yocto-poky/meta/classes/buildstats.bbclass b/yocto-poky/meta/classes/buildstats.bbclass index 22ec571b8..34ecb0386 100644 --- a/yocto-poky/meta/classes/buildstats.bbclass +++ b/yocto-poky/meta/classes/buildstats.bbclass @@ -1,135 +1,72 @@ BUILDSTATS_BASE = "${TMPDIR}/buildstats/" -BUILDSTATS_BNFILE = "${BUILDSTATS_BASE}/.buildname" -BUILDSTATS_DEVFILE = "${BUILDSTATS_BASE}/.device" ################################################################################ # Build statistics gathering. # # The CPU and Time gathering/tracking functions and bbevent inspiration -# were written by Christopher Larson and can be seen here: -# http://kergoth.pastey.net/142813 +# were written by Christopher Larson. # ################################################################################ -def get_process_cputime(pid): +def get_buildprocess_cputime(pid): with open("/proc/%d/stat" % pid, "r") as f: fields = f.readline().rstrip().split() # 13: utime, 14: stime, 15: cutime, 16: cstime return sum(int(field) for field in fields[13:16]) +def get_process_cputime(pid): + import resource + with open("/proc/%d/stat" % pid, "r") as f: + fields = f.readline().rstrip().split() + stats = { + 'utime' : fields[13], + 'stime' : fields[14], + 'cutime' : fields[15], + 'cstime' : fields[16], + } + iostats = {} + if os.path.isfile("/proc/%d/io" % pid): + with open("/proc/%d/io" % pid, "r") as f: + while True: + i = f.readline().strip() + if not i: + break + i = i.split(": ") + iostats[i[0]] = i[1] + resources = resource.getrusage(resource.RUSAGE_SELF) + childres = resource.getrusage(resource.RUSAGE_CHILDREN) + return stats, iostats, resources, childres + def get_cputime(): with open("/proc/stat", "r") as f: fields = f.readline().rstrip().split()[1:] return sum(int(field) for field in fields) -def set_bn(e): - bn = e.getPkgs()[0] + "-" + e.data.getVar('MACHINE', True) - try: - os.remove(e.data.getVar('BUILDSTATS_BNFILE', True)) - except: - pass - with open(e.data.getVar('BUILDSTATS_BNFILE', True), "w") as f: - f.write(os.path.join(bn, e.data.getVar('BUILDNAME', True))) +def set_timedata(var, d, server_time): + d.setVar(var, server_time) -def get_bn(e): - with open(e.data.getVar('BUILDSTATS_BNFILE', True)) as f: - bn = f.readline() - return bn - -def set_device(e): - tmpdir = e.data.getVar('TMPDIR', True) - devfile = e.data.getVar('BUILDSTATS_DEVFILE', True) - try: - os.remove(devfile) - except: - pass - ############################################################################ - # We look for the volume TMPDIR lives on. To do all disks would make little - # sense and not give us any particularly useful data. In theory we could do - # something like stick DL_DIR on a different partition and this would - # throw stats gathering off. The same goes with SSTATE_DIR. However, let's - # get the basics in here and work on the cornercases later. - # A note. /proc/diskstats does not contain info on encryptfs, tmpfs, etc. - # If we end up hitting one of these fs, we'll just skip diskstats collection. - ############################################################################ - device = os.stat(tmpdir) - majordev = os.major(long(device.st_dev)) - minordev = os.minor(long(device.st_dev)) - ############################################################################ - # Bug 1700: - # Because tmpfs/encryptfs/ramfs etc inserts no entry in /proc/diskstats - # we set rdev to NoLogicalDevice and search for it later. If we find NLD - # we do not collect diskstats as the method to collect meaningful statistics - # for these fs types requires a bit more research. - ############################################################################ - rdev = "NoLogicalDevice" - try: - with open("/proc/diskstats", "r") as f: - for line in f: - if majordev == int(line.split()[0]) and minordev == int(line.split()[1]): - rdev = line.split()[2] - except: - pass - with open(devfile, "w") as f: - f.write(rdev) - -def get_device(e): - with open(e.data.getVar('BUILDSTATS_DEVFILE', True)) as f: - device = f.readline() - return device - -def get_diskstats(dev): - import itertools - ############################################################################ - # For info on what these are, see kernel doc file iostats.txt - ############################################################################ - DSTAT_KEYS = ['ReadsComp', 'ReadsMerged', 'SectRead', 'TimeReads', 'WritesComp', 'SectWrite', 'TimeWrite', 'IOinProgress', 'TimeIO', 'WTimeIO'] - try: - with open("/proc/diskstats", "r") as f: - for x in f: - if dev in x: - diskstats_val = x.rstrip().split()[4:] - except IOError as e: +def get_timedata(var, d, end_time): + oldtime = d.getVar(var, False) + if oldtime is None: return - diskstats = dict(itertools.izip(DSTAT_KEYS, diskstats_val)) - return diskstats + return end_time - oldtime -def set_diskdata(var, dev, data): - data.setVar(var, get_diskstats(dev)) - -def get_diskdata(var, dev, data): - olddiskdata = data.getVar(var, False) - diskdata = {} - if olddiskdata is None: - return - newdiskdata = get_diskstats(dev) - for key in olddiskdata.iterkeys(): - diskdata["Start"+key] = str(int(olddiskdata[key])) - diskdata["End"+key] = str(int(newdiskdata[key])) - return diskdata - -def set_timedata(var, data, server_time=None): +def set_buildtimedata(var, d): import time - if server_time: - time = server_time - else: - time = time.time() + time = time.time() cputime = get_cputime() - proctime = get_process_cputime(os.getpid()) - data.setVar(var, (time, cputime, proctime)) + proctime = get_buildprocess_cputime(os.getpid()) + d.setVar(var, (time, cputime, proctime)) -def get_timedata(var, data, server_time=None): +def get_buildtimedata(var, d): import time - timedata = data.getVar(var, False) + timedata = d.getVar(var, False) if timedata is None: return oldtime, oldcpu, oldproc = timedata - procdiff = get_process_cputime(os.getpid()) - oldproc + procdiff = get_buildprocess_cputime(os.getpid()) - oldproc cpudiff = get_cputime() - oldcpu - if server_time: - end_time = server_time - else: - end_time = time.time() + end_time = time.time() timediff = end_time - oldtime if cpudiff > 0: cpuperc = float(procdiff) * 100 / cpudiff @@ -137,30 +74,27 @@ def get_timedata(var, data, server_time=None): cpuperc = None return timediff, cpuperc -def write_task_data(status, logfile, dev, e): - bn = get_bn(e) - bsdir = os.path.join(e.data.getVar('BUILDSTATS_BASE', True), bn) +def write_task_data(status, logfile, e, d): + bn = d.getVar('BUILDNAME', True) + bsdir = os.path.join(d.getVar('BUILDSTATS_BASE', True), bn) with open(os.path.join(logfile), "a") as f: - timedata = get_timedata("__timedata_task", e.data, e.time) - if timedata: - elapsedtime, cpu = timedata - f.write(bb.data.expand("${PF}: %s: Elapsed time: %0.2f seconds \n" % - (e.task, elapsedtime), e.data)) + elapsedtime = get_timedata("__timedata_task", d, e.time) + if elapsedtime: + f.write(d.expand("${PF}: %s: Elapsed time: %0.2f seconds \n" % + (e.task, elapsedtime))) + cpu, iostats, resources, childres = get_process_cputime(os.getpid()) if cpu: - f.write("CPU usage: %0.1f%% \n" % cpu) - ############################################################################ - # Here we gather up disk data. In an effort to avoid lying with stats - # I do a bare minimum of analysis of collected data. - # The simple fact is, doing disk io collection on a per process basis - # without effecting build time would be difficult. - # For the best information, running things with BB_TOTAL_THREADS = "1" - # would return accurate per task results. - ############################################################################ - if dev != "NoLogicalDevice": - diskdata = get_diskdata("__diskdata_task", dev, e.data) - if diskdata: - for key in sorted(diskdata.iterkeys()): - f.write(key + ": " + diskdata[key] + "\n") + f.write("utime: %s\n" % cpu['utime']) + f.write("stime: %s\n" % cpu['stime']) + f.write("cutime: %s\n" % cpu['cutime']) + f.write("cstime: %s\n" % cpu['cstime']) + for i in iostats: + f.write("IO %s: %s\n" % (i, iostats[i])) + rusages = ["ru_utime", "ru_stime", "ru_maxrss", "ru_minflt", "ru_majflt", "ru_inblock", "ru_oublock", "ru_nvcsw", "ru_nivcsw"] + for i in rusages: + f.write("rusage %s: %s\n" % (i, getattr(resources, i))) + for i in rusages: + f.write("Child rusage %s: %s\n" % (i, getattr(childres, i))) if status is "passed": f.write("Status: PASSED \n") else: @@ -170,25 +104,26 @@ def write_task_data(status, logfile, dev, e): python run_buildstats () { import bb.build import bb.event - import bb.data import time, subprocess, platform + bn = d.getVar('BUILDNAME', True) + bsdir = os.path.join(d.getVar('BUILDSTATS_BASE', True), bn) + taskdir = os.path.join(bsdir, d.getVar('PF', True)) + if isinstance(e, bb.event.BuildStarted): ######################################################################## - # at first pass make the buildstats heriarchy and then - # set the buildname + # If the kernel was not configured to provide I/O statistics, issue + # a one time warning. ######################################################################## - bb.utils.mkdirhier(e.data.getVar('BUILDSTATS_BASE', True)) - set_bn(e) - bn = get_bn(e) - set_device(e) - device = get_device(e) + if not os.path.isfile("/proc/%d/io" % os.getpid()): + bb.warn("The Linux kernel on your build host was not configured to provide process I/O statistics. (CONFIG_TASK_IO_ACCOUNTING is not set)") - bsdir = os.path.join(e.data.getVar('BUILDSTATS_BASE', True), bn) + ######################################################################## + # at first pass make the buildstats hierarchy and then + # set the buildname + ######################################################################## bb.utils.mkdirhier(bsdir) - if device != "NoLogicalDevice": - set_diskdata("__diskdata_build", device, e.data) - set_timedata("__timedata_build", e.data) + set_buildtimedata("__timedata_build", d) build_time = os.path.join(bsdir, "build_stats") # write start of build into build_time with open(build_time, "a") as f: @@ -201,35 +136,21 @@ python run_buildstats () { f.write("Build Started: %0.2f \n" % time.time()) elif isinstance(e, bb.event.BuildCompleted): - bn = get_bn(e) - device = get_device(e) - bsdir = os.path.join(e.data.getVar('BUILDSTATS_BASE', True), bn) build_time = os.path.join(bsdir, "build_stats") with open(build_time, "a") as f: ######################################################################## # Write build statistics for the build ######################################################################## - timedata = get_timedata("__timedata_build", e.data) + timedata = get_buildtimedata("__timedata_build", d) if timedata: time, cpu = timedata # write end of build and cpu used into build_time f.write("Elapsed time: %0.2f seconds \n" % (time)) if cpu: f.write("CPU usage: %0.1f%% \n" % cpu) - if device != "NoLogicalDevice": - diskio = get_diskdata("__diskdata_build", device, e.data) - if diskio: - for key in sorted(diskio.iterkeys()): - f.write(key + ": " + diskio[key] + "\n") if isinstance(e, bb.build.TaskStarted): - bn = get_bn(e) - device = get_device(e) - bsdir = os.path.join(e.data.getVar('BUILDSTATS_BASE', True), bn) - taskdir = os.path.join(bsdir, e.data.getVar('PF', True)) - if device != "NoLogicalDevice": - set_diskdata("__diskdata_task", device, e.data) - set_timedata("__timedata_task", e.data, e.time) + set_timedata("__timedata_task", d, e.time) bb.utils.mkdirhier(taskdir) # write into the task event file the name and start time with open(os.path.join(taskdir, e.task), "a") as f: @@ -237,24 +158,18 @@ python run_buildstats () { f.write("Started: %0.2f \n" % e.time) elif isinstance(e, bb.build.TaskSucceeded): - bn = get_bn(e) - device = get_device(e) - bsdir = os.path.join(e.data.getVar('BUILDSTATS_BASE', True), bn) - taskdir = os.path.join(bsdir, e.data.getVar('PF', True)) - write_task_data("passed", os.path.join(taskdir, e.task), device, e) + write_task_data("passed", os.path.join(taskdir, e.task), e, d) if e.task == "do_rootfs": bs = os.path.join(bsdir, "build_stats") with open(bs, "a") as f: - rootfs = e.data.getVar('IMAGE_ROOTFS', True) + rootfs = d.getVar('IMAGE_ROOTFS', True) rootfs_size = subprocess.Popen(["du", "-sh", rootfs], stdout=subprocess.PIPE).stdout.read() f.write("Uncompressed Rootfs size: %s" % rootfs_size) elif isinstance(e, bb.build.TaskFailed): - bn = get_bn(e) - device = get_device(e) - bsdir = os.path.join(e.data.getVar('BUILDSTATS_BASE', True), bn) - taskdir = os.path.join(bsdir, e.data.getVar('PF', True)) - write_task_data("failed", os.path.join(taskdir, e.task), device, e) + # Can have a failure before TaskStarted so need to mkdir here too + bb.utils.mkdirhier(taskdir) + write_task_data("failed", os.path.join(taskdir, e.task), e, d) ######################################################################## # Lets make things easier and tell people where the build failed in # build_status. We do this here because BuildCompleted triggers no @@ -262,7 +177,7 @@ python run_buildstats () { ######################################################################## build_status = os.path.join(bsdir, "build_stats") with open(build_status, "a") as f: - f.write(e.data.expand("Failed at: ${PF} at task: %s \n" % e.task)) + f.write(d.expand("Failed at: ${PF} at task: %s \n" % e.task)) } addhandler run_buildstats |