#!/usr/bin/env python3 """ Usage: analsyse_threadpool_tasks.py [options] input.dat where input.dat is a threadpool dump for a step. Use the '-Y interval' flag of the swift command to create these. The output is an analysis of the threadpool task timings, including deadtime per thread and step, total amount of time spent for each task type, for the whole step and per thread and the minimum and maximum times spent per task type. This file is part of SWIFT. Copyright (c) 2017 Peter W. Draper (p.w.draper@durham.ac.uk) This program is free software: you can redistribute it and/or modify it under the terms of the GNU Lesser General Public License as published by the Free Software Foundation, either version 3 of the License, or (at your option) any later version. This program is distributed in the hope that it will be useful, but WITHOUT ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License for more details. You should have received a copy of the GNU Lesser General Public License along with this program. If not, see . """ import pylab as pl import sys import argparse # Handle the command line. parser = argparse.ArgumentParser(description="Analyse task dumps") parser.add_argument("input", help="Threadpool data file (-y output)") parser.add_argument( "-v", "--verbose", dest="verbose", help="Verbose output (default: False)", default=False, action="store_true", ) parser.add_argument( "--html", dest="html", help="Use html titles and anchors in the output (default: False)", default=False, action="store_true", ) args = parser.parse_args() infile = args.input with_html = args.html # Read header. First two lines. with open(infile) as infid: head = [next(infid) for x in range(2)] header = head[1][2:].strip() header = eval(header) nthread = int(header["num_threads"]) + 1 CPU_CLOCK = float(header["cpufreq"]) / 1000.0 print("Number of threads: ", nthread - 1) if args.verbose: print("CPU frequency:", CPU_CLOCK * 1000.0) # Read input. data = pl.genfromtxt(infile, dtype=None, delimiter=" ", encoding=None) # Mixed types, so need to separate. tics = [] tocs = [] funcs = [] threads = [] chunks = [] for i in data: if i[0] != "#": funcs.append(i[0].replace("_mapper", "")) if i[1] < 0: threads.append(nthread - 1) else: threads.append(i[1]) chunks.append(i[2]) tics.append(i[3]) tocs.append(i[4]) tics = pl.array(tics) tocs = pl.array(tocs) funcs = pl.array(funcs) threads = pl.array(threads) chunks = pl.array(chunks) # Recover the start and end time tic_step = min(tics) toc_step = max(tocs) # Calculate the time range. total_t = (toc_step - tic_step) / CPU_CLOCK print("# Data range: ", total_t, "ms") print() # Correct times to relative millisecs. start_t = float(tic_step) tics = (tics - start_t) / CPU_CLOCK tocs = (tocs - start_t) / CPU_CLOCK tasks = {} tasks[-1] = [] for i in range(nthread): tasks[i] = [] # Gather into by thread data. for i in range(len(tics)): tasks[threads[i]].append([tics[i], tocs[i], funcs[i]]) # Don't actually process the fake thread. nthread = nthread - 1 # Sort by tic and gather used thread ids. threadids = [] for i in range(nthread): if len(tasks[i]) > 0: tasks[i] = sorted(tasks[i], key=lambda task: task[0]) threadids.append(i) # Times per task. print("# Task times:") print("# -----------") print( "# {0:<31s}: {1:>7s} {2:>9s} {3:>9s} {4:>9s} {5:>9s} {6:>9s}".format( "type/subtype", "count", "minimum", "maximum", "sum", "mean", "percent" ) ) alltasktimes = {} sidtimes = {} for i in threadids: tasktimes = {} for task in tasks[i]: key = task[2] dt = task[1] - task[0] if not key in tasktimes: tasktimes[key] = [] tasktimes[key].append(dt) if not key in alltasktimes: alltasktimes[key] = [] alltasktimes[key].append(dt) if with_html: print('
'.format(i)) print("# Thread : ", i) for key in sorted(tasktimes.keys()): taskmin = min(tasktimes[key]) taskmax = max(tasktimes[key]) tasksum = sum(tasktimes[key]) print( "{0:33s}: {1:7d} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.4f} {6:9.2f}".format( key, len(tasktimes[key]), taskmin, taskmax, tasksum, tasksum / len(tasktimes[key]), tasksum / total_t * 100.0, ) ) print() if with_html: print('
') print("# All threads : ") for key in sorted(alltasktimes.keys()): taskmin = min(alltasktimes[key]) taskmax = max(alltasktimes[key]) tasksum = sum(alltasktimes[key]) print( "{0:33s}: {1:7d} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.4f} {6:9.2f}".format( key, len(alltasktimes[key]), taskmin, taskmax, tasksum, tasksum / len(alltasktimes[key]), tasksum / (len(threadids) * total_t) * 100.0, ) ) print() # Dead times. print("# Times not in tasks (deadtimes)") print("# ------------------------------") if with_html: print('
') print("# Time before first task:") print("# no. : {0:>9s} {1:>9s}".format("value", "percent")) predeadtimes = [] for i in threadids: predeadtime = tasks[i][0][0] print( "thread {0:2d}: {1:9.4f} {2:9.4f}".format( i, predeadtime, predeadtime / total_t * 100.0 ) ) predeadtimes.append(predeadtime) predeadmin = min(predeadtimes) predeadmax = max(predeadtimes) predeadsum = sum(predeadtimes) print( "# : {0:>9s} {1:>9s} {2:>9s} {3:>9s} {4:>9s} {5:>9s}".format( "count", "minimum", "maximum", "sum", "mean", "percent" ) ) print( "all : {0:9d} {1:9.4f} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.2f}".format( len(predeadtimes), predeadmin, predeadmax, predeadsum, predeadsum / len(predeadtimes), predeadsum / (len(threadids) * total_t) * 100.0, ) ) print() if with_html: print('
') print("# Time after last task:") print("# no. : {0:>9s} {1:>9s}".format("value", "percent")) postdeadtimes = [] for i in threadids: postdeadtime = total_t - tasks[i][-1][1] print( "thread {0:2d}: {1:9.4f} {2:9.4f}".format( i, postdeadtime, postdeadtime / total_t * 100.0 ) ) postdeadtimes.append(postdeadtime) postdeadmin = min(postdeadtimes) postdeadmax = max(postdeadtimes) postdeadsum = sum(postdeadtimes) print( "# : {0:>9s} {1:>9s} {2:>9s} {3:>9s} {4:>9s} {5:>9s}".format( "count", "minimum", "maximum", "sum", "mean", "percent" ) ) print( "all : {0:9d} {1:9.4f} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.2f}".format( len(postdeadtimes), postdeadmin, postdeadmax, postdeadsum, postdeadsum / len(postdeadtimes), postdeadsum / (len(threadids) * total_t) * 100.0, ) ) print() # Time in threadpool, i.e. from first to last tasks. if with_html: print('
') print("# Time between tasks (threadpool deadtime):") print( "# no. : {0:>9s} {1:>9s} {2:>9s} {3:>9s} {4:>9s} {5:>9s}".format( "count", "minimum", "maximum", "sum", "mean", "percent" ) ) threadpooldeadtimes = [] for i in threadids: deadtimes = [] last = tasks[i][0][0] for task in tasks[i]: dt = task[0] - last deadtimes.append(dt) last = task[1] # Drop first value, last value already gone. if len(deadtimes) > 1: deadtimes = deadtimes[1:] else: # Only one task, so no deadtime by definition. deadtimes = [0.0] deadmin = min(deadtimes) deadmax = max(deadtimes) deadsum = sum(deadtimes) print( "thread {0:2d}: {1:9d} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.4f} {6:9.2f}".format( i, len(deadtimes), deadmin, deadmax, deadsum, deadsum / len(deadtimes), deadsum / total_t * 100.0, ) ) threadpooldeadtimes.extend(deadtimes) deadmin = min(threadpooldeadtimes) deadmax = max(threadpooldeadtimes) deadsum = sum(threadpooldeadtimes) print( "all : {0:9d} {1:9.4f} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.2f}".format( len(threadpooldeadtimes), deadmin, deadmax, deadsum, deadsum / len(threadpooldeadtimes), deadsum / (len(threadids) * total_t) * 100.0, ) ) print() # All times in step. if with_html: print('
') print("# All deadtimes:") print( "# no. : {0:>9s} {1:>9s} {2:>9s} {3:>9s} {4:>9s} {5:>9s}".format( "count", "minimum", "maximum", "sum", "mean", "percent" ) ) alldeadtimes = [] for i in threadids: deadtimes = [] last = 0 for task in tasks[i]: dt = task[0] - last deadtimes.append(dt) last = task[1] dt = total_t - last deadtimes.append(dt) deadmin = min(deadtimes) deadmax = max(deadtimes) deadsum = sum(deadtimes) print( "thread {0:2d}: {1:9d} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.4f} {6:9.2f}".format( i, len(deadtimes), deadmin, deadmax, deadsum, deadsum / len(deadtimes), deadsum / total_t * 100.0, ) ) alldeadtimes.extend(deadtimes) deadmin = min(alldeadtimes) deadmax = max(alldeadtimes) deadsum = sum(alldeadtimes) print( "all : {0:9d} {1:9.4f} {2:9.4f} {3:9.4f} {4:9.4f} {5:9.2f}".format( len(alldeadtimes), deadmin, deadmax, deadsum, deadsum / len(alldeadtimes), deadsum / (len(threadids) * total_t) * 100.0, ) ) print() sys.exit(0)