blob: 184ff95ef4f51f74dde3fc6a769325b6c194c4de [file] [log] [blame]
Ingo Molnarddcacfa2009-04-20 15:37:32 +02001/*
Ingo Molnarbf9e1872009-06-02 23:37:05 +02002 * builtin-stat.c
3 *
4 * Builtin stat command: Give a precise performance counters summary
5 * overview about any workload, CPU or specific PID.
6 *
7 * Sample output:
Ingo Molnarddcacfa2009-04-20 15:37:32 +02008
Ingo Molnarbf9e1872009-06-02 23:37:05 +02009 $ perf stat ~/hackbench 10
10 Time: 0.104
Ingo Molnarddcacfa2009-04-20 15:37:32 +020011
Ingo Molnarbf9e1872009-06-02 23:37:05 +020012 Performance counter stats for '/home/mingo/hackbench':
Ingo Molnarddcacfa2009-04-20 15:37:32 +020013
Ingo Molnarbf9e1872009-06-02 23:37:05 +020014 1255.538611 task clock ticks # 10.143 CPU utilization factor
15 54011 context switches # 0.043 M/sec
16 385 CPU migrations # 0.000 M/sec
17 17755 pagefaults # 0.014 M/sec
18 3808323185 CPU cycles # 3033.219 M/sec
19 1575111190 instructions # 1254.530 M/sec
20 17367895 cache references # 13.833 M/sec
21 7674421 cache misses # 6.112 M/sec
Ingo Molnarddcacfa2009-04-20 15:37:32 +020022
Ingo Molnarbf9e1872009-06-02 23:37:05 +020023 Wall-clock time elapsed: 123.786620 msecs
Ingo Molnarddcacfa2009-04-20 15:37:32 +020024
Ingo Molnar52425192009-05-26 09:17:18 +020025 *
26 * Copyright (C) 2008, Red Hat Inc, Ingo Molnar <mingo@redhat.com>
27 *
28 * Improvements and fixes by:
29 *
30 * Arjan van de Ven <arjan@linux.intel.com>
31 * Yanmin Zhang <yanmin.zhang@intel.com>
32 * Wu Fengguang <fengguang.wu@intel.com>
33 * Mike Galbraith <efault@gmx.de>
34 * Paul Mackerras <paulus@samba.org>
35 *
36 * Released under the GPL v2. (and only v2, not any later version)
Ingo Molnarddcacfa2009-04-20 15:37:32 +020037 */
38
Peter Zijlstra1a482f32009-05-23 18:28:58 +020039#include "perf.h"
Ingo Molnar16f762a2009-05-27 09:10:38 +020040#include "builtin.h"
Ingo Molnar148be2c2009-04-27 08:02:14 +020041#include "util/util.h"
Ingo Molnar52425192009-05-26 09:17:18 +020042#include "util/parse-options.h"
43#include "util/parse-events.h"
Ingo Molnarddcacfa2009-04-20 15:37:32 +020044
Ingo Molnarddcacfa2009-04-20 15:37:32 +020045#include <sys/prctl.h>
Peter Zijlstra16c8a102009-05-05 17:50:27 +020046
Ingo Molnara21ca2c2009-06-06 09:58:57 +020047static struct perf_counter_attr default_attrs[MAX_COUNTERS] = {
48
49 { .type = PERF_TYPE_SOFTWARE, .config = PERF_COUNT_TASK_CLOCK },
50 { .type = PERF_TYPE_SOFTWARE, .config = PERF_COUNT_CONTEXT_SWITCHES },
51 { .type = PERF_TYPE_SOFTWARE, .config = PERF_COUNT_CPU_MIGRATIONS },
52 { .type = PERF_TYPE_SOFTWARE, .config = PERF_COUNT_PAGE_FAULTS },
53
54 { .type = PERF_TYPE_HARDWARE, .config = PERF_COUNT_CPU_CYCLES },
55 { .type = PERF_TYPE_HARDWARE, .config = PERF_COUNT_INSTRUCTIONS },
56 { .type = PERF_TYPE_HARDWARE, .config = PERF_COUNT_CACHE_REFERENCES },
57 { .type = PERF_TYPE_HARDWARE, .config = PERF_COUNT_CACHE_MISSES },
58};
59
Ingo Molnarddcacfa2009-04-20 15:37:32 +020060static int system_wide = 0;
Ingo Molnar52425192009-05-26 09:17:18 +020061static int inherit = 1;
Ingo Molnar743ee1f2009-06-07 17:06:46 +020062static int verbose = 0;
Ingo Molnarddcacfa2009-04-20 15:37:32 +020063
Ingo Molnarddcacfa2009-04-20 15:37:32 +020064static int fd[MAX_NR_CPUS][MAX_COUNTERS];
65
Ingo Molnar52425192009-05-26 09:17:18 +020066static int target_pid = -1;
Ingo Molnarddcacfa2009-04-20 15:37:32 +020067static int nr_cpus = 0;
Ingo Molnarddcacfa2009-04-20 15:37:32 +020068static unsigned int page_size;
69
Ingo Molnar66cf7822009-04-30 13:53:33 +020070static int scale = 1;
Ingo Molnarddcacfa2009-04-20 15:37:32 +020071
72static const unsigned int default_count[] = {
73 1000000,
74 1000000,
75 10000,
76 10000,
77 1000000,
78 10000,
79};
80
Ingo Molnar2996f5d2009-05-29 09:10:54 +020081static __u64 event_res[MAX_COUNTERS][3];
82static __u64 event_scaled[MAX_COUNTERS];
83
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +020084static __u64 runtime_nsecs;
Ingo Molnard7c29312009-05-30 12:38:51 +020085static __u64 walltime_nsecs;
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +020086
Ingo Molnar743ee1f2009-06-07 17:06:46 +020087static void create_perf_stat_counter(int counter)
Ingo Molnarddcacfa2009-04-20 15:37:32 +020088{
Ingo Molnara21ca2c2009-06-06 09:58:57 +020089 struct perf_counter_attr *attr = attrs + counter;
Peter Zijlstra16c8a102009-05-05 17:50:27 +020090
Ingo Molnarddcacfa2009-04-20 15:37:32 +020091 if (scale)
Ingo Molnara21ca2c2009-06-06 09:58:57 +020092 attr->read_format = PERF_FORMAT_TOTAL_TIME_ENABLED |
93 PERF_FORMAT_TOTAL_TIME_RUNNING;
Ingo Molnarddcacfa2009-04-20 15:37:32 +020094
95 if (system_wide) {
96 int cpu;
97 for (cpu = 0; cpu < nr_cpus; cpu ++) {
Ingo Molnara21ca2c2009-06-06 09:58:57 +020098 fd[cpu][counter] = sys_perf_counter_open(attr, -1, cpu, -1, 0);
Ingo Molnar743ee1f2009-06-07 17:06:46 +020099 if (fd[cpu][counter] < 0 && verbose) {
100 printf("Error: counter %d, sys_perf_counter_open() syscall returned with %d (%s)\n", counter, fd[cpu][counter], strerror(errno));
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200101 }
102 }
103 } else {
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200104 attr->inherit = inherit;
105 attr->disabled = 1;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200106
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200107 fd[0][counter] = sys_perf_counter_open(attr, 0, -1, -1, 0);
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200108 if (fd[0][counter] < 0 && verbose) {
109 printf("Error: counter %d, sys_perf_counter_open() syscall returned with %d (%s)\n", counter, fd[0][counter], strerror(errno));
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200110 }
111 }
112}
113
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200114/*
115 * Does the counter have nsecs as a unit?
116 */
117static inline int nsec_counter(int counter)
118{
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200119 if (attrs[counter].type != PERF_TYPE_SOFTWARE)
120 return 0;
121
122 if (attrs[counter].config == PERF_COUNT_CPU_CLOCK)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200123 return 1;
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200124
125 if (attrs[counter].config == PERF_COUNT_TASK_CLOCK)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200126 return 1;
127
128 return 0;
129}
130
131/*
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200132 * Read out the results of a single counter:
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200133 */
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200134static void read_counter(int counter)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200135{
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200136 __u64 *count, single_count[3];
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200137 ssize_t res;
138 int cpu, nv;
139 int scaled;
140
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200141 count = event_res[counter];
142
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200143 count[0] = count[1] = count[2] = 0;
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200144
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200145 nv = scale ? 3 : 1;
146 for (cpu = 0; cpu < nr_cpus; cpu ++) {
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200147 if (fd[cpu][counter] < 0)
148 continue;
149
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200150 res = read(fd[cpu][counter], single_count, nv * sizeof(__u64));
151 assert(res == nv * sizeof(__u64));
152
153 count[0] += single_count[0];
154 if (scale) {
155 count[1] += single_count[1];
156 count[2] += single_count[2];
157 }
158 }
159
160 scaled = 0;
161 if (scale) {
162 if (count[2] == 0) {
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200163 event_scaled[counter] = -1;
164 count[0] = 0;
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200165 return;
166 }
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200167
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200168 if (count[2] < count[1]) {
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200169 event_scaled[counter] = 1;
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200170 count[0] = (unsigned long long)
171 ((double)count[0] * count[1] / count[2] + 0.5);
172 }
173 }
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200174 /*
175 * Save the full runtime - to allow normalization during printout:
176 */
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200177 if (attrs[counter].type == PERF_TYPE_SOFTWARE &&
178 attrs[counter].config == PERF_COUNT_TASK_CLOCK)
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200179 runtime_nsecs = count[0];
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200180}
181
182/*
183 * Print out the results of a single counter:
184 */
185static void print_counter(int counter)
186{
187 __u64 *count;
188 int scaled;
189
190 count = event_res[counter];
191 scaled = event_scaled[counter];
192
193 if (scaled == -1) {
194 fprintf(stderr, " %14s %-20s\n",
195 "<not counted>", event_name(counter));
196 return;
197 }
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200198
199 if (nsec_counter(counter)) {
200 double msecs = (double)count[0] / 1000000;
201
Ingo Molnard7c29312009-05-30 12:38:51 +0200202 fprintf(stderr, " %14.6f %-20s",
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200203 msecs, event_name(counter));
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200204 if (attrs[counter].type == PERF_TYPE_SOFTWARE &&
205 attrs[counter].config == PERF_COUNT_TASK_CLOCK) {
Ingo Molnard7c29312009-05-30 12:38:51 +0200206
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200207 if (walltime_nsecs)
208 fprintf(stderr, " # %11.3f CPU utilization factor",
209 (double)count[0] / (double)walltime_nsecs);
Ingo Molnard7c29312009-05-30 12:38:51 +0200210 }
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200211 } else {
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200212 fprintf(stderr, " %14Ld %-20s",
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200213 count[0], event_name(counter));
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200214 if (runtime_nsecs)
Ingo Molnard7c29312009-05-30 12:38:51 +0200215 fprintf(stderr, " # %11.3f M/sec",
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200216 (double)count[0]/runtime_nsecs*1000.0);
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200217 }
218 if (scaled)
219 fprintf(stderr, " (scaled from %.2f%%)",
220 (double) count[2] / count[1] * 100);
221 fprintf(stderr, "\n");
222}
223
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200224static int do_perf_stat(int argc, const char **argv)
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200225{
226 unsigned long long t0, t1;
227 int counter;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200228 int status;
229 int pid;
Ingo Molnar44db76c2009-06-03 19:36:07 +0200230 int i;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200231
232 if (!system_wide)
233 nr_cpus = 1;
234
235 for (counter = 0; counter < nr_counters; counter++)
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200236 create_perf_stat_counter(counter);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200237
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200238 /*
239 * Enable counters and exec the command:
240 */
241 t0 = rdclock();
242 prctl(PR_TASK_PERF_COUNTERS_ENABLE);
243
244 if ((pid = fork()) < 0)
245 perror("failed to fork");
Ingo Molnar44db76c2009-06-03 19:36:07 +0200246
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200247 if (!pid) {
Ingo Molnar52425192009-05-26 09:17:18 +0200248 if (execvp(argv[0], (char **)argv)) {
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200249 perror(argv[0]);
250 exit(-1);
251 }
252 }
Ingo Molnar44db76c2009-06-03 19:36:07 +0200253
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200254 while (wait(&status) >= 0)
255 ;
Ingo Molnar44db76c2009-06-03 19:36:07 +0200256
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200257 prctl(PR_TASK_PERF_COUNTERS_DISABLE);
258 t1 = rdclock();
259
Ingo Molnard7c29312009-05-30 12:38:51 +0200260 walltime_nsecs = t1 - t0;
261
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200262 fflush(stdout);
263
264 fprintf(stderr, "\n");
Ingo Molnar44db76c2009-06-03 19:36:07 +0200265 fprintf(stderr, " Performance counter stats for \'%s", argv[0]);
266
267 for (i = 1; i < argc; i++)
268 fprintf(stderr, " %s", argv[i]);
269
270 fprintf(stderr, "\':\n");
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200271 fprintf(stderr, "\n");
272
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200273 for (counter = 0; counter < nr_counters; counter++)
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200274 read_counter(counter);
275
276 for (counter = 0; counter < nr_counters; counter++)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200277 print_counter(counter);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200278
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200279
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200280 fprintf(stderr, "\n");
281 fprintf(stderr, " Wall-clock time elapsed: %12.6f msecs\n",
282 (double)(t1-t0)/1e6);
283 fprintf(stderr, "\n");
284
285 return 0;
286}
287
Ingo Molnar52425192009-05-26 09:17:18 +0200288static void skip_signal(int signo)
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200289{
Ingo Molnar52425192009-05-26 09:17:18 +0200290}
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200291
Ingo Molnar52425192009-05-26 09:17:18 +0200292static const char * const stat_usage[] = {
293 "perf stat [<options>] <command>",
294 NULL
295};
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200296
Ingo Molnar52425192009-05-26 09:17:18 +0200297static const struct option options[] = {
298 OPT_CALLBACK('e', "event", NULL, "event",
Thomas Gleixner86847b62009-06-06 12:24:17 +0200299 "event selector. use 'perf list' to list available events",
300 parse_events),
Ingo Molnar52425192009-05-26 09:17:18 +0200301 OPT_BOOLEAN('i', "inherit", &inherit,
302 "child tasks inherit counters"),
303 OPT_INTEGER('p', "pid", &target_pid,
304 "stat events on existing pid"),
305 OPT_BOOLEAN('a', "all-cpus", &system_wide,
306 "system-wide collection from all CPUs"),
Thomas Gleixner86847b62009-06-06 12:24:17 +0200307 OPT_BOOLEAN('S', "scale", &scale,
Ingo Molnar52425192009-05-26 09:17:18 +0200308 "scale/normalize counters"),
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200309 OPT_BOOLEAN('v', "verbose", &verbose,
310 "be more verbose (show counter open errors, etc)"),
Ingo Molnar52425192009-05-26 09:17:18 +0200311 OPT_END()
312};
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200313
Ingo Molnar52425192009-05-26 09:17:18 +0200314int cmd_stat(int argc, const char **argv, const char *prefix)
315{
Ingo Molnar52425192009-05-26 09:17:18 +0200316 page_size = sysconf(_SC_PAGE_SIZE);
317
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200318 memcpy(attrs, default_attrs, sizeof(attrs));
Ingo Molnar52425192009-05-26 09:17:18 +0200319
320 argc = parse_options(argc, argv, options, stat_usage, 0);
321 if (!argc)
322 usage_with_options(stat_usage, options);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200323
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200324 if (!nr_counters)
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200325 nr_counters = 8;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200326
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200327 nr_cpus = sysconf(_SC_NPROCESSORS_ONLN);
328 assert(nr_cpus <= MAX_NR_CPUS);
329 assert(nr_cpus >= 0);
330
Ingo Molnar58d7e992009-05-15 11:03:23 +0200331 /*
332 * We dont want to block the signals - that would cause
333 * child tasks to inherit that and Ctrl-C would not work.
334 * What we want is for Ctrl-C to work in the exec()-ed
335 * task, but being ignored by perf stat itself:
336 */
337 signal(SIGINT, skip_signal);
338 signal(SIGALRM, skip_signal);
339 signal(SIGABRT, skip_signal);
340
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200341 return do_perf_stat(argc, argv);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200342}