5 #include "util/cache.h"
6 #include "util/symbol.h"
7 #include "util/thread.h"
8 #include "util/header.h"
10 #include "util/parse-options.h"
11 #include "util/trace-event.h"
13 #include "util/debug.h"
14 #include "util/data_map.h"
16 #include <sys/types.h>
17 #include <sys/prctl.h>
19 #include <semaphore.h>
23 static char const *input_name
= "perf.data";
25 static struct perf_header
*header
;
26 static u64 sample_type
;
28 static char default_sort_order
[] = "avg, max, switch, runtime";
29 static char *sort_order
= default_sort_order
;
31 static int profile_cpu
= -1;
33 #define PR_SET_NAME 15 /* Set process name */
36 static u64 run_measurement_overhead
;
37 static u64 sleep_measurement_overhead
;
44 static unsigned long nr_tasks
;
53 unsigned long nr_events
;
54 unsigned long curr_event
;
55 struct sched_atom
**atoms
;
66 enum sched_event_type
{
70 SCHED_EVENT_MIGRATION
,
74 enum sched_event_type type
;
80 struct task_desc
*wakee
;
83 static struct task_desc
*pid_to_task
[MAX_PID
];
85 static struct task_desc
**tasks
;
87 static pthread_mutex_t start_work_mutex
= PTHREAD_MUTEX_INITIALIZER
;
88 static u64 start_time
;
90 static pthread_mutex_t work_done_wait_mutex
= PTHREAD_MUTEX_INITIALIZER
;
92 static unsigned long nr_run_events
;
93 static unsigned long nr_sleep_events
;
94 static unsigned long nr_wakeup_events
;
96 static unsigned long nr_sleep_corrections
;
97 static unsigned long nr_run_events_optimized
;
99 static unsigned long targetless_wakeups
;
100 static unsigned long multitarget_wakeups
;
102 static u64 cpu_usage
;
103 static u64 runavg_cpu_usage
;
104 static u64 parent_cpu_usage
;
105 static u64 runavg_parent_cpu_usage
;
107 static unsigned long nr_runs
;
108 static u64 sum_runtime
;
109 static u64 sum_fluct
;
112 static unsigned long replay_repeat
= 10;
113 static unsigned long nr_timestamps
;
114 static unsigned long nr_unordered_timestamps
;
115 static unsigned long nr_state_machine_bugs
;
116 static unsigned long nr_context_switch_bugs
;
117 static unsigned long nr_events
;
118 static unsigned long nr_lost_chunks
;
119 static unsigned long nr_lost_events
;
121 #define TASK_STATE_TO_CHAR_STR "RSDTtZX"
131 struct list_head list
;
132 enum thread_state state
;
140 struct list_head work_list
;
141 struct thread
*thread
;
149 typedef int (*sort_fn_t
)(struct work_atoms
*, struct work_atoms
*);
151 static struct rb_root atom_root
, sorted_atom_root
;
153 static u64 all_runtime
;
154 static u64 all_count
;
157 static u64
get_nsecs(void)
161 clock_gettime(CLOCK_MONOTONIC
, &ts
);
163 return ts
.tv_sec
* 1000000000ULL + ts
.tv_nsec
;
166 static void burn_nsecs(u64 nsecs
)
168 u64 T0
= get_nsecs(), T1
;
172 } while (T1
+ run_measurement_overhead
< T0
+ nsecs
);
175 static void sleep_nsecs(u64 nsecs
)
179 ts
.tv_nsec
= nsecs
% 999999999;
180 ts
.tv_sec
= nsecs
/ 999999999;
182 nanosleep(&ts
, NULL
);
185 static void calibrate_run_measurement_overhead(void)
187 u64 T0
, T1
, delta
, min_delta
= 1000000000ULL;
190 for (i
= 0; i
< 10; i
++) {
195 min_delta
= min(min_delta
, delta
);
197 run_measurement_overhead
= min_delta
;
199 printf("run measurement overhead: %Ld nsecs\n", min_delta
);
202 static void calibrate_sleep_measurement_overhead(void)
204 u64 T0
, T1
, delta
, min_delta
= 1000000000ULL;
207 for (i
= 0; i
< 10; i
++) {
212 min_delta
= min(min_delta
, delta
);
215 sleep_measurement_overhead
= min_delta
;
217 printf("sleep measurement overhead: %Ld nsecs\n", min_delta
);
220 static struct sched_atom
*
221 get_new_event(struct task_desc
*task
, u64 timestamp
)
223 struct sched_atom
*event
= zalloc(sizeof(*event
));
224 unsigned long idx
= task
->nr_events
;
227 event
->timestamp
= timestamp
;
231 size
= sizeof(struct sched_atom
*) * task
->nr_events
;
232 task
->atoms
= realloc(task
->atoms
, size
);
233 BUG_ON(!task
->atoms
);
235 task
->atoms
[idx
] = event
;
240 static struct sched_atom
*last_event(struct task_desc
*task
)
242 if (!task
->nr_events
)
245 return task
->atoms
[task
->nr_events
- 1];
249 add_sched_event_run(struct task_desc
*task
, u64 timestamp
, u64 duration
)
251 struct sched_atom
*event
, *curr_event
= last_event(task
);
254 * optimize an existing RUN event by merging this one
257 if (curr_event
&& curr_event
->type
== SCHED_EVENT_RUN
) {
258 nr_run_events_optimized
++;
259 curr_event
->duration
+= duration
;
263 event
= get_new_event(task
, timestamp
);
265 event
->type
= SCHED_EVENT_RUN
;
266 event
->duration
= duration
;
272 add_sched_event_wakeup(struct task_desc
*task
, u64 timestamp
,
273 struct task_desc
*wakee
)
275 struct sched_atom
*event
, *wakee_event
;
277 event
= get_new_event(task
, timestamp
);
278 event
->type
= SCHED_EVENT_WAKEUP
;
279 event
->wakee
= wakee
;
281 wakee_event
= last_event(wakee
);
282 if (!wakee_event
|| wakee_event
->type
!= SCHED_EVENT_SLEEP
) {
283 targetless_wakeups
++;
286 if (wakee_event
->wait_sem
) {
287 multitarget_wakeups
++;
291 wakee_event
->wait_sem
= zalloc(sizeof(*wakee_event
->wait_sem
));
292 sem_init(wakee_event
->wait_sem
, 0, 0);
293 wakee_event
->specific_wait
= 1;
294 event
->wait_sem
= wakee_event
->wait_sem
;
300 add_sched_event_sleep(struct task_desc
*task
, u64 timestamp
,
301 u64 task_state __used
)
303 struct sched_atom
*event
= get_new_event(task
, timestamp
);
305 event
->type
= SCHED_EVENT_SLEEP
;
310 static struct task_desc
*register_pid(unsigned long pid
, const char *comm
)
312 struct task_desc
*task
;
314 BUG_ON(pid
>= MAX_PID
);
316 task
= pid_to_task
[pid
];
321 task
= zalloc(sizeof(*task
));
324 strcpy(task
->comm
, comm
);
326 * every task starts in sleeping state - this gets ignored
327 * if there's no wakeup pointing to this sleep state:
329 add_sched_event_sleep(task
, 0, 0);
331 pid_to_task
[pid
] = task
;
333 tasks
= realloc(tasks
, nr_tasks
*sizeof(struct task_task
*));
335 tasks
[task
->nr
] = task
;
338 printf("registered task #%ld, PID %ld (%s)\n", nr_tasks
, pid
, comm
);
344 static void print_task_traces(void)
346 struct task_desc
*task
;
349 for (i
= 0; i
< nr_tasks
; i
++) {
351 printf("task %6ld (%20s:%10ld), nr_events: %ld\n",
352 task
->nr
, task
->comm
, task
->pid
, task
->nr_events
);
356 static void add_cross_task_wakeups(void)
358 struct task_desc
*task1
, *task2
;
361 for (i
= 0; i
< nr_tasks
; i
++) {
367 add_sched_event_wakeup(task1
, 0, task2
);
372 process_sched_event(struct task_desc
*this_task __used
, struct sched_atom
*atom
)
379 delta
= start_time
+ atom
->timestamp
- now
;
381 switch (atom
->type
) {
382 case SCHED_EVENT_RUN
:
383 burn_nsecs(atom
->duration
);
385 case SCHED_EVENT_SLEEP
:
387 ret
= sem_wait(atom
->wait_sem
);
390 case SCHED_EVENT_WAKEUP
:
392 ret
= sem_post(atom
->wait_sem
);
395 case SCHED_EVENT_MIGRATION
:
402 static u64
get_cpu_usage_nsec_parent(void)
408 err
= getrusage(RUSAGE_SELF
, &ru
);
411 sum
= ru
.ru_utime
.tv_sec
*1e9
+ ru
.ru_utime
.tv_usec
*1e3
;
412 sum
+= ru
.ru_stime
.tv_sec
*1e9
+ ru
.ru_stime
.tv_usec
*1e3
;
417 static u64
get_cpu_usage_nsec_self(void)
419 char filename
[] = "/proc/1234567890/sched";
420 unsigned long msecs
, nsecs
;
428 sprintf(filename
, "/proc/%d/sched", getpid());
429 file
= fopen(filename
, "r");
432 while ((chars
= getline(&line
, &len
, file
)) != -1) {
433 ret
= sscanf(line
, "se.sum_exec_runtime : %ld.%06ld\n",
436 total
= msecs
*1e6
+ nsecs
;
447 static void *thread_func(void *ctx
)
449 struct task_desc
*this_task
= ctx
;
450 u64 cpu_usage_0
, cpu_usage_1
;
451 unsigned long i
, ret
;
454 sprintf(comm2
, ":%s", this_task
->comm
);
455 prctl(PR_SET_NAME
, comm2
);
458 ret
= sem_post(&this_task
->ready_for_work
);
460 ret
= pthread_mutex_lock(&start_work_mutex
);
462 ret
= pthread_mutex_unlock(&start_work_mutex
);
465 cpu_usage_0
= get_cpu_usage_nsec_self();
467 for (i
= 0; i
< this_task
->nr_events
; i
++) {
468 this_task
->curr_event
= i
;
469 process_sched_event(this_task
, this_task
->atoms
[i
]);
472 cpu_usage_1
= get_cpu_usage_nsec_self();
473 this_task
->cpu_usage
= cpu_usage_1
- cpu_usage_0
;
475 ret
= sem_post(&this_task
->work_done_sem
);
478 ret
= pthread_mutex_lock(&work_done_wait_mutex
);
480 ret
= pthread_mutex_unlock(&work_done_wait_mutex
);
486 static void create_tasks(void)
488 struct task_desc
*task
;
493 err
= pthread_attr_init(&attr
);
495 err
= pthread_attr_setstacksize(&attr
, (size_t)(16*1024));
497 err
= pthread_mutex_lock(&start_work_mutex
);
499 err
= pthread_mutex_lock(&work_done_wait_mutex
);
501 for (i
= 0; i
< nr_tasks
; i
++) {
503 sem_init(&task
->sleep_sem
, 0, 0);
504 sem_init(&task
->ready_for_work
, 0, 0);
505 sem_init(&task
->work_done_sem
, 0, 0);
506 task
->curr_event
= 0;
507 err
= pthread_create(&task
->thread
, &attr
, thread_func
, task
);
512 static void wait_for_tasks(void)
514 u64 cpu_usage_0
, cpu_usage_1
;
515 struct task_desc
*task
;
516 unsigned long i
, ret
;
518 start_time
= get_nsecs();
520 pthread_mutex_unlock(&work_done_wait_mutex
);
522 for (i
= 0; i
< nr_tasks
; i
++) {
524 ret
= sem_wait(&task
->ready_for_work
);
526 sem_init(&task
->ready_for_work
, 0, 0);
528 ret
= pthread_mutex_lock(&work_done_wait_mutex
);
531 cpu_usage_0
= get_cpu_usage_nsec_parent();
533 pthread_mutex_unlock(&start_work_mutex
);
535 for (i
= 0; i
< nr_tasks
; i
++) {
537 ret
= sem_wait(&task
->work_done_sem
);
539 sem_init(&task
->work_done_sem
, 0, 0);
540 cpu_usage
+= task
->cpu_usage
;
544 cpu_usage_1
= get_cpu_usage_nsec_parent();
545 if (!runavg_cpu_usage
)
546 runavg_cpu_usage
= cpu_usage
;
547 runavg_cpu_usage
= (runavg_cpu_usage
*9 + cpu_usage
)/10;
549 parent_cpu_usage
= cpu_usage_1
- cpu_usage_0
;
550 if (!runavg_parent_cpu_usage
)
551 runavg_parent_cpu_usage
= parent_cpu_usage
;
552 runavg_parent_cpu_usage
= (runavg_parent_cpu_usage
*9 +
553 parent_cpu_usage
)/10;
555 ret
= pthread_mutex_lock(&start_work_mutex
);
558 for (i
= 0; i
< nr_tasks
; i
++) {
560 sem_init(&task
->sleep_sem
, 0, 0);
561 task
->curr_event
= 0;
565 static void run_one_test(void)
567 u64 T0
, T1
, delta
, avg_delta
, fluct
, std_dev
;
574 sum_runtime
+= delta
;
577 avg_delta
= sum_runtime
/ nr_runs
;
578 if (delta
< avg_delta
)
579 fluct
= avg_delta
- delta
;
581 fluct
= delta
- avg_delta
;
583 std_dev
= sum_fluct
/ nr_runs
/ sqrt(nr_runs
);
586 run_avg
= (run_avg
*9 + delta
)/10;
588 printf("#%-3ld: %0.3f, ",
589 nr_runs
, (double)delta
/1000000.0);
591 printf("ravg: %0.2f, ",
592 (double)run_avg
/1e6
);
594 printf("cpu: %0.2f / %0.2f",
595 (double)cpu_usage
/1e6
, (double)runavg_cpu_usage
/1e6
);
599 * rusage statistics done by the parent, these are less
600 * accurate than the sum_exec_runtime based statistics:
602 printf(" [%0.2f / %0.2f]",
603 (double)parent_cpu_usage
/1e6
,
604 (double)runavg_parent_cpu_usage
/1e6
);
609 if (nr_sleep_corrections
)
610 printf(" (%ld sleep corrections)\n", nr_sleep_corrections
);
611 nr_sleep_corrections
= 0;
614 static void test_calibrations(void)
622 printf("the run test took %Ld nsecs\n", T1
-T0
);
628 printf("the sleep test took %Ld nsecs\n", T1
-T0
);
631 struct raw_event_sample
{
636 #define FILL_FIELD(ptr, field, event, data) \
637 ptr.field = (typeof(ptr.field)) raw_field_value(event, #field, data)
639 #define FILL_ARRAY(ptr, array, event, data) \
641 void *__array = raw_field_ptr(event, #array, data); \
642 memcpy(ptr.array, __array, sizeof(ptr.array)); \
645 #define FILL_COMMON_FIELDS(ptr, event, data) \
647 FILL_FIELD(ptr, common_type, event, data); \
648 FILL_FIELD(ptr, common_flags, event, data); \
649 FILL_FIELD(ptr, common_preempt_count, event, data); \
650 FILL_FIELD(ptr, common_pid, event, data); \
651 FILL_FIELD(ptr, common_tgid, event, data); \
656 struct trace_switch_event
{
661 u8 common_preempt_count
;
674 struct trace_runtime_event
{
679 u8 common_preempt_count
;
689 struct trace_wakeup_event
{
694 u8 common_preempt_count
;
706 struct trace_fork_event
{
711 u8 common_preempt_count
;
715 char parent_comm
[16];
721 struct trace_migrate_task_event
{
726 u8 common_preempt_count
;
737 struct trace_sched_handler
{
738 void (*switch_event
)(struct trace_switch_event
*,
742 struct thread
*thread
);
744 void (*runtime_event
)(struct trace_runtime_event
*,
748 struct thread
*thread
);
750 void (*wakeup_event
)(struct trace_wakeup_event
*,
754 struct thread
*thread
);
756 void (*fork_event
)(struct trace_fork_event
*,
760 struct thread
*thread
);
762 void (*migrate_task_event
)(struct trace_migrate_task_event
*,
766 struct thread
*thread
);
771 replay_wakeup_event(struct trace_wakeup_event
*wakeup_event
,
774 u64 timestamp __used
,
775 struct thread
*thread __used
)
777 struct task_desc
*waker
, *wakee
;
780 printf("sched_wakeup event %p\n", event
);
782 printf(" ... pid %d woke up %s/%d\n",
783 wakeup_event
->common_pid
,
788 waker
= register_pid(wakeup_event
->common_pid
, "<unknown>");
789 wakee
= register_pid(wakeup_event
->pid
, wakeup_event
->comm
);
791 add_sched_event_wakeup(waker
, timestamp
, wakee
);
794 static u64 cpu_last_switched
[MAX_CPUS
];
797 replay_switch_event(struct trace_switch_event
*switch_event
,
801 struct thread
*thread __used
)
803 struct task_desc
*prev
, *next
;
808 printf("sched_switch event %p\n", event
);
810 if (cpu
>= MAX_CPUS
|| cpu
< 0)
813 timestamp0
= cpu_last_switched
[cpu
];
815 delta
= timestamp
- timestamp0
;
820 die("hm, delta: %Ld < 0 ?\n", delta
);
823 printf(" ... switch from %s/%d to %s/%d [ran %Ld nsecs]\n",
824 switch_event
->prev_comm
, switch_event
->prev_pid
,
825 switch_event
->next_comm
, switch_event
->next_pid
,
829 prev
= register_pid(switch_event
->prev_pid
, switch_event
->prev_comm
);
830 next
= register_pid(switch_event
->next_pid
, switch_event
->next_comm
);
832 cpu_last_switched
[cpu
] = timestamp
;
834 add_sched_event_run(prev
, timestamp
, delta
);
835 add_sched_event_sleep(prev
, timestamp
, switch_event
->prev_state
);
840 replay_fork_event(struct trace_fork_event
*fork_event
,
843 u64 timestamp __used
,
844 struct thread
*thread __used
)
847 printf("sched_fork event %p\n", event
);
848 printf("... parent: %s/%d\n", fork_event
->parent_comm
, fork_event
->parent_pid
);
849 printf("... child: %s/%d\n", fork_event
->child_comm
, fork_event
->child_pid
);
851 register_pid(fork_event
->parent_pid
, fork_event
->parent_comm
);
852 register_pid(fork_event
->child_pid
, fork_event
->child_comm
);
855 static struct trace_sched_handler replay_ops
= {
856 .wakeup_event
= replay_wakeup_event
,
857 .switch_event
= replay_switch_event
,
858 .fork_event
= replay_fork_event
,
861 struct sort_dimension
{
864 struct list_head list
;
867 static LIST_HEAD(cmp_pid
);
870 thread_lat_cmp(struct list_head
*list
, struct work_atoms
*l
, struct work_atoms
*r
)
872 struct sort_dimension
*sort
;
875 BUG_ON(list_empty(list
));
877 list_for_each_entry(sort
, list
, list
) {
878 ret
= sort
->cmp(l
, r
);
886 static struct work_atoms
*
887 thread_atoms_search(struct rb_root
*root
, struct thread
*thread
,
888 struct list_head
*sort_list
)
890 struct rb_node
*node
= root
->rb_node
;
891 struct work_atoms key
= { .thread
= thread
};
894 struct work_atoms
*atoms
;
897 atoms
= container_of(node
, struct work_atoms
, node
);
899 cmp
= thread_lat_cmp(sort_list
, &key
, atoms
);
901 node
= node
->rb_left
;
903 node
= node
->rb_right
;
905 BUG_ON(thread
!= atoms
->thread
);
913 __thread_latency_insert(struct rb_root
*root
, struct work_atoms
*data
,
914 struct list_head
*sort_list
)
916 struct rb_node
**new = &(root
->rb_node
), *parent
= NULL
;
919 struct work_atoms
*this;
922 this = container_of(*new, struct work_atoms
, node
);
925 cmp
= thread_lat_cmp(sort_list
, data
, this);
928 new = &((*new)->rb_left
);
930 new = &((*new)->rb_right
);
933 rb_link_node(&data
->node
, parent
, new);
934 rb_insert_color(&data
->node
, root
);
937 static void thread_atoms_insert(struct thread
*thread
)
939 struct work_atoms
*atoms
= zalloc(sizeof(*atoms
));
943 atoms
->thread
= thread
;
944 INIT_LIST_HEAD(&atoms
->work_list
);
945 __thread_latency_insert(&atom_root
, atoms
, &cmp_pid
);
949 latency_fork_event(struct trace_fork_event
*fork_event __used
,
950 struct event
*event __used
,
952 u64 timestamp __used
,
953 struct thread
*thread __used
)
955 /* should insert the newcomer */
959 static char sched_out_state(struct trace_switch_event
*switch_event
)
961 const char *str
= TASK_STATE_TO_CHAR_STR
;
963 return str
[switch_event
->prev_state
];
967 add_sched_out_event(struct work_atoms
*atoms
,
971 struct work_atom
*atom
= zalloc(sizeof(*atom
));
975 atom
->sched_out_time
= timestamp
;
977 if (run_state
== 'R') {
978 atom
->state
= THREAD_WAIT_CPU
;
979 atom
->wake_up_time
= atom
->sched_out_time
;
982 list_add_tail(&atom
->list
, &atoms
->work_list
);
986 add_runtime_event(struct work_atoms
*atoms
, u64 delta
, u64 timestamp __used
)
988 struct work_atom
*atom
;
990 BUG_ON(list_empty(&atoms
->work_list
));
992 atom
= list_entry(atoms
->work_list
.prev
, struct work_atom
, list
);
994 atom
->runtime
+= delta
;
995 atoms
->total_runtime
+= delta
;
999 add_sched_in_event(struct work_atoms
*atoms
, u64 timestamp
)
1001 struct work_atom
*atom
;
1004 if (list_empty(&atoms
->work_list
))
1007 atom
= list_entry(atoms
->work_list
.prev
, struct work_atom
, list
);
1009 if (atom
->state
!= THREAD_WAIT_CPU
)
1012 if (timestamp
< atom
->wake_up_time
) {
1013 atom
->state
= THREAD_IGNORE
;
1017 atom
->state
= THREAD_SCHED_IN
;
1018 atom
->sched_in_time
= timestamp
;
1020 delta
= atom
->sched_in_time
- atom
->wake_up_time
;
1021 atoms
->total_lat
+= delta
;
1022 if (delta
> atoms
->max_lat
)
1023 atoms
->max_lat
= delta
;
1028 latency_switch_event(struct trace_switch_event
*switch_event
,
1029 struct event
*event __used
,
1032 struct thread
*thread __used
)
1034 struct work_atoms
*out_events
, *in_events
;
1035 struct thread
*sched_out
, *sched_in
;
1039 BUG_ON(cpu
>= MAX_CPUS
|| cpu
< 0);
1041 timestamp0
= cpu_last_switched
[cpu
];
1042 cpu_last_switched
[cpu
] = timestamp
;
1044 delta
= timestamp
- timestamp0
;
1049 die("hm, delta: %Ld < 0 ?\n", delta
);
1052 sched_out
= threads__findnew(switch_event
->prev_pid
);
1053 sched_in
= threads__findnew(switch_event
->next_pid
);
1055 out_events
= thread_atoms_search(&atom_root
, sched_out
, &cmp_pid
);
1057 thread_atoms_insert(sched_out
);
1058 out_events
= thread_atoms_search(&atom_root
, sched_out
, &cmp_pid
);
1060 die("out-event: Internal tree error");
1062 add_sched_out_event(out_events
, sched_out_state(switch_event
), timestamp
);
1064 in_events
= thread_atoms_search(&atom_root
, sched_in
, &cmp_pid
);
1066 thread_atoms_insert(sched_in
);
1067 in_events
= thread_atoms_search(&atom_root
, sched_in
, &cmp_pid
);
1069 die("in-event: Internal tree error");
1071 * Take came in we have not heard about yet,
1072 * add in an initial atom in runnable state:
1074 add_sched_out_event(in_events
, 'R', timestamp
);
1076 add_sched_in_event(in_events
, timestamp
);
1080 latency_runtime_event(struct trace_runtime_event
*runtime_event
,
1081 struct event
*event __used
,
1084 struct thread
*this_thread __used
)
1086 struct thread
*thread
= threads__findnew(runtime_event
->pid
);
1087 struct work_atoms
*atoms
= thread_atoms_search(&atom_root
, thread
, &cmp_pid
);
1089 BUG_ON(cpu
>= MAX_CPUS
|| cpu
< 0);
1091 thread_atoms_insert(thread
);
1092 atoms
= thread_atoms_search(&atom_root
, thread
, &cmp_pid
);
1094 die("in-event: Internal tree error");
1095 add_sched_out_event(atoms
, 'R', timestamp
);
1098 add_runtime_event(atoms
, runtime_event
->runtime
, timestamp
);
1102 latency_wakeup_event(struct trace_wakeup_event
*wakeup_event
,
1103 struct event
*__event __used
,
1106 struct thread
*thread __used
)
1108 struct work_atoms
*atoms
;
1109 struct work_atom
*atom
;
1110 struct thread
*wakee
;
1112 /* Note for later, it may be interesting to observe the failing cases */
1113 if (!wakeup_event
->success
)
1116 wakee
= threads__findnew(wakeup_event
->pid
);
1117 atoms
= thread_atoms_search(&atom_root
, wakee
, &cmp_pid
);
1119 thread_atoms_insert(wakee
);
1120 atoms
= thread_atoms_search(&atom_root
, wakee
, &cmp_pid
);
1122 die("wakeup-event: Internal tree error");
1123 add_sched_out_event(atoms
, 'S', timestamp
);
1126 BUG_ON(list_empty(&atoms
->work_list
));
1128 atom
= list_entry(atoms
->work_list
.prev
, struct work_atom
, list
);
1131 * You WILL be missing events if you've recorded only
1132 * one CPU, or are only looking at only one, so don't
1133 * make useless noise.
1135 if (profile_cpu
== -1 && atom
->state
!= THREAD_SLEEPING
)
1136 nr_state_machine_bugs
++;
1139 if (atom
->sched_out_time
> timestamp
) {
1140 nr_unordered_timestamps
++;
1144 atom
->state
= THREAD_WAIT_CPU
;
1145 atom
->wake_up_time
= timestamp
;
1149 latency_migrate_task_event(struct trace_migrate_task_event
*migrate_task_event
,
1150 struct event
*__event __used
,
1153 struct thread
*thread __used
)
1155 struct work_atoms
*atoms
;
1156 struct work_atom
*atom
;
1157 struct thread
*migrant
;
1160 * Only need to worry about migration when profiling one CPU.
1162 if (profile_cpu
== -1)
1165 migrant
= threads__findnew(migrate_task_event
->pid
);
1166 atoms
= thread_atoms_search(&atom_root
, migrant
, &cmp_pid
);
1168 thread_atoms_insert(migrant
);
1169 register_pid(migrant
->pid
, migrant
->comm
);
1170 atoms
= thread_atoms_search(&atom_root
, migrant
, &cmp_pid
);
1172 die("migration-event: Internal tree error");
1173 add_sched_out_event(atoms
, 'R', timestamp
);
1176 BUG_ON(list_empty(&atoms
->work_list
));
1178 atom
= list_entry(atoms
->work_list
.prev
, struct work_atom
, list
);
1179 atom
->sched_in_time
= atom
->sched_out_time
= atom
->wake_up_time
= timestamp
;
1183 if (atom
->sched_out_time
> timestamp
)
1184 nr_unordered_timestamps
++;
1187 static struct trace_sched_handler lat_ops
= {
1188 .wakeup_event
= latency_wakeup_event
,
1189 .switch_event
= latency_switch_event
,
1190 .runtime_event
= latency_runtime_event
,
1191 .fork_event
= latency_fork_event
,
1192 .migrate_task_event
= latency_migrate_task_event
,
1195 static void output_lat_thread(struct work_atoms
*work_list
)
1201 if (!work_list
->nb_atoms
)
1204 * Ignore idle threads:
1206 if (!strcmp(work_list
->thread
->comm
, "swapper"))
1209 all_runtime
+= work_list
->total_runtime
;
1210 all_count
+= work_list
->nb_atoms
;
1212 ret
= printf(" %s:%d ", work_list
->thread
->comm
, work_list
->thread
->pid
);
1214 for (i
= 0; i
< 24 - ret
; i
++)
1217 avg
= work_list
->total_lat
/ work_list
->nb_atoms
;
1219 printf("|%11.3f ms |%9llu | avg:%9.3f ms | max:%9.3f ms |\n",
1220 (double)work_list
->total_runtime
/ 1e6
,
1221 work_list
->nb_atoms
, (double)avg
/ 1e6
,
1222 (double)work_list
->max_lat
/ 1e6
);
1225 static int pid_cmp(struct work_atoms
*l
, struct work_atoms
*r
)
1227 if (l
->thread
->pid
< r
->thread
->pid
)
1229 if (l
->thread
->pid
> r
->thread
->pid
)
1235 static struct sort_dimension pid_sort_dimension
= {
1240 static int avg_cmp(struct work_atoms
*l
, struct work_atoms
*r
)
1250 avgl
= l
->total_lat
/ l
->nb_atoms
;
1251 avgr
= r
->total_lat
/ r
->nb_atoms
;
1261 static struct sort_dimension avg_sort_dimension
= {
1266 static int max_cmp(struct work_atoms
*l
, struct work_atoms
*r
)
1268 if (l
->max_lat
< r
->max_lat
)
1270 if (l
->max_lat
> r
->max_lat
)
1276 static struct sort_dimension max_sort_dimension
= {
1281 static int switch_cmp(struct work_atoms
*l
, struct work_atoms
*r
)
1283 if (l
->nb_atoms
< r
->nb_atoms
)
1285 if (l
->nb_atoms
> r
->nb_atoms
)
1291 static struct sort_dimension switch_sort_dimension
= {
1296 static int runtime_cmp(struct work_atoms
*l
, struct work_atoms
*r
)
1298 if (l
->total_runtime
< r
->total_runtime
)
1300 if (l
->total_runtime
> r
->total_runtime
)
1306 static struct sort_dimension runtime_sort_dimension
= {
1311 static struct sort_dimension
*available_sorts
[] = {
1312 &pid_sort_dimension
,
1313 &avg_sort_dimension
,
1314 &max_sort_dimension
,
1315 &switch_sort_dimension
,
1316 &runtime_sort_dimension
,
1319 #define NB_AVAILABLE_SORTS (int)(sizeof(available_sorts) / sizeof(struct sort_dimension *))
1321 static LIST_HEAD(sort_list
);
1323 static int sort_dimension__add(const char *tok
, struct list_head
*list
)
1327 for (i
= 0; i
< NB_AVAILABLE_SORTS
; i
++) {
1328 if (!strcmp(available_sorts
[i
]->name
, tok
)) {
1329 list_add_tail(&available_sorts
[i
]->list
, list
);
1338 static void setup_sorting(void);
1340 static void sort_lat(void)
1342 struct rb_node
*node
;
1345 struct work_atoms
*data
;
1346 node
= rb_first(&atom_root
);
1350 rb_erase(node
, &atom_root
);
1351 data
= rb_entry(node
, struct work_atoms
, node
);
1352 __thread_latency_insert(&sorted_atom_root
, data
, &sort_list
);
1356 static struct trace_sched_handler
*trace_handler
;
1359 process_sched_wakeup_event(struct raw_event_sample
*raw
,
1360 struct event
*event
,
1362 u64 timestamp __used
,
1363 struct thread
*thread __used
)
1365 struct trace_wakeup_event wakeup_event
;
1367 FILL_COMMON_FIELDS(wakeup_event
, event
, raw
->data
);
1369 FILL_ARRAY(wakeup_event
, comm
, event
, raw
->data
);
1370 FILL_FIELD(wakeup_event
, pid
, event
, raw
->data
);
1371 FILL_FIELD(wakeup_event
, prio
, event
, raw
->data
);
1372 FILL_FIELD(wakeup_event
, success
, event
, raw
->data
);
1373 FILL_FIELD(wakeup_event
, cpu
, event
, raw
->data
);
1375 if (trace_handler
->wakeup_event
)
1376 trace_handler
->wakeup_event(&wakeup_event
, event
, cpu
, timestamp
, thread
);
1380 * Track the current task - that way we can know whether there's any
1381 * weird events, such as a task being switched away that is not current.
1385 static u32 curr_pid
[MAX_CPUS
] = { [0 ... MAX_CPUS
-1] = -1 };
1387 static struct thread
*curr_thread
[MAX_CPUS
];
1389 static char next_shortname1
= 'A';
1390 static char next_shortname2
= '0';
1393 map_switch_event(struct trace_switch_event
*switch_event
,
1394 struct event
*event __used
,
1397 struct thread
*thread __used
)
1399 struct thread
*sched_out
, *sched_in
;
1405 BUG_ON(this_cpu
>= MAX_CPUS
|| this_cpu
< 0);
1407 if (this_cpu
> max_cpu
)
1410 timestamp0
= cpu_last_switched
[this_cpu
];
1411 cpu_last_switched
[this_cpu
] = timestamp
;
1413 delta
= timestamp
- timestamp0
;
1418 die("hm, delta: %Ld < 0 ?\n", delta
);
1421 sched_out
= threads__findnew(switch_event
->prev_pid
);
1422 sched_in
= threads__findnew(switch_event
->next_pid
);
1424 curr_thread
[this_cpu
] = sched_in
;
1429 if (!sched_in
->shortname
[0]) {
1430 sched_in
->shortname
[0] = next_shortname1
;
1431 sched_in
->shortname
[1] = next_shortname2
;
1433 if (next_shortname1
< 'Z') {
1436 next_shortname1
='A';
1437 if (next_shortname2
< '9') {
1440 next_shortname2
='0';
1446 for (cpu
= 0; cpu
<= max_cpu
; cpu
++) {
1447 if (cpu
!= this_cpu
)
1452 if (curr_thread
[cpu
]) {
1453 if (curr_thread
[cpu
]->pid
)
1454 printf("%2s ", curr_thread
[cpu
]->shortname
);
1461 printf(" %12.6f secs ", (double)timestamp
/1e9
);
1462 if (new_shortname
) {
1463 printf("%s => %s:%d\n",
1464 sched_in
->shortname
, sched_in
->comm
, sched_in
->pid
);
1472 process_sched_switch_event(struct raw_event_sample
*raw
,
1473 struct event
*event
,
1475 u64 timestamp __used
,
1476 struct thread
*thread __used
)
1478 struct trace_switch_event switch_event
;
1480 FILL_COMMON_FIELDS(switch_event
, event
, raw
->data
);
1482 FILL_ARRAY(switch_event
, prev_comm
, event
, raw
->data
);
1483 FILL_FIELD(switch_event
, prev_pid
, event
, raw
->data
);
1484 FILL_FIELD(switch_event
, prev_prio
, event
, raw
->data
);
1485 FILL_FIELD(switch_event
, prev_state
, event
, raw
->data
);
1486 FILL_ARRAY(switch_event
, next_comm
, event
, raw
->data
);
1487 FILL_FIELD(switch_event
, next_pid
, event
, raw
->data
);
1488 FILL_FIELD(switch_event
, next_prio
, event
, raw
->data
);
1490 if (curr_pid
[this_cpu
] != (u32
)-1) {
1492 * Are we trying to switch away a PID that is
1495 if (curr_pid
[this_cpu
] != switch_event
.prev_pid
)
1496 nr_context_switch_bugs
++;
1498 if (trace_handler
->switch_event
)
1499 trace_handler
->switch_event(&switch_event
, event
, this_cpu
, timestamp
, thread
);
1501 curr_pid
[this_cpu
] = switch_event
.next_pid
;
1505 process_sched_runtime_event(struct raw_event_sample
*raw
,
1506 struct event
*event
,
1508 u64 timestamp __used
,
1509 struct thread
*thread __used
)
1511 struct trace_runtime_event runtime_event
;
1513 FILL_ARRAY(runtime_event
, comm
, event
, raw
->data
);
1514 FILL_FIELD(runtime_event
, pid
, event
, raw
->data
);
1515 FILL_FIELD(runtime_event
, runtime
, event
, raw
->data
);
1516 FILL_FIELD(runtime_event
, vruntime
, event
, raw
->data
);
1518 if (trace_handler
->runtime_event
)
1519 trace_handler
->runtime_event(&runtime_event
, event
, cpu
, timestamp
, thread
);
1523 process_sched_fork_event(struct raw_event_sample
*raw
,
1524 struct event
*event
,
1526 u64 timestamp __used
,
1527 struct thread
*thread __used
)
1529 struct trace_fork_event fork_event
;
1531 FILL_COMMON_FIELDS(fork_event
, event
, raw
->data
);
1533 FILL_ARRAY(fork_event
, parent_comm
, event
, raw
->data
);
1534 FILL_FIELD(fork_event
, parent_pid
, event
, raw
->data
);
1535 FILL_ARRAY(fork_event
, child_comm
, event
, raw
->data
);
1536 FILL_FIELD(fork_event
, child_pid
, event
, raw
->data
);
1538 if (trace_handler
->fork_event
)
1539 trace_handler
->fork_event(&fork_event
, event
, cpu
, timestamp
, thread
);
1543 process_sched_exit_event(struct event
*event
,
1545 u64 timestamp __used
,
1546 struct thread
*thread __used
)
1549 printf("sched_exit event %p\n", event
);
1553 process_sched_migrate_task_event(struct raw_event_sample
*raw
,
1554 struct event
*event
,
1556 u64 timestamp __used
,
1557 struct thread
*thread __used
)
1559 struct trace_migrate_task_event migrate_task_event
;
1561 FILL_COMMON_FIELDS(migrate_task_event
, event
, raw
->data
);
1563 FILL_ARRAY(migrate_task_event
, comm
, event
, raw
->data
);
1564 FILL_FIELD(migrate_task_event
, pid
, event
, raw
->data
);
1565 FILL_FIELD(migrate_task_event
, prio
, event
, raw
->data
);
1566 FILL_FIELD(migrate_task_event
, cpu
, event
, raw
->data
);
1568 if (trace_handler
->migrate_task_event
)
1569 trace_handler
->migrate_task_event(&migrate_task_event
, event
, cpu
, timestamp
, thread
);
1573 process_raw_event(event_t
*raw_event __used
, void *more_data
,
1574 int cpu
, u64 timestamp
, struct thread
*thread
)
1576 struct raw_event_sample
*raw
= more_data
;
1577 struct event
*event
;
1580 type
= trace_parse_common_type(raw
->data
);
1581 event
= trace_find_event(type
);
1583 if (!strcmp(event
->name
, "sched_switch"))
1584 process_sched_switch_event(raw
, event
, cpu
, timestamp
, thread
);
1585 if (!strcmp(event
->name
, "sched_stat_runtime"))
1586 process_sched_runtime_event(raw
, event
, cpu
, timestamp
, thread
);
1587 if (!strcmp(event
->name
, "sched_wakeup"))
1588 process_sched_wakeup_event(raw
, event
, cpu
, timestamp
, thread
);
1589 if (!strcmp(event
->name
, "sched_wakeup_new"))
1590 process_sched_wakeup_event(raw
, event
, cpu
, timestamp
, thread
);
1591 if (!strcmp(event
->name
, "sched_process_fork"))
1592 process_sched_fork_event(raw
, event
, cpu
, timestamp
, thread
);
1593 if (!strcmp(event
->name
, "sched_process_exit"))
1594 process_sched_exit_event(event
, cpu
, timestamp
, thread
);
1595 if (!strcmp(event
->name
, "sched_migrate_task"))
1596 process_sched_migrate_task_event(raw
, event
, cpu
, timestamp
, thread
);
1599 static int process_sample_event(event_t
*event
)
1601 struct thread
*thread
;
1602 u64 ip
= event
->ip
.ip
;
1606 void *more_data
= event
->ip
.__more_data
;
1608 if (!(sample_type
& PERF_SAMPLE_RAW
))
1611 thread
= threads__findnew(event
->ip
.pid
);
1613 if (sample_type
& PERF_SAMPLE_TIME
) {
1614 timestamp
= *(u64
*)more_data
;
1615 more_data
+= sizeof(u64
);
1618 if (sample_type
& PERF_SAMPLE_CPU
) {
1619 cpu
= *(u32
*)more_data
;
1620 more_data
+= sizeof(u32
);
1621 more_data
+= sizeof(u32
); /* reserved */
1624 if (sample_type
& PERF_SAMPLE_PERIOD
) {
1625 period
= *(u64
*)more_data
;
1626 more_data
+= sizeof(u64
);
1629 dump_printf("(IP, %d): %d/%d: %p period: %Ld\n",
1631 event
->ip
.pid
, event
->ip
.tid
,
1635 if (thread
== NULL
) {
1636 pr_debug("problem processing %d event, skipping it.\n",
1637 event
->header
.type
);
1641 dump_printf(" ... thread: %s:%d\n", thread
->comm
, thread
->pid
);
1643 if (profile_cpu
!= -1 && profile_cpu
!= (int) cpu
)
1646 process_raw_event(event
, more_data
, cpu
, timestamp
, thread
);
1651 static int process_lost_event(event_t
*event __used
)
1654 nr_lost_events
+= event
->lost
.lost
;
1659 static int sample_type_check(u64 type
)
1663 if (!(sample_type
& PERF_SAMPLE_RAW
)) {
1665 "No trace sample to read. Did you call perf record "
1673 static struct perf_file_handler file_handler
= {
1674 .process_sample_event
= process_sample_event
,
1675 .process_comm_event
= event__process_comm
,
1676 .process_lost_event
= process_lost_event
,
1677 .sample_type_check
= sample_type_check
,
1680 static int read_events(void)
1682 register_idle_thread();
1683 register_perf_file_handler(&file_handler
);
1685 return mmap_dispatch_perf_file(&header
, input_name
, 0, 0,
1686 &event__cwdlen
, &event__cwd
);
1689 static void print_bad_events(void)
1691 if (nr_unordered_timestamps
&& nr_timestamps
) {
1692 printf(" INFO: %.3f%% unordered timestamps (%ld out of %ld)\n",
1693 (double)nr_unordered_timestamps
/(double)nr_timestamps
*100.0,
1694 nr_unordered_timestamps
, nr_timestamps
);
1696 if (nr_lost_events
&& nr_events
) {
1697 printf(" INFO: %.3f%% lost events (%ld out of %ld, in %ld chunks)\n",
1698 (double)nr_lost_events
/(double)nr_events
*100.0,
1699 nr_lost_events
, nr_events
, nr_lost_chunks
);
1701 if (nr_state_machine_bugs
&& nr_timestamps
) {
1702 printf(" INFO: %.3f%% state machine bugs (%ld out of %ld)",
1703 (double)nr_state_machine_bugs
/(double)nr_timestamps
*100.0,
1704 nr_state_machine_bugs
, nr_timestamps
);
1706 printf(" (due to lost events?)");
1709 if (nr_context_switch_bugs
&& nr_timestamps
) {
1710 printf(" INFO: %.3f%% context switch bugs (%ld out of %ld)",
1711 (double)nr_context_switch_bugs
/(double)nr_timestamps
*100.0,
1712 nr_context_switch_bugs
, nr_timestamps
);
1714 printf(" (due to lost events?)");
1719 static void __cmd_lat(void)
1721 struct rb_node
*next
;
1727 printf("\n -----------------------------------------------------------------------------------------\n");
1728 printf(" Task | Runtime ms | Switches | Average delay ms | Maximum delay ms |\n");
1729 printf(" -----------------------------------------------------------------------------------------\n");
1731 next
= rb_first(&sorted_atom_root
);
1734 struct work_atoms
*work_list
;
1736 work_list
= rb_entry(next
, struct work_atoms
, node
);
1737 output_lat_thread(work_list
);
1738 next
= rb_next(next
);
1741 printf(" -----------------------------------------------------------------------------------------\n");
1742 printf(" TOTAL: |%11.3f ms |%9Ld |\n",
1743 (double)all_runtime
/1e6
, all_count
);
1745 printf(" ---------------------------------------------------\n");
1752 static struct trace_sched_handler map_ops
= {
1753 .wakeup_event
= NULL
,
1754 .switch_event
= map_switch_event
,
1755 .runtime_event
= NULL
,
1759 static void __cmd_map(void)
1761 max_cpu
= sysconf(_SC_NPROCESSORS_CONF
);
1768 static void __cmd_replay(void)
1772 calibrate_run_measurement_overhead();
1773 calibrate_sleep_measurement_overhead();
1775 test_calibrations();
1779 printf("nr_run_events: %ld\n", nr_run_events
);
1780 printf("nr_sleep_events: %ld\n", nr_sleep_events
);
1781 printf("nr_wakeup_events: %ld\n", nr_wakeup_events
);
1783 if (targetless_wakeups
)
1784 printf("target-less wakeups: %ld\n", targetless_wakeups
);
1785 if (multitarget_wakeups
)
1786 printf("multi-target wakeups: %ld\n", multitarget_wakeups
);
1787 if (nr_run_events_optimized
)
1788 printf("run atoms optimized: %ld\n",
1789 nr_run_events_optimized
);
1791 print_task_traces();
1792 add_cross_task_wakeups();
1795 printf("------------------------------------------------------------\n");
1796 for (i
= 0; i
< replay_repeat
; i
++)
1801 static const char * const sched_usage
[] = {
1802 "perf sched [<options>] {record|latency|map|replay|trace}",
1806 static const struct option sched_options
[] = {
1807 OPT_STRING('i', "input", &input_name
, "file",
1809 OPT_BOOLEAN('v', "verbose", &verbose
,
1810 "be more verbose (show symbol address, etc)"),
1811 OPT_BOOLEAN('D', "dump-raw-trace", &dump_trace
,
1812 "dump raw trace in ASCII"),
1816 static const char * const latency_usage
[] = {
1817 "perf sched latency [<options>]",
1821 static const struct option latency_options
[] = {
1822 OPT_STRING('s', "sort", &sort_order
, "key[,key2...]",
1823 "sort by key(s): runtime, switch, avg, max"),
1824 OPT_BOOLEAN('v', "verbose", &verbose
,
1825 "be more verbose (show symbol address, etc)"),
1826 OPT_INTEGER('C', "CPU", &profile_cpu
,
1827 "CPU to profile on"),
1828 OPT_BOOLEAN('D', "dump-raw-trace", &dump_trace
,
1829 "dump raw trace in ASCII"),
1833 static const char * const replay_usage
[] = {
1834 "perf sched replay [<options>]",
1838 static const struct option replay_options
[] = {
1839 OPT_INTEGER('r', "repeat", &replay_repeat
,
1840 "repeat the workload replay N times (-1: infinite)"),
1841 OPT_BOOLEAN('v', "verbose", &verbose
,
1842 "be more verbose (show symbol address, etc)"),
1843 OPT_BOOLEAN('D', "dump-raw-trace", &dump_trace
,
1844 "dump raw trace in ASCII"),
1848 static void setup_sorting(void)
1850 char *tmp
, *tok
, *str
= strdup(sort_order
);
1852 for (tok
= strtok_r(str
, ", ", &tmp
);
1853 tok
; tok
= strtok_r(NULL
, ", ", &tmp
)) {
1854 if (sort_dimension__add(tok
, &sort_list
) < 0) {
1855 error("Unknown --sort key: `%s'", tok
);
1856 usage_with_options(latency_usage
, latency_options
);
1862 sort_dimension__add("pid", &cmp_pid
);
1865 static const char *record_args
[] = {
1873 "-e", "sched:sched_switch:r",
1874 "-e", "sched:sched_stat_wait:r",
1875 "-e", "sched:sched_stat_sleep:r",
1876 "-e", "sched:sched_stat_iowait:r",
1877 "-e", "sched:sched_stat_runtime:r",
1878 "-e", "sched:sched_process_exit:r",
1879 "-e", "sched:sched_process_fork:r",
1880 "-e", "sched:sched_wakeup:r",
1881 "-e", "sched:sched_migrate_task:r",
1884 static int __cmd_record(int argc
, const char **argv
)
1886 unsigned int rec_argc
, i
, j
;
1887 const char **rec_argv
;
1889 rec_argc
= ARRAY_SIZE(record_args
) + argc
- 1;
1890 rec_argv
= calloc(rec_argc
+ 1, sizeof(char *));
1892 for (i
= 0; i
< ARRAY_SIZE(record_args
); i
++)
1893 rec_argv
[i
] = strdup(record_args
[i
]);
1895 for (j
= 1; j
< (unsigned int)argc
; j
++, i
++)
1896 rec_argv
[i
] = argv
[j
];
1898 BUG_ON(i
!= rec_argc
);
1900 return cmd_record(i
, rec_argv
, NULL
);
1903 int cmd_sched(int argc
, const char **argv
, const char *prefix __used
)
1907 argc
= parse_options(argc
, argv
, sched_options
, sched_usage
,
1908 PARSE_OPT_STOP_AT_NON_OPTION
);
1910 usage_with_options(sched_usage
, sched_options
);
1912 if (!strncmp(argv
[0], "rec", 3)) {
1913 return __cmd_record(argc
, argv
);
1914 } else if (!strncmp(argv
[0], "lat", 3)) {
1915 trace_handler
= &lat_ops
;
1917 argc
= parse_options(argc
, argv
, latency_options
, latency_usage
, 0);
1919 usage_with_options(latency_usage
, latency_options
);
1923 } else if (!strcmp(argv
[0], "map")) {
1924 trace_handler
= &map_ops
;
1927 } else if (!strncmp(argv
[0], "rep", 3)) {
1928 trace_handler
= &replay_ops
;
1930 argc
= parse_options(argc
, argv
, replay_options
, replay_usage
, 0);
1932 usage_with_options(replay_usage
, replay_options
);
1935 } else if (!strcmp(argv
[0], "trace")) {
1937 * Aliased to 'perf trace' for now:
1939 return cmd_trace(argc
, argv
, prefix
);
1941 usage_with_options(sched_usage
, sched_options
);