githubmirror/strace

src/count.c
876 строк22 KB
Vadym Hvas
count: include out-of-range syscalls in -c summary
27 май 2026, 11:00
27 май 2026, 11:00be4ac3f
/*
 * Copyright (c) 1991, 1992 Paul Kranenburg <pk@cs.few.eur.nl>
 * Copyright (c) 1993 Branko Lankester <branko@hacktic.nl>
 * Copyright (c) 1993, 1994, 1995, 1996 Rick Sladkey <jrs@world.std.com>
 * Copyright (c) 1996-1999 Wichert Akkerman <wichert@cistron.nl>
 * Copyright (c) 1999 IBM Deutschland Entwicklung GmbH, IBM Corporation
 *                     Linux for s390 port by D.J. Barrow
 *                    <barrow_dj@mail.yahoo.com,djbarrow@de.ibm.com>
 * Copyright (c) 2004 Roland McGrath <roland@redhat.com>
 * Copyright (c) 2006 Dmitry V. Levin <ldv@strace.io>
 * Copyright (c) 2006-2026 The strace developers.
 * All rights reserved.
 *
 * SPDX-License-Identifier: LGPL-2.1-or-later
 */

#include "defs.h"
#include "xstring.h"

#include <stdarg.h>

/* Per-syscall stats structure */
struct call_counts {
	/* time may be total latency or system time */
	struct timespec time;
	struct timespec time_min;
	struct timespec time_max;
	struct timespec time_avg;
	struct timespec wall_time;
	struct timespec wall_time_min;
	struct timespec wall_time_max;
	struct timespec wall_time_avg;
	uint64_t calls, errors;
};

static struct call_counts *countv[SUPPORTED_PERSONALITIES];
#define counts (countv[current_personality])

static const struct timespec zero_ts;
static const struct timespec max_ts = {
	(time_t) (long long) (zero_extend_signed_to_ull((time_t) -1ULL) >> 1),
	999999999 };

static struct timespec overhead;

static bool summary_wall_columns;
static bool summary_sortby_wall;

enum count_summary_columns {
	CSC_NONE,
	CSC_TIME_100S,
	CSC_TIME_TOTAL,
	CSC_TIME_MIN,
	CSC_TIME_MAX,
	CSC_TIME_AVG,
	CSC_CALLS,
	CSC_ERRORS,
	CSC_SC_NAME,
	CSC_TIME_WALL_TOTAL,
	CSC_TIME_WALL_MIN,
	CSC_TIME_WALL_MAX,
	CSC_TIME_WALL_AVG,

	CSC_MAX,
};

static bool
summary_needs_wall(void)
{
	return summary_wall_columns || summary_sortby_wall;
}

static bool
column_is_wall(uint8_t column)
{
	return column >= CSC_TIME_WALL_TOTAL && column <= CSC_TIME_WALL_AVG;
}

static uint8_t columns[CSC_MAX] = {
	CSC_TIME_100S,
	CSC_TIME_TOTAL,
	CSC_TIME_AVG,
	CSC_CALLS,
	CSC_ERRORS,
	CSC_SC_NAME,
};

static const struct {
	const char *name;
	uint8_t     column;
} column_aliases[] = {
	{ "time",         CSC_TIME_100S  },
	{ "time_percent", CSC_TIME_100S  },
	{ "time-percent", CSC_TIME_100S  },
	{ "time_total",   CSC_TIME_TOTAL },
	{ "time-total",   CSC_TIME_TOTAL },
	{ "total_time",   CSC_TIME_TOTAL },
	{ "total-time",   CSC_TIME_TOTAL },
	{ "min_time",     CSC_TIME_MIN   },
	{ "min-time",     CSC_TIME_MIN   },
	{ "shortest",     CSC_TIME_MIN   },
	{ "time_min",     CSC_TIME_MIN   },
	{ "time-min",     CSC_TIME_MIN   },
	{ "longest" ,     CSC_TIME_MAX   },
	{ "max_time",     CSC_TIME_MAX   },
	{ "max-time",     CSC_TIME_MAX   },
	{ "time_max",     CSC_TIME_MAX   },
	{ "time-max",     CSC_TIME_MAX   },
	{ "avg_time",     CSC_TIME_AVG   },
	{ "avg-time",     CSC_TIME_AVG   },
	{ "time_avg",     CSC_TIME_AVG   },
	{ "time-avg",     CSC_TIME_AVG   },
	{ "calls",        CSC_CALLS      },
	{ "count",        CSC_CALLS      },
	{ "error",        CSC_ERRORS     },
	{ "errors",       CSC_ERRORS     },
	{ "name",         CSC_SC_NAME    },
	{ "syscall",      CSC_SC_NAME    },
	{ "syscall_name", CSC_SC_NAME    },
	{ "syscall-name", CSC_SC_NAME    },
	{ "wall_total",   CSC_TIME_WALL_TOTAL },
	{ "wall-total",   CSC_TIME_WALL_TOTAL },
	{ "total_wall",   CSC_TIME_WALL_TOTAL },
	{ "total-wall",   CSC_TIME_WALL_TOTAL },
	{ "wall_min",     CSC_TIME_WALL_MIN   },
	{ "wall-min",     CSC_TIME_WALL_MIN   },
	{ "min_wall",     CSC_TIME_WALL_MIN   },
	{ "min-wall",     CSC_TIME_WALL_MIN   },
	{ "wall_max",     CSC_TIME_WALL_MAX   },
	{ "wall-max",     CSC_TIME_WALL_MAX   },
	{ "max_wall",     CSC_TIME_WALL_MAX   },
	{ "max-wall",     CSC_TIME_WALL_MAX   },
	{ "wall_avg",     CSC_TIME_WALL_AVG   },
	{ "wall-avg",     CSC_TIME_WALL_AVG   },
	{ "avg_wall",     CSC_TIME_WALL_AVG   },
	{ "avg-wall",     CSC_TIME_WALL_AVG   },
	{ "none",         CSC_NONE       },
	{ "nothing",      CSC_NONE       },
};

/* Per-unknown syscall stats structure */
struct unknown_call_counts {
	kernel_ulong_t scno;
	char sys_name[sizeof("syscall_0x") + sizeof(kernel_ulong_t) * 2];
	struct call_counts call_counts;
};

struct unknown_call_bucket {
	size_t len;
	size_t cap;
	struct unknown_call_counts *entries;
};

#define UNKNOWN_COUNTS_ENTRIES 16

static struct unknown_call_bucket *unknown_countv[SUPPORTED_PERSONALITIES];
#define unknown_counts (unknown_countv[current_personality])

static void
unknown_init_slot(struct call_counts *cc)
{
	cc->time_min = max_ts;

	if (summary_needs_wall())
		cc->wall_time_min = max_ts;
}

static void
unknown_init(void)
{
	if (unknown_counts)
		return;

	unknown_counts = xmalloc(sizeof(*unknown_counts));

	unknown_counts->len = 0;
	unknown_counts->cap = UNKNOWN_COUNTS_ENTRIES;
	unknown_counts->entries = xcalloc(UNKNOWN_COUNTS_ENTRIES,
					  sizeof(*unknown_counts->entries));

	for (size_t i = 0; i < UNKNOWN_COUNTS_ENTRIES; i++)
		unknown_init_slot(&unknown_counts->entries[i].call_counts);
}

static struct unknown_call_counts *
get_unknown_by_scno(kernel_ulong_t scno)
{
	if (!unknown_counts)
		return NULL;

	for (size_t i = 0; i < unknown_counts->len; i++)
		if (unknown_counts->entries[i].scno == scno)
			return &unknown_counts->entries[i];

	return NULL;
}

static struct unknown_call_counts *
get_unknown_by_idx(size_t idx)
{
	if (!unknown_counts)
		return NULL;

	if (idx >= unknown_counts->len)
		return NULL;

	return &unknown_counts->entries[idx];
}

static size_t
get_unknown_bucket_size(void)
{
	return unknown_counts ? unknown_counts->len : 0;
}

static struct call_counts *
get_syscall_cc(kernel_ulong_t scno)
{
	if (scno_in_range(scno))
		return &counts[scno];

	struct unknown_call_counts *ucc = get_unknown_by_scno(scno);
	if (!ucc)
		error_msg_and_die("failed to get unknown syscall"
				  " counts by %#" PRI_klx " number", scno);

	return &ucc->call_counts;
}

static const char *
get_syscall_name(kernel_ulong_t scno)
{
	if (scno_in_range(scno))
		return sysent[scno].sys_name;

	struct unknown_call_counts *ucc = get_unknown_by_scno(scno);
	if (!ucc)
		error_msg_and_die("failed to get unknown syscall"
				  " name by %#" PRI_klx " number", scno);

	return ucc->sys_name;
}

static void
unknown_insert(kernel_ulong_t scno)
{
	if (get_unknown_by_scno(scno))
		return;

	unknown_init();

	if (unknown_counts->len == unknown_counts->cap) {
		unknown_counts->cap *= 2;
		unknown_counts->entries = xreallocarray(unknown_counts->entries,
							unknown_counts->cap,
							sizeof(*unknown_counts->entries));

		for (size_t i = unknown_counts->len; i < unknown_counts->cap; i++)
			unknown_init_slot(&unknown_counts->entries[i].call_counts);
	}

	unknown_counts->entries[unknown_counts->len].scno = scno;
	xsprintf(unknown_counts->entries[unknown_counts->len].sys_name,
		 "syscall_%#" PRI_klx, shuffle_scno(scno));
	unknown_counts->len++;
}

void
count_syscall(struct tcb *tcp, const struct timespec *syscall_exiting_ts)
{
	if (!scno_in_range(tcp->scno))
		unknown_insert(tcp->scno);

	if (!counts) {
		const bool wall = summary_needs_wall();

		counts = xcalloc(nsyscalls, sizeof(*counts));

		for (size_t i = 0; i < nsyscalls; i++) {
			counts[i].time_min = max_ts;
			if (wall)
				counts[i].wall_time_min = max_ts;
		}
	}
	struct call_counts *cc = get_syscall_cc(tcp->scno);

	cc->calls++;
	if (syserror(tcp))
		cc->errors++;

	struct timespec wts;
	if (count_wallclock) {
		/* wall clock time spent while in syscall */
		ts_sub(&wts, syscall_exiting_ts, &tcp->etime);
	} else {
		/* system CPU time spent while in syscall */
		ts_sub(&wts, &tcp->stime, &tcp->ltime);
	}

	ts_sub(&wts, &wts, &overhead);

	const struct timespec *wts_nonneg = ts_max(&wts, &zero_ts);

	ts_add(&cc->time, &cc->time, wts_nonneg);
	cc->time_min = *ts_min(&cc->time_min, wts_nonneg);
	cc->time_max = *ts_max(&cc->time_max, wts_nonneg);

	if (!summary_needs_wall())
		return;

	struct timespec wall_wts;
	const struct timespec *wall_wts_nonneg;

	if (count_wallclock) {
		wall_wts_nonneg = wts_nonneg;
	} else {
		ts_sub(&wall_wts, syscall_exiting_ts, &tcp->etime);
		ts_sub(&wall_wts, &wall_wts, &overhead);
		wall_wts_nonneg = ts_max(&wall_wts, &zero_ts);
	}

	ts_add(&cc->wall_time, &cc->wall_time, wall_wts_nonneg);
	cc->wall_time_min = *ts_min(&cc->wall_time_min, wall_wts_nonneg);
	cc->wall_time_max = *ts_max(&cc->wall_time_max, wall_wts_nonneg);
}

static int
time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->time,
		       &get_syscall_cc(*b_k)->time);
}

static int
min_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->time_min,
		       &get_syscall_cc(*b_k)->time_min);
}

static int
max_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->time_max,
		       &get_syscall_cc(*b_k)->time_max);
}

static int
avg_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->time_avg,
		       &get_syscall_cc(*b_k)->time_avg);
}

static int
wall_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->wall_time,
		       &get_syscall_cc(*b_k)->wall_time);
}

static int
wall_min_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->wall_time_min,
		       &get_syscall_cc(*b_k)->wall_time_min);
}

static int
wall_max_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->wall_time_max,
		       &get_syscall_cc(*b_k)->wall_time_max);
}

static int
wall_avg_time_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;

	return -ts_cmp(&get_syscall_cc(*a_k)->wall_time_avg,
		       &get_syscall_cc(*b_k)->wall_time_avg);
}

static int
syscall_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;
	const char *a_name = get_syscall_name(*a_k);
	const char *b_name = get_syscall_name(*b_k);

	return strcmp(a_name ? a_name : "", b_name ? b_name : "");
}

static int
count_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;
	uint64_t m = get_syscall_cc(*a_k)->calls;
	uint64_t n = get_syscall_cc(*b_k)->calls;

	return (m < n) ? 1 : (m > n) ? -1 : 0;
}

static int
error_cmp(const void *a, const void *b)
{
	const kernel_ulong_t *a_k = a;
	const kernel_ulong_t *b_k = b;
	uint64_t m = get_syscall_cc(*a_k)->errors;
	uint64_t n = get_syscall_cc(*b_k)->errors;

	return (m < n) ? 1 : (m > n) ? -1 : 0;
}

typedef int (*sort_func)(const void *, const void *);
static sort_func sortfun;

void
set_sortby(const char *sortby)
{
	static const sort_func sort_fns[CSC_MAX] = {
		[CSC_TIME_100S]       = time_cmp,
		[CSC_TIME_TOTAL]      = time_cmp,
		[CSC_TIME_MIN]        = min_time_cmp,
		[CSC_TIME_MAX]        = max_time_cmp,
		[CSC_TIME_AVG]        = avg_time_cmp,
		[CSC_CALLS]           = count_cmp,
		[CSC_ERRORS]          = error_cmp,
		[CSC_SC_NAME]         = syscall_cmp,
		[CSC_TIME_WALL_TOTAL] = wall_time_cmp,
		[CSC_TIME_WALL_MIN]   = wall_min_time_cmp,
		[CSC_TIME_WALL_MAX]   = wall_max_time_cmp,
		[CSC_TIME_WALL_AVG]   = wall_avg_time_cmp,
	};

	summary_sortby_wall = false;

	for (size_t i = 0; i < ARRAY_SIZE(column_aliases); ++i) {
		if (!strcmp(column_aliases[i].name, sortby)) {
			const uint8_t column = column_aliases[i].column;

			sortfun = sort_fns[column];
			summary_sortby_wall = column_is_wall(column);
			return;
		}
	}

	error_msg_and_help("invalid sortby: '%s'", sortby);
}

void
set_count_summary_columns(const char *s)
{
	uint8_t visible[CSC_MAX] = { 0 };
	const char *prev = s;
	size_t cur = 0;

	memset(columns, 0, sizeof(columns));
	summary_wall_columns = false;

	for (;;) {
		bool found = false;
		const char *pos = strchr(prev, ',');
		size_t len = pos ? (size_t) (pos - prev) : strlen(prev);

		for (size_t i = 0; i < ARRAY_SIZE(column_aliases); i++) {
			if (strncmp(column_aliases[i].name, prev, len) ||
			    column_aliases[i].name[len])
				continue;
			if (column_aliases[i].column == CSC_NONE ||
			    column_aliases[i].column >= CSC_MAX)
				continue;

			if (visible[column_aliases[i].column])
				error_msg_and_help("call summary column "
						   "has been provided more "
						   "than once: '%s' (-U option "
						   "residual: '%s')",
						   column_aliases[i].name,
						   prev);

			columns[cur++] = column_aliases[i].column;
			visible[column_aliases[i].column] = 1;
			found = true;

			break;
		}

		if (!found)
			error_msg_and_help("unknown column name: '%.*s'",
					   (int) MIN(len, INT_MAX), prev);

		if (!pos)
			break;

		prev = pos + 1;
	}

	/*
	 * Always enable syscall name column, as without it table is meaningless
	 */
	if (!visible[CSC_SC_NAME])
		columns[cur++] = CSC_SC_NAME;

	for (size_t i = 0; i < ARRAY_SIZE(columns) && columns[i]; ++i) {
		if (column_is_wall(columns[i])) {
			summary_wall_columns = true;
			break;
		}
	}
}

int
set_overhead(const char *str)
{
	return parse_ts(str, &overhead);
}

static size_t ATTRIBUTE_FORMAT((printf, 1, 2))
num_chars(const char *fmt, ...)
{
	va_list ap;

	va_start(ap, fmt);
	int ret = vsnprintf(NULL, 0, fmt, ap);
	va_end(ap);

	return (unsigned int) MAX(ret, 0);
}

struct summary_stats {
	struct timespec tv_cum;
	const struct timespec *tv_min;
	const struct timespec *tv_min_max;
	const struct timespec *tv_max;
	const struct timespec *tv_avg_max;
	struct timespec tv_wall_cum;
	const struct timespec *tv_wall_min;
	const struct timespec *tv_wall_min_max;
	const struct timespec *tv_wall_max;
	const struct timespec *tv_wall_avg_max;
	uint64_t call_cum;
	uint64_t error_cum;
	double float_tv_cum;
	double float_tv_wall_cum;
	size_t sc_name_max;
};

static void
calc_summary_stats(struct summary_stats *stats, struct call_counts *cc, const char *sys_name)
{
	ts_add(&stats->tv_cum, &stats->tv_cum, &cc->time);
	stats->tv_min = ts_min(stats->tv_min, &cc->time_min);
	stats->tv_min_max = ts_max(stats->tv_min_max, &cc->time_min);
	stats->tv_max = ts_max(stats->tv_max, &cc->time_max);
	if (summary_needs_wall()) {
		ts_add(&stats->tv_wall_cum, &stats->tv_wall_cum, &cc->wall_time);
		stats->tv_wall_min = ts_min(stats->tv_wall_min, &cc->wall_time_min);
		stats->tv_wall_min_max = ts_max(stats->tv_wall_min_max,
						&cc->wall_time_min);
		stats->tv_wall_max = ts_max(stats->tv_wall_max,
					&cc->wall_time_max);
		ts_div(&cc->wall_time_avg, &cc->wall_time,
			cc->calls);
		stats->tv_wall_avg_max = ts_max(stats->tv_wall_avg_max,
						&cc->wall_time_avg);
	}
	stats->call_cum += cc->calls;
	stats->error_cum += cc->errors;

	ts_div(&cc->time_avg, &cc->time, cc->calls);
	stats->tv_avg_max = ts_max(stats->tv_avg_max, &cc->time_avg);

	stats->sc_name_max = MAX(stats->sc_name_max, strlen(sys_name));
}

static void
call_summary_pers(FILE *outf)
{
	/*
	 * unknown system calls start at index nsyscalls
	 * and their index may differ from their number
	 */
	kernel_ulong_t *indices;
	size_t indices_size = nsyscalls + get_unknown_bucket_size();
	size_t last_column = 0;

	struct summary_stats stats = {
		.tv_cum      	 = zero_ts,
		.tv_min      	 = &max_ts,
		.tv_min_max  	 = &zero_ts,
		.tv_max      	 = &zero_ts,
		.tv_avg_max  	 = &zero_ts,
		.tv_wall_cum 	 = zero_ts,
		.tv_wall_min 	 = &max_ts,
		.tv_wall_min_max = &zero_ts,
		.tv_wall_max 	 = &zero_ts,
		.tv_wall_avg_max = &zero_ts,
		.call_cum    	 = 0,
		.error_cum   	 = 0,
		.sc_name_max 	 = 0
	};

	/* sort, calculate statistics (for known syscalls) */
	indices = xcalloc(indices_size, sizeof(indices[0]));
	for (size_t i = 0; i < nsyscalls; ++i) {
		indices[i] = i;
		if (counts[i].calls == 0)
			continue;

		calc_summary_stats(&stats, &counts[i], sysent[i].sys_name);
	}

	/* for unknown syscalls */
	for (size_t i = 0; i < get_unknown_bucket_size(); ++i) {
		struct unknown_call_counts *ucc = get_unknown_by_idx(i);
		struct call_counts *cc = &ucc->call_counts;

		indices[nsyscalls + i] = ucc->scno;

		calc_summary_stats(&stats, cc, ucc->sys_name);
	}

	stats.float_tv_cum = ts_float(&stats.tv_cum);
	stats.float_tv_wall_cum = summary_needs_wall()
				  ? ts_float(&stats.tv_wall_cum) : 0;

	if (sortfun)
		qsort((void *) indices, indices_size, sizeof(indices[0]), sortfun);

	enum column_flags {
		CF_L = 1 << 0, /* Left-aligned column */
	};
	static const struct {
		const char *s;
		size_t sz;
		const char *fmt;
		const char *last_fmt;
		uint32_t flags;
	} cdesc[] = {
		[CSC_TIME_100S]       = { ARRSZ_PAIR("% time") - 1,
					  "%1$*2$.2f" },
		[CSC_TIME_MIN]        = { ARRSZ_PAIR("shortest") - 1,
					  "%1$*2$.6f" },
		[CSC_TIME_MAX]        = { ARRSZ_PAIR("longest") - 1,
					  "%1$*2$.6f" },
		/* Historical field sizes are preserved */
		[CSC_TIME_TOTAL]      = { "seconds",    11, "%1$*2$.6f" },
		[CSC_TIME_AVG]        = { "usecs/call", 11, "%1$*2$" PRIu64 },
		[CSC_CALLS]           = { "calls",       9, "%1$*2$" PRIu64 },
		[CSC_ERRORS]          = { "errors",      9,
					  "%1$*2$.0" PRIu64 },
		[CSC_SC_NAME]         = { "syscall",    16, "%1$-*2$s",
					  "%1$s", CF_L },
		[CSC_TIME_WALL_TOTAL] = { "wall-total", 11, "%1$*2$.6f" },
		[CSC_TIME_WALL_MIN]   = { ARRSZ_PAIR("wall-min") - 1,
					  "%1$*2$.6f" },
		[CSC_TIME_WALL_MAX]   = { ARRSZ_PAIR("wall-max") - 1,
					  "%1$*2$.6f" },
		[CSC_TIME_WALL_AVG]   = { "wall-avg",   11, "%1$*2$" PRIu64 },
	};

	/* calculate column widths */
#define W_(c_, v_) [c_] = MAX(cdesc[c_].sz, (v_))
	unsigned int cwidths[CSC_MAX] = {
		W_(CSC_TIME_100S,  sizeof("100.00") - 1),
		W_(CSC_TIME_TOTAL, num_chars("%.6f", stats.float_tv_cum)),
		W_(CSC_TIME_MIN,   num_chars("%" PRId64 ".000000",
					     (int64_t) stats.tv_min_max->tv_sec)),
		W_(CSC_TIME_MAX,   num_chars("%" PRId64 ".000000",
					     (int64_t) stats.tv_max->tv_sec)),
		W_(CSC_TIME_AVG,   num_chars("%" PRId64 ,
					     (uint64_t) (ts_float(stats.tv_avg_max)
							 * 1e6))),
		W_(CSC_CALLS,      num_chars("%" PRIu64, stats.call_cum)),
		W_(CSC_ERRORS,     num_chars("%" PRIu64, stats.error_cum)),
		W_(CSC_SC_NAME,    stats.sc_name_max + 1),
		W_(CSC_TIME_WALL_TOTAL,
		   num_chars("%.6f", stats.float_tv_wall_cum)),
		W_(CSC_TIME_WALL_MIN,
		   num_chars("%" PRId64 ".000000",
			     (int64_t) stats.tv_wall_min_max->tv_sec)),
		W_(CSC_TIME_WALL_MAX,
		   num_chars("%" PRId64 ".000000",
			     (int64_t) stats.tv_wall_max->tv_sec)),
		W_(CSC_TIME_WALL_AVG,
		   num_chars("%" PRId64,
			     (uint64_t) (ts_float(stats.tv_wall_avg_max) * 1e6))),
	};
#undef W_

	/* find the last column */
	for (size_t i = 0; i < ARRAY_SIZE(columns) && columns[i]; ++i)
		last_column = i;

	/* header */
	for (size_t i = 0; i <= last_column; ++i) {
		const char *fmt = cdesc[columns[i]].flags & CF_L
				  ? (i == last_column ? "%1$s" : "%1$-*2$s")
				  : "%1$*2$s";
		if (i)
			fputc(' ', outf);
		fprintf(outf, fmt, cdesc[columns[i]].s, cwidths[columns[i]]);
	}
	fputc('\n', outf);

	/* divider */
	for (size_t i = 0; i <= last_column; ++i) {
		if (i)
			fputc(' ', outf);

		for (size_t j = 0; j < cwidths[columns[i]]; ++j)
			fputc('-', outf);
	}
	fputc('\n', outf);

	/* cache column formats */
#define FC_(c_) \
	case (c_): \
		column_fmts[i] = (i == last_column) && cdesc[c].last_fmt \
				 ? cdesc[c].last_fmt : cdesc[c].fmt; \
		break
#define PC_(c_, val_) \
	case (c_): \
		fprintf(outf, column_fmts[i], (val_), cwidths[c]); \
		break

	const char *column_fmts[last_column + 1];
	for (size_t i = 0; i <= last_column; ++i) {
		const size_t c = columns[i];

		switch (c) {
		FC_(CSC_TIME_100S);
		FC_(CSC_TIME_TOTAL);
		FC_(CSC_TIME_MIN);
		FC_(CSC_TIME_MAX);
		FC_(CSC_TIME_AVG);
		FC_(CSC_CALLS);
		FC_(CSC_ERRORS);
		FC_(CSC_SC_NAME);
		FC_(CSC_TIME_WALL_TOTAL);
		FC_(CSC_TIME_WALL_MIN);
		FC_(CSC_TIME_WALL_MAX);
		FC_(CSC_TIME_WALL_AVG);
		}
	}

	/* data output */
	for (size_t j = 0; j < indices_size; ++j) {
		kernel_ulong_t idx = indices[j];
		struct call_counts *cc = get_syscall_cc(idx);
		const char *sys_name = get_syscall_name(idx);
		double float_syscall_time;
		double percent;

		if (cc->calls == 0)
			continue;

		float_syscall_time = ts_float(&cc->time);
		percent = (100.0 * float_syscall_time);
		/* else: float_tv_cum can be 0.0 too and we get 0/0 = NAN */
		if (percent != 0.0)
			percent /= stats.float_tv_cum;

		for (size_t i = 0; i <= last_column; ++i) {
			const size_t c = columns[i];
			if (i)
				fputc(' ', outf);

			switch (c) {
			PC_(CSC_TIME_100S,  percent);
			PC_(CSC_TIME_TOTAL, float_syscall_time);
			PC_(CSC_TIME_MIN,   ts_float(&cc->time_min));
			PC_(CSC_TIME_MAX,   ts_float(&cc->time_max));
			PC_(CSC_TIME_AVG,
			    (uint64_t) (ts_float(&cc->time_avg) * 1e6));
			PC_(CSC_CALLS,      cc->calls);
			PC_(CSC_ERRORS,     cc->errors);
			PC_(CSC_SC_NAME,    sys_name);
			PC_(CSC_TIME_WALL_TOTAL, ts_float(&cc->wall_time));
			PC_(CSC_TIME_WALL_MIN,   ts_float(&cc->wall_time_min));
			PC_(CSC_TIME_WALL_MAX,   ts_float(&cc->wall_time_max));
			PC_(CSC_TIME_WALL_AVG,
			    (uint64_t) (ts_float(&cc->wall_time_avg) * 1e6));
			}
		}

		fputc('\n', outf);
	}

	free(indices);

	/* footer */
	for (size_t i = 0; i <= last_column; ++i) {
		if (i)
			fputc(' ', outf);

		for (size_t j = 0; j < cwidths[columns[i]]; ++j)
			fputc('-', outf);
	}
	fputc('\n', outf);

	/* totals */
	for (size_t i = 0; i <= last_column; ++i) {
		const size_t c = columns[i];
		if (i)
			fputc(' ', outf);

		switch (c) {
		PC_(CSC_TIME_100S, 100.0);
		PC_(CSC_TIME_TOTAL, stats.float_tv_cum);
		PC_(CSC_TIME_MIN, ts_float(stats.tv_min));
		PC_(CSC_TIME_MAX, ts_float(stats.tv_max));
		PC_(CSC_TIME_AVG, (uint64_t) (stats.float_tv_cum / stats.call_cum * 1e6));
		PC_(CSC_CALLS, stats.call_cum);
		PC_(CSC_ERRORS, stats.error_cum);
		PC_(CSC_SC_NAME, "total");
		PC_(CSC_TIME_WALL_TOTAL, stats.float_tv_wall_cum);
		PC_(CSC_TIME_WALL_MIN, ts_float(stats.tv_wall_min));
		PC_(CSC_TIME_WALL_MAX, ts_float(stats.tv_wall_max));
		PC_(CSC_TIME_WALL_AVG,
		    (uint64_t) (stats.float_tv_wall_cum / stats.call_cum * 1e6));
		}
	}
	fputc('\n', outf);

#undef PC_
#undef FC_
}

void
call_summary(FILE *outf)
{
	const unsigned int old_pers = current_personality;

	for (unsigned int i = 0; i < SUPPORTED_PERSONALITIES; ++i) {
		if (!countv[i])
			continue;

		if (current_personality != i)
			set_personality(i);
		if (i)
			fprintf(outf,
				"System call usage summary for %s mode:\n",
				personality_names[i]);
		call_summary_pers(outf);
	}

	if (old_pers != current_personality)
		set_personality(old_pers);
}