* <barrow_dj@mail.yahoo.com,djbarrow@de.ibm.com>
* Copyright (c) 2004 Roland McGrath <roland@redhat.com>
* Copyright (c) 2006 Dmitry V. Levin <ldv@altlinux.org>
+ * Copyright (c) 2006-2018 The strace developers.
* All rights reserved.
*
* Redistribution and use in source and binary forms, with or without
* 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.
- *
- * $Id$
*/
#include "defs.h"
+/* Per-syscall stats structure */
struct call_counts {
+ /* time may be total latency or system time */
struct timeval time;
- int calls, errors;
+ unsigned int calls, errors;
};
static struct call_counts *countv[SUPPORTED_PERSONALITIES];
static struct timeval shortest = { 1000000, 0 };
void
-count_syscall(struct tcb *tcp, struct timeval *tv)
+count_syscall(struct tcb *tcp, const struct timeval *syscall_exiting_tv)
{
- if (!SCNO_IN_RANGE(tcp->scno))
+ struct timeval wtv;
+ struct timeval *tv = &wtv;
+ struct call_counts *cc;
+
+ if (!scno_in_range(tcp->scno))
return;
- if (!counts) {
- counts = calloc(nsyscalls, sizeof(*counts));
- if (!counts)
- die_out_of_memory();
- }
+ if (!counts)
+ counts = xcalloc(nsyscalls, sizeof(*counts));
+ cc = &counts[tcp->scno];
- counts[tcp->scno].calls++;
- if (tcp->u_error)
- counts[tcp->scno].errors++;
+ cc->calls++;
+ if (syserror(tcp))
+ cc->errors++;
- tv_sub(tv, tv, &tcp->etime);
-#ifdef LINUX
+ /* tv = wall clock time spent while in syscall */
+ tv_sub(tv, syscall_exiting_tv, &tcp->etime);
+
+ /* Spent more wall clock time than spent system time? (usually yes) */
if (tv_cmp(tv, &tcp->dtime) > 0) {
- static struct timeval one_tick;
+ static struct timeval one_tick = { -1, 0 };
- if (one_tick.tv_usec == 0) {
+ if (one_tick.tv_sec == -1) {
/* Initialize it. */
struct itimerval it;
- memset(&it, 0, sizeof it);
+ memset(&it, 0, sizeof(it));
it.it_interval.tv_usec = 1;
setitimer(ITIMER_REAL, &it, NULL);
getitimer(ITIMER_REAL, &it);
one_tick = it.it_interval;
+//FIXME: this hack doesn't work (tested on linux-3.6.11): one_tick = 0.000000
+//tprintf(" one_tick.tv_usec:%u\n", (unsigned)one_tick.tv_usec);
}
if (tv_nz(&tcp->dtime))
- *tv = tcp->dtime;
+ /* tv = system time spent, if it isn't 0 */
+ tv = &tcp->dtime;
else if (tv_cmp(tv, &one_tick) > 0) {
+ /* tv = smallest "sane" time interval */
if (tv_cmp(&shortest, &one_tick) < 0)
- *tv = shortest;
+ tv = &shortest;
else
- *tv = one_tick;
+ tv = &one_tick;
}
}
-#endif /* LINUX */
if (tv_cmp(tv, &shortest) < 0)
shortest = *tv;
- tv_add(&counts[tcp->scno].time, &counts[tcp->scno].time, tv);
+ tv_add(&cc->time, &cc->time, count_wallclock ? &wtv : tv);
}
static int
static int
syscall_cmp(void *a, void *b)
{
- return strcmp(sysent[*((int *) a)].sys_name,
- sysent[*((int *) b)].sys_name);
+ const char *a_name = sysent[*((int *) a)].sys_name;
+ const char *b_name = sysent[*((int *) b)].sys_name;
+ return strcmp(a_name ? a_name : "", b_name ? b_name : "");
}
static int
else if (strcmp(sortby, "nothing") == 0)
sortfun = NULL;
else {
- fprintf(stderr, "invalid sortby: `%s'\n", sortby);
- exit(1);
+ error_msg_and_help("invalid sortby: '%s'", sortby);
}
}
static void
call_summary_pers(FILE *outf)
{
- int i, j;
- int call_cum, error_cum;
+ static const char dashes[] = "----------------";
+ static const char header[] = "%6.6s %11.11s %11.11s %9.9s %9.9s %s\n";
+ static const char data[] = "%6.2f %11.6f %11lu %9u %9.u %s\n";
+ static const char summary[] = "%6.6s %11.6f %11.11s %9u %9.u %s\n";
+
+ unsigned int i;
+ unsigned int call_cum, error_cum;
struct timeval tv_cum, dtv;
+ double float_tv_cum;
double percent;
- const char *dashes = "-------------------------";
- char error_str[16];
- int *sorted_count = calloc(sizeof(int), nsyscalls);
+ unsigned int *sorted_count;
- if (!sorted_count)
- die_out_of_memory();
+ fprintf(outf, header,
+ "% time", "seconds", "usecs/call",
+ "calls", "errors", "syscall");
+ fprintf(outf, header, dashes, dashes, dashes, dashes, dashes, dashes);
+ sorted_count = xcalloc(sizeof(sorted_count[0]), nsyscalls);
call_cum = error_cum = tv_cum.tv_sec = tv_cum.tv_usec = 0;
if (overhead.tv_sec == -1) {
tv_mul(&overhead, &shortest, 8);
error_cum += counts[i].errors;
tv_add(&tv_cum, &tv_cum, &counts[i].time);
}
- if (counts && sortfun)
- qsort((void *) sorted_count, nsyscalls, sizeof(int), sortfun);
- fprintf(outf, "%6.6s %11.11s %11.11s %9.9s %9.9s %s\n",
- "% time", "seconds", "usecs/call",
- "calls", "errors", "syscall");
- fprintf(outf, "%6.6s %11.11s %11.11s %9.9s %9.9s %-16.16s\n",
- dashes, dashes, dashes, dashes, dashes, dashes);
+ float_tv_cum = tv_float(&tv_cum);
if (counts) {
+ if (sortfun)
+ qsort((void *) sorted_count, nsyscalls,
+ sizeof(sorted_count[0]), sortfun);
for (i = 0; i < nsyscalls; i++) {
- j = sorted_count[i];
- if (counts[j].calls == 0)
+ double float_syscall_time;
+ unsigned int idx = sorted_count[i];
+ struct call_counts *cc = &counts[idx];
+ if (cc->calls == 0)
continue;
- tv_div(&dtv, &counts[j].time, counts[j].calls);
- if (counts[j].errors)
- sprintf(error_str, "%d", counts[j].errors);
- else
- error_str[0] = '\0';
- percent = (100.0 * tv_float(&counts[j].time)
- / tv_float(&tv_cum));
- fprintf(outf, "%6.2f %11.6f %11ld %9d %9.9s %s\n",
- percent, tv_float(&counts[j].time),
- (long) 1000000 * dtv.tv_sec + dtv.tv_usec,
- counts[j].calls,
- error_str, sysent[j].sys_name);
+ tv_div(&dtv, &cc->time, cc->calls);
+ float_syscall_time = tv_float(&cc->time);
+ percent = (100.0 * float_syscall_time);
+ if (percent != 0.0)
+ percent /= float_tv_cum;
+ /* else: float_tv_cum can be 0.0 too and we get 0/0 = NAN */
+ fprintf(outf, data,
+ percent, float_syscall_time,
+ (long) (1000000 * dtv.tv_sec + dtv.tv_usec),
+ cc->calls, cc->errors, sysent[idx].sys_name);
}
}
free(sorted_count);
- fprintf(outf, "%6.6s %11.11s %11.11s %9.9s %9.9s %-16.16s\n",
- dashes, dashes, dashes, dashes, dashes, dashes);
- if (error_cum)
- sprintf(error_str, "%d", error_cum);
- else
- error_str[0] = '\0';
- fprintf(outf, "%6.6s %11.6f %11.11s %9d %9.9s %s\n",
- "100.00", tv_float(&tv_cum), "",
- call_cum, error_str, "total");
+ fprintf(outf, header, dashes, dashes, dashes, dashes, dashes, dashes);
+ fprintf(outf, summary,
+ "100.00", float_tv_cum, "",
+ call_cum, error_cum, "total");
}
void
call_summary(FILE *outf)
{
- int i, old_pers = current_personality;
+ unsigned int i, old_pers = current_personality;
for (i = 0; i < SUPPORTED_PERSONALITIES; ++i) {
if (!countv[i])
set_personality(i);
if (i)
fprintf(outf,
- "System call usage summary for %u bit mode:\n",
- personality_wordsize[current_personality] * 8);
+ "System call usage summary for %s mode:\n",
+ personality_names[i]);
call_summary_pers(outf);
}