blob: 6404906924fa2fb40cbc08e95bba5e38565fa291 [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 Molnare7798982009-06-07 18:14:46 +020086static __u64 runtime_cycles;
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +020087
Ingo Molnar743ee1f2009-06-07 17:06:46 +020088static void create_perf_stat_counter(int counter)
Ingo Molnarddcacfa2009-04-20 15:37:32 +020089{
Ingo Molnara21ca2c2009-06-06 09:58:57 +020090 struct perf_counter_attr *attr = attrs + counter;
Peter Zijlstra16c8a102009-05-05 17:50:27 +020091
Ingo Molnarddcacfa2009-04-20 15:37:32 +020092 if (scale)
Ingo Molnara21ca2c2009-06-06 09:58:57 +020093 attr->read_format = PERF_FORMAT_TOTAL_TIME_ENABLED |
94 PERF_FORMAT_TOTAL_TIME_RUNNING;
Ingo Molnarddcacfa2009-04-20 15:37:32 +020095
96 if (system_wide) {
97 int cpu;
98 for (cpu = 0; cpu < nr_cpus; cpu ++) {
Ingo Molnara21ca2c2009-06-06 09:58:57 +020099 fd[cpu][counter] = sys_perf_counter_open(attr, -1, cpu, -1, 0);
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200100 if (fd[cpu][counter] < 0 && verbose) {
101 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 +0200102 }
103 }
104 } else {
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200105 attr->inherit = inherit;
106 attr->disabled = 1;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200107
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200108 fd[0][counter] = sys_perf_counter_open(attr, 0, -1, -1, 0);
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200109 if (fd[0][counter] < 0 && verbose) {
110 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 +0200111 }
112 }
113}
114
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200115/*
116 * Does the counter have nsecs as a unit?
117 */
118static inline int nsec_counter(int counter)
119{
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200120 if (attrs[counter].type != PERF_TYPE_SOFTWARE)
121 return 0;
122
123 if (attrs[counter].config == PERF_COUNT_CPU_CLOCK)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200124 return 1;
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200125
126 if (attrs[counter].config == PERF_COUNT_TASK_CLOCK)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200127 return 1;
128
129 return 0;
130}
131
132/*
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200133 * Read out the results of a single counter:
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200134 */
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200135static void read_counter(int counter)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200136{
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200137 __u64 *count, single_count[3];
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200138 ssize_t res;
139 int cpu, nv;
140 int scaled;
141
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200142 count = event_res[counter];
143
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200144 count[0] = count[1] = count[2] = 0;
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200145
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200146 nv = scale ? 3 : 1;
147 for (cpu = 0; cpu < nr_cpus; cpu ++) {
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200148 if (fd[cpu][counter] < 0)
149 continue;
150
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200151 res = read(fd[cpu][counter], single_count, nv * sizeof(__u64));
152 assert(res == nv * sizeof(__u64));
153
154 count[0] += single_count[0];
155 if (scale) {
156 count[1] += single_count[1];
157 count[2] += single_count[2];
158 }
159 }
160
161 scaled = 0;
162 if (scale) {
163 if (count[2] == 0) {
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200164 event_scaled[counter] = -1;
165 count[0] = 0;
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200166 return;
167 }
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200168
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200169 if (count[2] < count[1]) {
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200170 event_scaled[counter] = 1;
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200171 count[0] = (unsigned long long)
172 ((double)count[0] * count[1] / count[2] + 0.5);
173 }
174 }
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200175 /*
176 * Save the full runtime - to allow normalization during printout:
177 */
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200178 if (attrs[counter].type == PERF_TYPE_SOFTWARE &&
179 attrs[counter].config == PERF_COUNT_TASK_CLOCK)
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200180 runtime_nsecs = count[0];
Ingo Molnare7798982009-06-07 18:14:46 +0200181 if (attrs[counter].type == PERF_TYPE_HARDWARE &&
182 attrs[counter].config == PERF_COUNT_CPU_CYCLES)
183 runtime_cycles = count[0];
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200184}
185
186/*
187 * Print out the results of a single counter:
188 */
189static void print_counter(int counter)
190{
191 __u64 *count;
192 int scaled;
193
194 count = event_res[counter];
195 scaled = event_scaled[counter];
196
197 if (scaled == -1) {
198 fprintf(stderr, " %14s %-20s\n",
199 "<not counted>", event_name(counter));
200 return;
201 }
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200202
203 if (nsec_counter(counter)) {
204 double msecs = (double)count[0] / 1000000;
205
Ingo Molnard7c29312009-05-30 12:38:51 +0200206 fprintf(stderr, " %14.6f %-20s",
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200207 msecs, event_name(counter));
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200208 if (attrs[counter].type == PERF_TYPE_SOFTWARE &&
209 attrs[counter].config == PERF_COUNT_TASK_CLOCK) {
Ingo Molnard7c29312009-05-30 12:38:51 +0200210
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200211 if (walltime_nsecs)
212 fprintf(stderr, " # %11.3f CPU utilization factor",
213 (double)count[0] / (double)walltime_nsecs);
Ingo Molnard7c29312009-05-30 12:38:51 +0200214 }
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200215 } else {
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200216 fprintf(stderr, " %14Ld %-20s",
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200217 count[0], event_name(counter));
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200218 if (runtime_nsecs)
Ingo Molnard7c29312009-05-30 12:38:51 +0200219 fprintf(stderr, " # %11.3f M/sec",
Ingo Molnarbe1ac0d2009-05-29 09:10:54 +0200220 (double)count[0]/runtime_nsecs*1000.0);
Ingo Molnare7798982009-06-07 18:14:46 +0200221 if (runtime_cycles &&
222 attrs[counter].type == PERF_TYPE_HARDWARE &&
223 attrs[counter].config == PERF_COUNT_INSTRUCTIONS) {
224
225 fprintf(stderr, " # %1.3f per cycle",
226 (double)count[0] / (double)runtime_cycles);
227 }
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200228 }
229 if (scaled)
230 fprintf(stderr, " (scaled from %.2f%%)",
231 (double) count[2] / count[1] * 100);
232 fprintf(stderr, "\n");
233}
234
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200235static int do_perf_stat(int argc, const char **argv)
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200236{
237 unsigned long long t0, t1;
238 int counter;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200239 int status;
240 int pid;
Ingo Molnar44db76c2009-06-03 19:36:07 +0200241 int i;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200242
243 if (!system_wide)
244 nr_cpus = 1;
245
246 for (counter = 0; counter < nr_counters; counter++)
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200247 create_perf_stat_counter(counter);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200248
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200249 /*
250 * Enable counters and exec the command:
251 */
252 t0 = rdclock();
253 prctl(PR_TASK_PERF_COUNTERS_ENABLE);
254
255 if ((pid = fork()) < 0)
256 perror("failed to fork");
Ingo Molnar44db76c2009-06-03 19:36:07 +0200257
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200258 if (!pid) {
Ingo Molnar52425192009-05-26 09:17:18 +0200259 if (execvp(argv[0], (char **)argv)) {
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200260 perror(argv[0]);
261 exit(-1);
262 }
263 }
Ingo Molnar44db76c2009-06-03 19:36:07 +0200264
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200265 while (wait(&status) >= 0)
266 ;
Ingo Molnar44db76c2009-06-03 19:36:07 +0200267
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200268 prctl(PR_TASK_PERF_COUNTERS_DISABLE);
269 t1 = rdclock();
270
Ingo Molnard7c29312009-05-30 12:38:51 +0200271 walltime_nsecs = t1 - t0;
272
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200273 fflush(stdout);
274
275 fprintf(stderr, "\n");
Ingo Molnar44db76c2009-06-03 19:36:07 +0200276 fprintf(stderr, " Performance counter stats for \'%s", argv[0]);
277
278 for (i = 1; i < argc; i++)
279 fprintf(stderr, " %s", argv[i]);
280
281 fprintf(stderr, "\':\n");
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200282 fprintf(stderr, "\n");
283
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200284 for (counter = 0; counter < nr_counters; counter++)
Ingo Molnar2996f5d2009-05-29 09:10:54 +0200285 read_counter(counter);
286
287 for (counter = 0; counter < nr_counters; counter++)
Ingo Molnarc04f5e52009-05-29 09:10:54 +0200288 print_counter(counter);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200289
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200290
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200291 fprintf(stderr, "\n");
292 fprintf(stderr, " Wall-clock time elapsed: %12.6f msecs\n",
293 (double)(t1-t0)/1e6);
294 fprintf(stderr, "\n");
295
296 return 0;
297}
298
Peter Zijlstraf7b7c262009-06-10 15:55:59 +0200299static volatile int signr = -1;
300
Ingo Molnar52425192009-05-26 09:17:18 +0200301static void skip_signal(int signo)
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200302{
Peter Zijlstraf7b7c262009-06-10 15:55:59 +0200303 signr = signo;
304}
305
306static void sig_atexit(void)
307{
308 if (signr == -1)
309 return;
310
311 signal(signr, SIG_DFL);
312 kill(getpid(), signr);
Ingo Molnar52425192009-05-26 09:17:18 +0200313}
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200314
Ingo Molnar52425192009-05-26 09:17:18 +0200315static const char * const stat_usage[] = {
316 "perf stat [<options>] <command>",
317 NULL
318};
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200319
Ingo Molnar52425192009-05-26 09:17:18 +0200320static const struct option options[] = {
321 OPT_CALLBACK('e', "event", NULL, "event",
Thomas Gleixner86847b62009-06-06 12:24:17 +0200322 "event selector. use 'perf list' to list available events",
323 parse_events),
Ingo Molnar52425192009-05-26 09:17:18 +0200324 OPT_BOOLEAN('i', "inherit", &inherit,
325 "child tasks inherit counters"),
326 OPT_INTEGER('p', "pid", &target_pid,
327 "stat events on existing pid"),
328 OPT_BOOLEAN('a', "all-cpus", &system_wide,
329 "system-wide collection from all CPUs"),
Thomas Gleixner86847b62009-06-06 12:24:17 +0200330 OPT_BOOLEAN('S', "scale", &scale,
Ingo Molnar52425192009-05-26 09:17:18 +0200331 "scale/normalize counters"),
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200332 OPT_BOOLEAN('v', "verbose", &verbose,
333 "be more verbose (show counter open errors, etc)"),
Ingo Molnar52425192009-05-26 09:17:18 +0200334 OPT_END()
335};
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200336
Ingo Molnar52425192009-05-26 09:17:18 +0200337int cmd_stat(int argc, const char **argv, const char *prefix)
338{
Ingo Molnar52425192009-05-26 09:17:18 +0200339 page_size = sysconf(_SC_PAGE_SIZE);
340
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200341 memcpy(attrs, default_attrs, sizeof(attrs));
Ingo Molnar52425192009-05-26 09:17:18 +0200342
343 argc = parse_options(argc, argv, options, stat_usage, 0);
344 if (!argc)
345 usage_with_options(stat_usage, options);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200346
Ingo Molnara21ca2c2009-06-06 09:58:57 +0200347 if (!nr_counters)
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200348 nr_counters = 8;
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200349
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200350 nr_cpus = sysconf(_SC_NPROCESSORS_ONLN);
351 assert(nr_cpus <= MAX_NR_CPUS);
352 assert(nr_cpus >= 0);
353
Ingo Molnar58d7e992009-05-15 11:03:23 +0200354 /*
355 * We dont want to block the signals - that would cause
356 * child tasks to inherit that and Ctrl-C would not work.
357 * What we want is for Ctrl-C to work in the exec()-ed
358 * task, but being ignored by perf stat itself:
359 */
Peter Zijlstraf7b7c262009-06-10 15:55:59 +0200360 atexit(sig_atexit);
Ingo Molnar58d7e992009-05-15 11:03:23 +0200361 signal(SIGINT, skip_signal);
362 signal(SIGALRM, skip_signal);
363 signal(SIGABRT, skip_signal);
364
Ingo Molnar743ee1f2009-06-07 17:06:46 +0200365 return do_perf_stat(argc, argv);
Ingo Molnarddcacfa2009-04-20 15:37:32 +0200366}