• Home
  • Line#
  • Scopes#
  • Navigate#
  • Raw
  • Download
1 // SPDX-License-Identifier: GPL-2.0
2 /*
3  * builtin-kwork.c
4  *
5  * Copyright (c) 2022  Huawei Inc,  Yang Jihong <yangjihong1@huawei.com>
6  */
7 
8 #include "builtin.h"
9 
10 #include "util/data.h"
11 #include "util/kwork.h"
12 #include "util/debug.h"
13 #include "util/symbol.h"
14 #include "util/thread.h"
15 #include "util/string2.h"
16 #include "util/callchain.h"
17 #include "util/evsel_fprintf.h"
18 
19 #include <subcmd/pager.h>
20 #include <subcmd/parse-options.h>
21 
22 #include <errno.h>
23 #include <inttypes.h>
24 #include <linux/err.h>
25 #include <linux/time64.h>
26 #include <linux/zalloc.h>
27 
28 /*
29  * report header elements width
30  */
31 #define PRINT_CPU_WIDTH 4
32 #define PRINT_COUNT_WIDTH 9
33 #define PRINT_RUNTIME_WIDTH 10
34 #define PRINT_LATENCY_WIDTH 10
35 #define PRINT_TIMESTAMP_WIDTH 17
36 #define PRINT_KWORK_NAME_WIDTH 30
37 #define RPINT_DECIMAL_WIDTH 3
38 #define PRINT_BRACKETPAIR_WIDTH 2
39 #define PRINT_TIME_UNIT_SEC_WIDTH 2
40 #define PRINT_TIME_UNIT_MESC_WIDTH 3
41 #define PRINT_RUNTIME_HEADER_WIDTH (PRINT_RUNTIME_WIDTH + PRINT_TIME_UNIT_MESC_WIDTH)
42 #define PRINT_LATENCY_HEADER_WIDTH (PRINT_LATENCY_WIDTH + PRINT_TIME_UNIT_MESC_WIDTH)
43 #define PRINT_TIMEHIST_CPU_WIDTH (PRINT_CPU_WIDTH + PRINT_BRACKETPAIR_WIDTH)
44 #define PRINT_TIMESTAMP_HEADER_WIDTH (PRINT_TIMESTAMP_WIDTH + PRINT_TIME_UNIT_SEC_WIDTH)
45 
46 struct sort_dimension {
47 	const char      *name;
48 	int             (*cmp)(struct kwork_work *l, struct kwork_work *r);
49 	struct          list_head list;
50 };
51 
id_cmp(struct kwork_work * l,struct kwork_work * r)52 static int id_cmp(struct kwork_work *l, struct kwork_work *r)
53 {
54 	if (l->cpu > r->cpu)
55 		return 1;
56 	if (l->cpu < r->cpu)
57 		return -1;
58 
59 	if (l->id > r->id)
60 		return 1;
61 	if (l->id < r->id)
62 		return -1;
63 
64 	return 0;
65 }
66 
count_cmp(struct kwork_work * l,struct kwork_work * r)67 static int count_cmp(struct kwork_work *l, struct kwork_work *r)
68 {
69 	if (l->nr_atoms > r->nr_atoms)
70 		return 1;
71 	if (l->nr_atoms < r->nr_atoms)
72 		return -1;
73 
74 	return 0;
75 }
76 
runtime_cmp(struct kwork_work * l,struct kwork_work * r)77 static int runtime_cmp(struct kwork_work *l, struct kwork_work *r)
78 {
79 	if (l->total_runtime > r->total_runtime)
80 		return 1;
81 	if (l->total_runtime < r->total_runtime)
82 		return -1;
83 
84 	return 0;
85 }
86 
max_runtime_cmp(struct kwork_work * l,struct kwork_work * r)87 static int max_runtime_cmp(struct kwork_work *l, struct kwork_work *r)
88 {
89 	if (l->max_runtime > r->max_runtime)
90 		return 1;
91 	if (l->max_runtime < r->max_runtime)
92 		return -1;
93 
94 	return 0;
95 }
96 
avg_latency_cmp(struct kwork_work * l,struct kwork_work * r)97 static int avg_latency_cmp(struct kwork_work *l, struct kwork_work *r)
98 {
99 	u64 avgl, avgr;
100 
101 	if (!r->nr_atoms)
102 		return 1;
103 	if (!l->nr_atoms)
104 		return -1;
105 
106 	avgl = l->total_latency / l->nr_atoms;
107 	avgr = r->total_latency / r->nr_atoms;
108 
109 	if (avgl > avgr)
110 		return 1;
111 	if (avgl < avgr)
112 		return -1;
113 
114 	return 0;
115 }
116 
max_latency_cmp(struct kwork_work * l,struct kwork_work * r)117 static int max_latency_cmp(struct kwork_work *l, struct kwork_work *r)
118 {
119 	if (l->max_latency > r->max_latency)
120 		return 1;
121 	if (l->max_latency < r->max_latency)
122 		return -1;
123 
124 	return 0;
125 }
126 
sort_dimension__add(struct perf_kwork * kwork __maybe_unused,const char * tok,struct list_head * list)127 static int sort_dimension__add(struct perf_kwork *kwork __maybe_unused,
128 			       const char *tok, struct list_head *list)
129 {
130 	size_t i;
131 	static struct sort_dimension max_sort_dimension = {
132 		.name = "max",
133 		.cmp  = max_runtime_cmp,
134 	};
135 	static struct sort_dimension id_sort_dimension = {
136 		.name = "id",
137 		.cmp  = id_cmp,
138 	};
139 	static struct sort_dimension runtime_sort_dimension = {
140 		.name = "runtime",
141 		.cmp  = runtime_cmp,
142 	};
143 	static struct sort_dimension count_sort_dimension = {
144 		.name = "count",
145 		.cmp  = count_cmp,
146 	};
147 	static struct sort_dimension avg_sort_dimension = {
148 		.name = "avg",
149 		.cmp  = avg_latency_cmp,
150 	};
151 	struct sort_dimension *available_sorts[] = {
152 		&id_sort_dimension,
153 		&max_sort_dimension,
154 		&count_sort_dimension,
155 		&runtime_sort_dimension,
156 		&avg_sort_dimension,
157 	};
158 
159 	if (kwork->report == KWORK_REPORT_LATENCY)
160 		max_sort_dimension.cmp = max_latency_cmp;
161 
162 	for (i = 0; i < ARRAY_SIZE(available_sorts); i++) {
163 		if (!strcmp(available_sorts[i]->name, tok)) {
164 			list_add_tail(&available_sorts[i]->list, list);
165 			return 0;
166 		}
167 	}
168 
169 	return -1;
170 }
171 
setup_sorting(struct perf_kwork * kwork,const struct option * options,const char * const usage_msg[])172 static void setup_sorting(struct perf_kwork *kwork,
173 			  const struct option *options,
174 			  const char * const usage_msg[])
175 {
176 	char *tmp, *tok, *str = strdup(kwork->sort_order);
177 
178 	for (tok = strtok_r(str, ", ", &tmp);
179 	     tok; tok = strtok_r(NULL, ", ", &tmp)) {
180 		if (sort_dimension__add(kwork, tok, &kwork->sort_list) < 0)
181 			usage_with_options_msg(usage_msg, options,
182 					       "Unknown --sort key: `%s'", tok);
183 	}
184 
185 	pr_debug("Sort order: %s\n", kwork->sort_order);
186 	free(str);
187 }
188 
atom_new(struct perf_kwork * kwork,struct perf_sample * sample)189 static struct kwork_atom *atom_new(struct perf_kwork *kwork,
190 				   struct perf_sample *sample)
191 {
192 	unsigned long i;
193 	struct kwork_atom_page *page;
194 	struct kwork_atom *atom = NULL;
195 
196 	list_for_each_entry(page, &kwork->atom_page_list, list) {
197 		if (!bitmap_full(page->bitmap, NR_ATOM_PER_PAGE)) {
198 			i = find_first_zero_bit(page->bitmap, NR_ATOM_PER_PAGE);
199 			BUG_ON(i >= NR_ATOM_PER_PAGE);
200 			atom = &page->atoms[i];
201 			goto found_atom;
202 		}
203 	}
204 
205 	/*
206 	 * new page
207 	 */
208 	page = zalloc(sizeof(*page));
209 	if (page == NULL) {
210 		pr_err("Failed to zalloc kwork atom page\n");
211 		return NULL;
212 	}
213 
214 	i = 0;
215 	atom = &page->atoms[0];
216 	list_add_tail(&page->list, &kwork->atom_page_list);
217 
218 found_atom:
219 	set_bit(i, page->bitmap);
220 	atom->time = sample->time;
221 	atom->prev = NULL;
222 	atom->page_addr = page;
223 	atom->bit_inpage = i;
224 	return atom;
225 }
226 
atom_free(struct kwork_atom * atom)227 static void atom_free(struct kwork_atom *atom)
228 {
229 	if (atom->prev != NULL)
230 		atom_free(atom->prev);
231 
232 	clear_bit(atom->bit_inpage,
233 		  ((struct kwork_atom_page *)atom->page_addr)->bitmap);
234 }
235 
atom_del(struct kwork_atom * atom)236 static void atom_del(struct kwork_atom *atom)
237 {
238 	list_del(&atom->list);
239 	atom_free(atom);
240 }
241 
work_cmp(struct list_head * list,struct kwork_work * l,struct kwork_work * r)242 static int work_cmp(struct list_head *list,
243 		    struct kwork_work *l, struct kwork_work *r)
244 {
245 	int ret = 0;
246 	struct sort_dimension *sort;
247 
248 	BUG_ON(list_empty(list));
249 
250 	list_for_each_entry(sort, list, list) {
251 		ret = sort->cmp(l, r);
252 		if (ret)
253 			return ret;
254 	}
255 
256 	return ret;
257 }
258 
work_search(struct rb_root_cached * root,struct kwork_work * key,struct list_head * sort_list)259 static struct kwork_work *work_search(struct rb_root_cached *root,
260 				      struct kwork_work *key,
261 				      struct list_head *sort_list)
262 {
263 	int cmp;
264 	struct kwork_work *work;
265 	struct rb_node *node = root->rb_root.rb_node;
266 
267 	while (node) {
268 		work = container_of(node, struct kwork_work, node);
269 		cmp = work_cmp(sort_list, key, work);
270 		if (cmp > 0)
271 			node = node->rb_left;
272 		else if (cmp < 0)
273 			node = node->rb_right;
274 		else {
275 			if (work->name == NULL)
276 				work->name = key->name;
277 			return work;
278 		}
279 	}
280 	return NULL;
281 }
282 
work_insert(struct rb_root_cached * root,struct kwork_work * key,struct list_head * sort_list)283 static void work_insert(struct rb_root_cached *root,
284 			struct kwork_work *key, struct list_head *sort_list)
285 {
286 	int cmp;
287 	bool leftmost = true;
288 	struct kwork_work *cur;
289 	struct rb_node **new = &(root->rb_root.rb_node), *parent = NULL;
290 
291 	while (*new) {
292 		cur = container_of(*new, struct kwork_work, node);
293 		parent = *new;
294 		cmp = work_cmp(sort_list, key, cur);
295 
296 		if (cmp > 0)
297 			new = &((*new)->rb_left);
298 		else {
299 			new = &((*new)->rb_right);
300 			leftmost = false;
301 		}
302 	}
303 
304 	rb_link_node(&key->node, parent, new);
305 	rb_insert_color_cached(&key->node, root, leftmost);
306 }
307 
work_new(struct kwork_work * key)308 static struct kwork_work *work_new(struct kwork_work *key)
309 {
310 	int i;
311 	struct kwork_work *work = zalloc(sizeof(*work));
312 
313 	if (work == NULL) {
314 		pr_err("Failed to zalloc kwork work\n");
315 		return NULL;
316 	}
317 
318 	for (i = 0; i < KWORK_TRACE_MAX; i++)
319 		INIT_LIST_HEAD(&work->atom_list[i]);
320 
321 	work->id = key->id;
322 	work->cpu = key->cpu;
323 	work->name = key->name;
324 	work->class = key->class;
325 	return work;
326 }
327 
work_findnew(struct rb_root_cached * root,struct kwork_work * key,struct list_head * sort_list)328 static struct kwork_work *work_findnew(struct rb_root_cached *root,
329 				       struct kwork_work *key,
330 				       struct list_head *sort_list)
331 {
332 	struct kwork_work *work = work_search(root, key, sort_list);
333 
334 	if (work != NULL)
335 		return work;
336 
337 	work = work_new(key);
338 	if (work)
339 		work_insert(root, work, sort_list);
340 
341 	return work;
342 }
343 
profile_update_timespan(struct perf_kwork * kwork,struct perf_sample * sample)344 static void profile_update_timespan(struct perf_kwork *kwork,
345 				    struct perf_sample *sample)
346 {
347 	if (!kwork->summary)
348 		return;
349 
350 	if ((kwork->timestart == 0) || (kwork->timestart > sample->time))
351 		kwork->timestart = sample->time;
352 
353 	if (kwork->timeend < sample->time)
354 		kwork->timeend = sample->time;
355 }
356 
profile_event_match(struct perf_kwork * kwork,struct kwork_work * work,struct perf_sample * sample)357 static bool profile_event_match(struct perf_kwork *kwork,
358 				struct kwork_work *work,
359 				struct perf_sample *sample)
360 {
361 	int cpu = work->cpu;
362 	u64 time = sample->time;
363 	struct perf_time_interval *ptime = &kwork->ptime;
364 
365 	if ((kwork->cpu_list != NULL) && !test_bit(cpu, kwork->cpu_bitmap))
366 		return false;
367 
368 	if (((ptime->start != 0) && (ptime->start > time)) ||
369 	    ((ptime->end != 0) && (ptime->end < time)))
370 		return false;
371 
372 	if ((kwork->profile_name != NULL) &&
373 	    (work->name != NULL) &&
374 	    (strcmp(work->name, kwork->profile_name) != 0))
375 		return false;
376 
377 	profile_update_timespan(kwork, sample);
378 	return true;
379 }
380 
work_push_atom(struct perf_kwork * kwork,struct kwork_class * class,enum kwork_trace_type src_type,enum kwork_trace_type dst_type,struct evsel * evsel,struct perf_sample * sample,struct machine * machine,struct kwork_work ** ret_work)381 static int work_push_atom(struct perf_kwork *kwork,
382 			  struct kwork_class *class,
383 			  enum kwork_trace_type src_type,
384 			  enum kwork_trace_type dst_type,
385 			  struct evsel *evsel,
386 			  struct perf_sample *sample,
387 			  struct machine *machine,
388 			  struct kwork_work **ret_work)
389 {
390 	struct kwork_atom *atom, *dst_atom;
391 	struct kwork_work *work, key;
392 
393 	BUG_ON(class->work_init == NULL);
394 	class->work_init(class, &key, evsel, sample, machine);
395 
396 	atom = atom_new(kwork, sample);
397 	if (atom == NULL)
398 		return -1;
399 
400 	work = work_findnew(&class->work_root, &key, &kwork->cmp_id);
401 	if (work == NULL) {
402 		atom_free(atom);
403 		return -1;
404 	}
405 
406 	if (!profile_event_match(kwork, work, sample)) {
407 		atom_free(atom);
408 		return 0;
409 	}
410 
411 	if (dst_type < KWORK_TRACE_MAX) {
412 		dst_atom = list_last_entry_or_null(&work->atom_list[dst_type],
413 						   struct kwork_atom, list);
414 		if (dst_atom != NULL) {
415 			atom->prev = dst_atom;
416 			list_del(&dst_atom->list);
417 		}
418 	}
419 
420 	if (ret_work != NULL)
421 		*ret_work = work;
422 
423 	list_add_tail(&atom->list, &work->atom_list[src_type]);
424 
425 	return 0;
426 }
427 
work_pop_atom(struct perf_kwork * kwork,struct kwork_class * class,enum kwork_trace_type src_type,enum kwork_trace_type dst_type,struct evsel * evsel,struct perf_sample * sample,struct machine * machine,struct kwork_work ** ret_work)428 static struct kwork_atom *work_pop_atom(struct perf_kwork *kwork,
429 					struct kwork_class *class,
430 					enum kwork_trace_type src_type,
431 					enum kwork_trace_type dst_type,
432 					struct evsel *evsel,
433 					struct perf_sample *sample,
434 					struct machine *machine,
435 					struct kwork_work **ret_work)
436 {
437 	struct kwork_atom *atom, *src_atom;
438 	struct kwork_work *work, key;
439 
440 	BUG_ON(class->work_init == NULL);
441 	class->work_init(class, &key, evsel, sample, machine);
442 
443 	work = work_findnew(&class->work_root, &key, &kwork->cmp_id);
444 	if (ret_work != NULL)
445 		*ret_work = work;
446 
447 	if (work == NULL)
448 		return NULL;
449 
450 	if (!profile_event_match(kwork, work, sample))
451 		return NULL;
452 
453 	atom = list_last_entry_or_null(&work->atom_list[dst_type],
454 				       struct kwork_atom, list);
455 	if (atom != NULL)
456 		return atom;
457 
458 	src_atom = atom_new(kwork, sample);
459 	if (src_atom != NULL)
460 		list_add_tail(&src_atom->list, &work->atom_list[src_type]);
461 	else {
462 		if (ret_work != NULL)
463 			*ret_work = NULL;
464 	}
465 
466 	return NULL;
467 }
468 
report_update_exit_event(struct kwork_work * work,struct kwork_atom * atom,struct perf_sample * sample)469 static void report_update_exit_event(struct kwork_work *work,
470 				     struct kwork_atom *atom,
471 				     struct perf_sample *sample)
472 {
473 	u64 delta;
474 	u64 exit_time = sample->time;
475 	u64 entry_time = atom->time;
476 
477 	if ((entry_time != 0) && (exit_time >= entry_time)) {
478 		delta = exit_time - entry_time;
479 		if ((delta > work->max_runtime) ||
480 		    (work->max_runtime == 0)) {
481 			work->max_runtime = delta;
482 			work->max_runtime_start = entry_time;
483 			work->max_runtime_end = exit_time;
484 		}
485 		work->total_runtime += delta;
486 		work->nr_atoms++;
487 	}
488 }
489 
report_entry_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)490 static int report_entry_event(struct perf_kwork *kwork,
491 			      struct kwork_class *class,
492 			      struct evsel *evsel,
493 			      struct perf_sample *sample,
494 			      struct machine *machine)
495 {
496 	return work_push_atom(kwork, class, KWORK_TRACE_ENTRY,
497 			      KWORK_TRACE_MAX, evsel, sample,
498 			      machine, NULL);
499 }
500 
report_exit_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)501 static int report_exit_event(struct perf_kwork *kwork,
502 			     struct kwork_class *class,
503 			     struct evsel *evsel,
504 			     struct perf_sample *sample,
505 			     struct machine *machine)
506 {
507 	struct kwork_atom *atom = NULL;
508 	struct kwork_work *work = NULL;
509 
510 	atom = work_pop_atom(kwork, class, KWORK_TRACE_EXIT,
511 			     KWORK_TRACE_ENTRY, evsel, sample,
512 			     machine, &work);
513 	if (work == NULL)
514 		return -1;
515 
516 	if (atom != NULL) {
517 		report_update_exit_event(work, atom, sample);
518 		atom_del(atom);
519 	}
520 
521 	return 0;
522 }
523 
latency_update_entry_event(struct kwork_work * work,struct kwork_atom * atom,struct perf_sample * sample)524 static void latency_update_entry_event(struct kwork_work *work,
525 				       struct kwork_atom *atom,
526 				       struct perf_sample *sample)
527 {
528 	u64 delta;
529 	u64 entry_time = sample->time;
530 	u64 raise_time = atom->time;
531 
532 	if ((raise_time != 0) && (entry_time >= raise_time)) {
533 		delta = entry_time - raise_time;
534 		if ((delta > work->max_latency) ||
535 		    (work->max_latency == 0)) {
536 			work->max_latency = delta;
537 			work->max_latency_start = raise_time;
538 			work->max_latency_end = entry_time;
539 		}
540 		work->total_latency += delta;
541 		work->nr_atoms++;
542 	}
543 }
544 
latency_raise_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)545 static int latency_raise_event(struct perf_kwork *kwork,
546 			       struct kwork_class *class,
547 			       struct evsel *evsel,
548 			       struct perf_sample *sample,
549 			       struct machine *machine)
550 {
551 	return work_push_atom(kwork, class, KWORK_TRACE_RAISE,
552 			      KWORK_TRACE_MAX, evsel, sample,
553 			      machine, NULL);
554 }
555 
latency_entry_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)556 static int latency_entry_event(struct perf_kwork *kwork,
557 			       struct kwork_class *class,
558 			       struct evsel *evsel,
559 			       struct perf_sample *sample,
560 			       struct machine *machine)
561 {
562 	struct kwork_atom *atom = NULL;
563 	struct kwork_work *work = NULL;
564 
565 	atom = work_pop_atom(kwork, class, KWORK_TRACE_ENTRY,
566 			     KWORK_TRACE_RAISE, evsel, sample,
567 			     machine, &work);
568 	if (work == NULL)
569 		return -1;
570 
571 	if (atom != NULL) {
572 		latency_update_entry_event(work, atom, sample);
573 		atom_del(atom);
574 	}
575 
576 	return 0;
577 }
578 
timehist_save_callchain(struct perf_kwork * kwork,struct perf_sample * sample,struct evsel * evsel,struct machine * machine)579 static void timehist_save_callchain(struct perf_kwork *kwork,
580 				    struct perf_sample *sample,
581 				    struct evsel *evsel,
582 				    struct machine *machine)
583 {
584 	struct symbol *sym;
585 	struct thread *thread;
586 	struct callchain_cursor_node *node;
587 	struct callchain_cursor *cursor = &callchain_cursor;
588 
589 	if (!kwork->show_callchain || sample->callchain == NULL)
590 		return;
591 
592 	/* want main thread for process - has maps */
593 	thread = machine__findnew_thread(machine, sample->pid, sample->pid);
594 	if (thread == NULL) {
595 		pr_debug("Failed to get thread for pid %d\n", sample->pid);
596 		return;
597 	}
598 
599 	if (thread__resolve_callchain(thread, cursor, evsel, sample,
600 				      NULL, NULL, kwork->max_stack + 2) != 0) {
601 		pr_debug("Failed to resolve callchain, skipping\n");
602 		goto out_put;
603 	}
604 
605 	callchain_cursor_commit(cursor);
606 
607 	while (true) {
608 		node = callchain_cursor_current(cursor);
609 		if (node == NULL)
610 			break;
611 
612 		sym = node->ms.sym;
613 		if (sym) {
614 			if (!strcmp(sym->name, "__softirqentry_text_start") ||
615 			    !strcmp(sym->name, "__do_softirq"))
616 				sym->ignore = 1;
617 		}
618 
619 		callchain_cursor_advance(cursor);
620 	}
621 
622 out_put:
623 	thread__put(thread);
624 }
625 
timehist_print_event(struct perf_kwork * kwork,struct kwork_work * work,struct kwork_atom * atom,struct perf_sample * sample,struct addr_location * al)626 static void timehist_print_event(struct perf_kwork *kwork,
627 				 struct kwork_work *work,
628 				 struct kwork_atom *atom,
629 				 struct perf_sample *sample,
630 				 struct addr_location *al)
631 {
632 	char entrytime[32], exittime[32];
633 	char kwork_name[PRINT_KWORK_NAME_WIDTH];
634 
635 	/*
636 	 * runtime start
637 	 */
638 	timestamp__scnprintf_usec(atom->time,
639 				  entrytime, sizeof(entrytime));
640 	printf(" %*s ", PRINT_TIMESTAMP_WIDTH, entrytime);
641 
642 	/*
643 	 * runtime end
644 	 */
645 	timestamp__scnprintf_usec(sample->time,
646 				  exittime, sizeof(exittime));
647 	printf(" %*s ", PRINT_TIMESTAMP_WIDTH, exittime);
648 
649 	/*
650 	 * cpu
651 	 */
652 	printf(" [%0*d] ", PRINT_CPU_WIDTH, work->cpu);
653 
654 	/*
655 	 * kwork name
656 	 */
657 	if (work->class && work->class->work_name) {
658 		work->class->work_name(work, kwork_name,
659 				       PRINT_KWORK_NAME_WIDTH);
660 		printf(" %-*s ", PRINT_KWORK_NAME_WIDTH, kwork_name);
661 	} else
662 		printf(" %-*s ", PRINT_KWORK_NAME_WIDTH, "");
663 
664 	/*
665 	 *runtime
666 	 */
667 	printf(" %*.*f ",
668 	       PRINT_RUNTIME_WIDTH, RPINT_DECIMAL_WIDTH,
669 	       (double)(sample->time - atom->time) / NSEC_PER_MSEC);
670 
671 	/*
672 	 * delaytime
673 	 */
674 	if (atom->prev != NULL)
675 		printf(" %*.*f ", PRINT_LATENCY_WIDTH, RPINT_DECIMAL_WIDTH,
676 		       (double)(atom->time - atom->prev->time) / NSEC_PER_MSEC);
677 	else
678 		printf(" %*s ", PRINT_LATENCY_WIDTH, " ");
679 
680 	/*
681 	 * callchain
682 	 */
683 	if (kwork->show_callchain) {
684 		printf(" ");
685 		sample__fprintf_sym(sample, al, 0,
686 				    EVSEL__PRINT_SYM | EVSEL__PRINT_ONELINE |
687 				    EVSEL__PRINT_CALLCHAIN_ARROW |
688 				    EVSEL__PRINT_SKIP_IGNORED,
689 				    &callchain_cursor, symbol_conf.bt_stop_list,
690 				    stdout);
691 	}
692 
693 	printf("\n");
694 }
695 
timehist_raise_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)696 static int timehist_raise_event(struct perf_kwork *kwork,
697 				struct kwork_class *class,
698 				struct evsel *evsel,
699 				struct perf_sample *sample,
700 				struct machine *machine)
701 {
702 	return work_push_atom(kwork, class, KWORK_TRACE_RAISE,
703 			      KWORK_TRACE_MAX, evsel, sample,
704 			      machine, NULL);
705 }
706 
timehist_entry_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)707 static int timehist_entry_event(struct perf_kwork *kwork,
708 				struct kwork_class *class,
709 				struct evsel *evsel,
710 				struct perf_sample *sample,
711 				struct machine *machine)
712 {
713 	int ret;
714 	struct kwork_work *work = NULL;
715 
716 	ret = work_push_atom(kwork, class, KWORK_TRACE_ENTRY,
717 			     KWORK_TRACE_RAISE, evsel, sample,
718 			     machine, &work);
719 	if (ret)
720 		return ret;
721 
722 	if (work != NULL)
723 		timehist_save_callchain(kwork, sample, evsel, machine);
724 
725 	return 0;
726 }
727 
timehist_exit_event(struct perf_kwork * kwork,struct kwork_class * class,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)728 static int timehist_exit_event(struct perf_kwork *kwork,
729 			       struct kwork_class *class,
730 			       struct evsel *evsel,
731 			       struct perf_sample *sample,
732 			       struct machine *machine)
733 {
734 	struct kwork_atom *atom = NULL;
735 	struct kwork_work *work = NULL;
736 	struct addr_location al;
737 
738 	if (machine__resolve(machine, &al, sample) < 0) {
739 		pr_debug("Problem processing event, skipping it\n");
740 		return -1;
741 	}
742 
743 	atom = work_pop_atom(kwork, class, KWORK_TRACE_EXIT,
744 			     KWORK_TRACE_ENTRY, evsel, sample,
745 			     machine, &work);
746 	if (work == NULL)
747 		return -1;
748 
749 	if (atom != NULL) {
750 		work->nr_atoms++;
751 		timehist_print_event(kwork, work, atom, sample, &al);
752 		atom_del(atom);
753 	}
754 
755 	return 0;
756 }
757 
758 static struct kwork_class kwork_irq;
process_irq_handler_entry_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)759 static int process_irq_handler_entry_event(struct perf_tool *tool,
760 					   struct evsel *evsel,
761 					   struct perf_sample *sample,
762 					   struct machine *machine)
763 {
764 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
765 
766 	if (kwork->tp_handler->entry_event)
767 		return kwork->tp_handler->entry_event(kwork, &kwork_irq,
768 						      evsel, sample, machine);
769 	return 0;
770 }
771 
process_irq_handler_exit_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)772 static int process_irq_handler_exit_event(struct perf_tool *tool,
773 					  struct evsel *evsel,
774 					  struct perf_sample *sample,
775 					  struct machine *machine)
776 {
777 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
778 
779 	if (kwork->tp_handler->exit_event)
780 		return kwork->tp_handler->exit_event(kwork, &kwork_irq,
781 						     evsel, sample, machine);
782 	return 0;
783 }
784 
785 const struct evsel_str_handler irq_tp_handlers[] = {
786 	{ "irq:irq_handler_entry", process_irq_handler_entry_event, },
787 	{ "irq:irq_handler_exit",  process_irq_handler_exit_event,  },
788 };
789 
irq_class_init(struct kwork_class * class,struct perf_session * session)790 static int irq_class_init(struct kwork_class *class,
791 			  struct perf_session *session)
792 {
793 	if (perf_session__set_tracepoints_handlers(session, irq_tp_handlers)) {
794 		pr_err("Failed to set irq tracepoints handlers\n");
795 		return -1;
796 	}
797 
798 	class->work_root = RB_ROOT_CACHED;
799 	return 0;
800 }
801 
irq_work_init(struct kwork_class * class,struct kwork_work * work,struct evsel * evsel,struct perf_sample * sample,struct machine * machine __maybe_unused)802 static void irq_work_init(struct kwork_class *class,
803 			  struct kwork_work *work,
804 			  struct evsel *evsel,
805 			  struct perf_sample *sample,
806 			  struct machine *machine __maybe_unused)
807 {
808 	work->class = class;
809 	work->cpu = sample->cpu;
810 	work->id = evsel__intval(evsel, sample, "irq");
811 	work->name = evsel__strval(evsel, sample, "name");
812 }
813 
irq_work_name(struct kwork_work * work,char * buf,int len)814 static void irq_work_name(struct kwork_work *work, char *buf, int len)
815 {
816 	snprintf(buf, len, "%s:%" PRIu64 "", work->name, work->id);
817 }
818 
819 static struct kwork_class kwork_irq = {
820 	.name           = "irq",
821 	.type           = KWORK_CLASS_IRQ,
822 	.nr_tracepoints = 2,
823 	.tp_handlers    = irq_tp_handlers,
824 	.class_init     = irq_class_init,
825 	.work_init      = irq_work_init,
826 	.work_name      = irq_work_name,
827 };
828 
829 static struct kwork_class kwork_softirq;
process_softirq_raise_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)830 static int process_softirq_raise_event(struct perf_tool *tool,
831 				       struct evsel *evsel,
832 				       struct perf_sample *sample,
833 				       struct machine *machine)
834 {
835 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
836 
837 	if (kwork->tp_handler->raise_event)
838 		return kwork->tp_handler->raise_event(kwork, &kwork_softirq,
839 						      evsel, sample, machine);
840 
841 	return 0;
842 }
843 
process_softirq_entry_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)844 static int process_softirq_entry_event(struct perf_tool *tool,
845 				       struct evsel *evsel,
846 				       struct perf_sample *sample,
847 				       struct machine *machine)
848 {
849 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
850 
851 	if (kwork->tp_handler->entry_event)
852 		return kwork->tp_handler->entry_event(kwork, &kwork_softirq,
853 						      evsel, sample, machine);
854 
855 	return 0;
856 }
857 
process_softirq_exit_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)858 static int process_softirq_exit_event(struct perf_tool *tool,
859 				      struct evsel *evsel,
860 				      struct perf_sample *sample,
861 				      struct machine *machine)
862 {
863 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
864 
865 	if (kwork->tp_handler->exit_event)
866 		return kwork->tp_handler->exit_event(kwork, &kwork_softirq,
867 						     evsel, sample, machine);
868 
869 	return 0;
870 }
871 
872 const struct evsel_str_handler softirq_tp_handlers[] = {
873 	{ "irq:softirq_raise", process_softirq_raise_event, },
874 	{ "irq:softirq_entry", process_softirq_entry_event, },
875 	{ "irq:softirq_exit",  process_softirq_exit_event,  },
876 };
877 
softirq_class_init(struct kwork_class * class,struct perf_session * session)878 static int softirq_class_init(struct kwork_class *class,
879 			      struct perf_session *session)
880 {
881 	if (perf_session__set_tracepoints_handlers(session,
882 						   softirq_tp_handlers)) {
883 		pr_err("Failed to set softirq tracepoints handlers\n");
884 		return -1;
885 	}
886 
887 	class->work_root = RB_ROOT_CACHED;
888 	return 0;
889 }
890 
evsel__softirq_name(struct evsel * evsel,u64 num)891 static char *evsel__softirq_name(struct evsel *evsel, u64 num)
892 {
893 	char *name = NULL;
894 	bool found = false;
895 	struct tep_print_flag_sym *sym = NULL;
896 	struct tep_print_arg *args = evsel->tp_format->print_fmt.args;
897 
898 	if ((args == NULL) || (args->next == NULL))
899 		return NULL;
900 
901 	/* skip softirq field: "REC->vec" */
902 	for (sym = args->next->symbol.symbols; sym != NULL; sym = sym->next) {
903 		if ((eval_flag(sym->value) == (unsigned long long)num) &&
904 		    (strlen(sym->str) != 0)) {
905 			found = true;
906 			break;
907 		}
908 	}
909 
910 	if (!found)
911 		return NULL;
912 
913 	name = strdup(sym->str);
914 	if (name == NULL) {
915 		pr_err("Failed to copy symbol name\n");
916 		return NULL;
917 	}
918 	return name;
919 }
920 
softirq_work_init(struct kwork_class * class,struct kwork_work * work,struct evsel * evsel,struct perf_sample * sample,struct machine * machine __maybe_unused)921 static void softirq_work_init(struct kwork_class *class,
922 			      struct kwork_work *work,
923 			      struct evsel *evsel,
924 			      struct perf_sample *sample,
925 			      struct machine *machine __maybe_unused)
926 {
927 	u64 num = evsel__intval(evsel, sample, "vec");
928 
929 	work->id = num;
930 	work->class = class;
931 	work->cpu = sample->cpu;
932 	work->name = evsel__softirq_name(evsel, num);
933 }
934 
softirq_work_name(struct kwork_work * work,char * buf,int len)935 static void softirq_work_name(struct kwork_work *work, char *buf, int len)
936 {
937 	snprintf(buf, len, "(s)%s:%" PRIu64 "", work->name, work->id);
938 }
939 
940 static struct kwork_class kwork_softirq = {
941 	.name           = "softirq",
942 	.type           = KWORK_CLASS_SOFTIRQ,
943 	.nr_tracepoints = 3,
944 	.tp_handlers    = softirq_tp_handlers,
945 	.class_init     = softirq_class_init,
946 	.work_init      = softirq_work_init,
947 	.work_name      = softirq_work_name,
948 };
949 
950 static struct kwork_class kwork_workqueue;
process_workqueue_activate_work_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)951 static int process_workqueue_activate_work_event(struct perf_tool *tool,
952 						 struct evsel *evsel,
953 						 struct perf_sample *sample,
954 						 struct machine *machine)
955 {
956 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
957 
958 	if (kwork->tp_handler->raise_event)
959 		return kwork->tp_handler->raise_event(kwork, &kwork_workqueue,
960 						    evsel, sample, machine);
961 
962 	return 0;
963 }
964 
process_workqueue_execute_start_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)965 static int process_workqueue_execute_start_event(struct perf_tool *tool,
966 						 struct evsel *evsel,
967 						 struct perf_sample *sample,
968 						 struct machine *machine)
969 {
970 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
971 
972 	if (kwork->tp_handler->entry_event)
973 		return kwork->tp_handler->entry_event(kwork, &kwork_workqueue,
974 						    evsel, sample, machine);
975 
976 	return 0;
977 }
978 
process_workqueue_execute_end_event(struct perf_tool * tool,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)979 static int process_workqueue_execute_end_event(struct perf_tool *tool,
980 					       struct evsel *evsel,
981 					       struct perf_sample *sample,
982 					       struct machine *machine)
983 {
984 	struct perf_kwork *kwork = container_of(tool, struct perf_kwork, tool);
985 
986 	if (kwork->tp_handler->exit_event)
987 		return kwork->tp_handler->exit_event(kwork, &kwork_workqueue,
988 						   evsel, sample, machine);
989 
990 	return 0;
991 }
992 
993 const struct evsel_str_handler workqueue_tp_handlers[] = {
994 	{ "workqueue:workqueue_activate_work", process_workqueue_activate_work_event, },
995 	{ "workqueue:workqueue_execute_start", process_workqueue_execute_start_event, },
996 	{ "workqueue:workqueue_execute_end",   process_workqueue_execute_end_event,   },
997 };
998 
workqueue_class_init(struct kwork_class * class,struct perf_session * session)999 static int workqueue_class_init(struct kwork_class *class,
1000 				struct perf_session *session)
1001 {
1002 	if (perf_session__set_tracepoints_handlers(session,
1003 						   workqueue_tp_handlers)) {
1004 		pr_err("Failed to set workqueue tracepoints handlers\n");
1005 		return -1;
1006 	}
1007 
1008 	class->work_root = RB_ROOT_CACHED;
1009 	return 0;
1010 }
1011 
workqueue_work_init(struct kwork_class * class,struct kwork_work * work,struct evsel * evsel,struct perf_sample * sample,struct machine * machine)1012 static void workqueue_work_init(struct kwork_class *class,
1013 				struct kwork_work *work,
1014 				struct evsel *evsel,
1015 				struct perf_sample *sample,
1016 				struct machine *machine)
1017 {
1018 	char *modp = NULL;
1019 	unsigned long long function_addr = evsel__intval(evsel,
1020 							 sample, "function");
1021 
1022 	work->class = class;
1023 	work->cpu = sample->cpu;
1024 	work->id = evsel__intval(evsel, sample, "work");
1025 	work->name = function_addr == 0 ? NULL :
1026 		machine__resolve_kernel_addr(machine, &function_addr, &modp);
1027 }
1028 
workqueue_work_name(struct kwork_work * work,char * buf,int len)1029 static void workqueue_work_name(struct kwork_work *work, char *buf, int len)
1030 {
1031 	if (work->name != NULL)
1032 		snprintf(buf, len, "(w)%s", work->name);
1033 	else
1034 		snprintf(buf, len, "(w)0x%" PRIx64, work->id);
1035 }
1036 
1037 static struct kwork_class kwork_workqueue = {
1038 	.name           = "workqueue",
1039 	.type           = KWORK_CLASS_WORKQUEUE,
1040 	.nr_tracepoints = 3,
1041 	.tp_handlers    = workqueue_tp_handlers,
1042 	.class_init     = workqueue_class_init,
1043 	.work_init      = workqueue_work_init,
1044 	.work_name      = workqueue_work_name,
1045 };
1046 
1047 static struct kwork_class *kwork_class_supported_list[KWORK_CLASS_MAX] = {
1048 	[KWORK_CLASS_IRQ]       = &kwork_irq,
1049 	[KWORK_CLASS_SOFTIRQ]   = &kwork_softirq,
1050 	[KWORK_CLASS_WORKQUEUE] = &kwork_workqueue,
1051 };
1052 
print_separator(int len)1053 static void print_separator(int len)
1054 {
1055 	printf(" %.*s\n", len, graph_dotted_line);
1056 }
1057 
report_print_work(struct perf_kwork * kwork,struct kwork_work * work)1058 static int report_print_work(struct perf_kwork *kwork, struct kwork_work *work)
1059 {
1060 	int ret = 0;
1061 	char kwork_name[PRINT_KWORK_NAME_WIDTH];
1062 	char max_runtime_start[32], max_runtime_end[32];
1063 	char max_latency_start[32], max_latency_end[32];
1064 
1065 	printf(" ");
1066 
1067 	/*
1068 	 * kwork name
1069 	 */
1070 	if (work->class && work->class->work_name) {
1071 		work->class->work_name(work, kwork_name,
1072 				       PRINT_KWORK_NAME_WIDTH);
1073 		ret += printf(" %-*s |", PRINT_KWORK_NAME_WIDTH, kwork_name);
1074 	} else {
1075 		ret += printf(" %-*s |", PRINT_KWORK_NAME_WIDTH, "");
1076 	}
1077 
1078 	/*
1079 	 * cpu
1080 	 */
1081 	ret += printf(" %0*d |", PRINT_CPU_WIDTH, work->cpu);
1082 
1083 	/*
1084 	 * total runtime
1085 	 */
1086 	if (kwork->report == KWORK_REPORT_RUNTIME) {
1087 		ret += printf(" %*.*f ms |",
1088 			      PRINT_RUNTIME_WIDTH, RPINT_DECIMAL_WIDTH,
1089 			      (double)work->total_runtime / NSEC_PER_MSEC);
1090 	} else if (kwork->report == KWORK_REPORT_LATENCY) { // avg delay
1091 		ret += printf(" %*.*f ms |",
1092 			      PRINT_LATENCY_WIDTH, RPINT_DECIMAL_WIDTH,
1093 			      (double)work->total_latency /
1094 			      work->nr_atoms / NSEC_PER_MSEC);
1095 	}
1096 
1097 	/*
1098 	 * count
1099 	 */
1100 	ret += printf(" %*" PRIu64 " |", PRINT_COUNT_WIDTH, work->nr_atoms);
1101 
1102 	/*
1103 	 * max runtime, max runtime start, max runtime end
1104 	 */
1105 	if (kwork->report == KWORK_REPORT_RUNTIME) {
1106 		timestamp__scnprintf_usec(work->max_runtime_start,
1107 					  max_runtime_start,
1108 					  sizeof(max_runtime_start));
1109 		timestamp__scnprintf_usec(work->max_runtime_end,
1110 					  max_runtime_end,
1111 					  sizeof(max_runtime_end));
1112 		ret += printf(" %*.*f ms | %*s s | %*s s |",
1113 			      PRINT_RUNTIME_WIDTH, RPINT_DECIMAL_WIDTH,
1114 			      (double)work->max_runtime / NSEC_PER_MSEC,
1115 			      PRINT_TIMESTAMP_WIDTH, max_runtime_start,
1116 			      PRINT_TIMESTAMP_WIDTH, max_runtime_end);
1117 	}
1118 	/*
1119 	 * max delay, max delay start, max delay end
1120 	 */
1121 	else if (kwork->report == KWORK_REPORT_LATENCY) {
1122 		timestamp__scnprintf_usec(work->max_latency_start,
1123 					  max_latency_start,
1124 					  sizeof(max_latency_start));
1125 		timestamp__scnprintf_usec(work->max_latency_end,
1126 					  max_latency_end,
1127 					  sizeof(max_latency_end));
1128 		ret += printf(" %*.*f ms | %*s s | %*s s |",
1129 			      PRINT_LATENCY_WIDTH, RPINT_DECIMAL_WIDTH,
1130 			      (double)work->max_latency / NSEC_PER_MSEC,
1131 			      PRINT_TIMESTAMP_WIDTH, max_latency_start,
1132 			      PRINT_TIMESTAMP_WIDTH, max_latency_end);
1133 	}
1134 
1135 	printf("\n");
1136 	return ret;
1137 }
1138 
report_print_header(struct perf_kwork * kwork)1139 static int report_print_header(struct perf_kwork *kwork)
1140 {
1141 	int ret;
1142 
1143 	printf("\n ");
1144 	ret = printf(" %-*s | %-*s |",
1145 		     PRINT_KWORK_NAME_WIDTH, "Kwork Name",
1146 		     PRINT_CPU_WIDTH, "Cpu");
1147 
1148 	if (kwork->report == KWORK_REPORT_RUNTIME) {
1149 		ret += printf(" %-*s |",
1150 			      PRINT_RUNTIME_HEADER_WIDTH, "Total Runtime");
1151 	} else if (kwork->report == KWORK_REPORT_LATENCY) {
1152 		ret += printf(" %-*s |",
1153 			      PRINT_LATENCY_HEADER_WIDTH, "Avg delay");
1154 	}
1155 
1156 	ret += printf(" %-*s |", PRINT_COUNT_WIDTH, "Count");
1157 
1158 	if (kwork->report == KWORK_REPORT_RUNTIME) {
1159 		ret += printf(" %-*s | %-*s | %-*s |",
1160 			      PRINT_RUNTIME_HEADER_WIDTH, "Max runtime",
1161 			      PRINT_TIMESTAMP_HEADER_WIDTH, "Max runtime start",
1162 			      PRINT_TIMESTAMP_HEADER_WIDTH, "Max runtime end");
1163 	} else if (kwork->report == KWORK_REPORT_LATENCY) {
1164 		ret += printf(" %-*s | %-*s | %-*s |",
1165 			      PRINT_LATENCY_HEADER_WIDTH, "Max delay",
1166 			      PRINT_TIMESTAMP_HEADER_WIDTH, "Max delay start",
1167 			      PRINT_TIMESTAMP_HEADER_WIDTH, "Max delay end");
1168 	}
1169 
1170 	printf("\n");
1171 	print_separator(ret);
1172 	return ret;
1173 }
1174 
timehist_print_header(void)1175 static void timehist_print_header(void)
1176 {
1177 	/*
1178 	 * header row
1179 	 */
1180 	printf(" %-*s  %-*s  %-*s  %-*s  %-*s  %-*s\n",
1181 	       PRINT_TIMESTAMP_WIDTH, "Runtime start",
1182 	       PRINT_TIMESTAMP_WIDTH, "Runtime end",
1183 	       PRINT_TIMEHIST_CPU_WIDTH, "Cpu",
1184 	       PRINT_KWORK_NAME_WIDTH, "Kwork name",
1185 	       PRINT_RUNTIME_WIDTH, "Runtime",
1186 	       PRINT_RUNTIME_WIDTH, "Delaytime");
1187 
1188 	/*
1189 	 * units row
1190 	 */
1191 	printf(" %-*s  %-*s  %-*s  %-*s  %-*s  %-*s\n",
1192 	       PRINT_TIMESTAMP_WIDTH, "",
1193 	       PRINT_TIMESTAMP_WIDTH, "",
1194 	       PRINT_TIMEHIST_CPU_WIDTH, "",
1195 	       PRINT_KWORK_NAME_WIDTH, "(TYPE)NAME:NUM",
1196 	       PRINT_RUNTIME_WIDTH, "(msec)",
1197 	       PRINT_RUNTIME_WIDTH, "(msec)");
1198 
1199 	/*
1200 	 * separator
1201 	 */
1202 	printf(" %.*s  %.*s  %.*s  %.*s  %.*s  %.*s\n",
1203 	       PRINT_TIMESTAMP_WIDTH, graph_dotted_line,
1204 	       PRINT_TIMESTAMP_WIDTH, graph_dotted_line,
1205 	       PRINT_TIMEHIST_CPU_WIDTH, graph_dotted_line,
1206 	       PRINT_KWORK_NAME_WIDTH, graph_dotted_line,
1207 	       PRINT_RUNTIME_WIDTH, graph_dotted_line,
1208 	       PRINT_RUNTIME_WIDTH, graph_dotted_line);
1209 }
1210 
print_summary(struct perf_kwork * kwork)1211 static void print_summary(struct perf_kwork *kwork)
1212 {
1213 	u64 time = kwork->timeend - kwork->timestart;
1214 
1215 	printf("  Total count            : %9" PRIu64 "\n", kwork->all_count);
1216 	printf("  Total runtime   (msec) : %9.3f (%.3f%% load average)\n",
1217 	       (double)kwork->all_runtime / NSEC_PER_MSEC,
1218 	       time == 0 ? 0 : (double)kwork->all_runtime / time);
1219 	printf("  Total time span (msec) : %9.3f\n",
1220 	       (double)time / NSEC_PER_MSEC);
1221 }
1222 
nr_list_entry(struct list_head * head)1223 static unsigned long long nr_list_entry(struct list_head *head)
1224 {
1225 	struct list_head *pos;
1226 	unsigned long long n = 0;
1227 
1228 	list_for_each(pos, head)
1229 		n++;
1230 
1231 	return n;
1232 }
1233 
print_skipped_events(struct perf_kwork * kwork)1234 static void print_skipped_events(struct perf_kwork *kwork)
1235 {
1236 	int i;
1237 	const char *const kwork_event_str[] = {
1238 		[KWORK_TRACE_RAISE] = "raise",
1239 		[KWORK_TRACE_ENTRY] = "entry",
1240 		[KWORK_TRACE_EXIT]  = "exit",
1241 	};
1242 
1243 	if ((kwork->nr_skipped_events[KWORK_TRACE_MAX] != 0) &&
1244 	    (kwork->nr_events != 0)) {
1245 		printf("  INFO: %.3f%% skipped events (%" PRIu64 " including ",
1246 		       (double)kwork->nr_skipped_events[KWORK_TRACE_MAX] /
1247 		       (double)kwork->nr_events * 100.0,
1248 		       kwork->nr_skipped_events[KWORK_TRACE_MAX]);
1249 
1250 		for (i = 0; i < KWORK_TRACE_MAX; i++) {
1251 			printf("%" PRIu64 " %s%s",
1252 			       kwork->nr_skipped_events[i],
1253 			       kwork_event_str[i],
1254 			       (i == KWORK_TRACE_MAX - 1) ? ")\n" : ", ");
1255 		}
1256 	}
1257 
1258 	if (verbose > 0)
1259 		printf("  INFO: use %lld atom pages\n",
1260 		       nr_list_entry(&kwork->atom_page_list));
1261 }
1262 
print_bad_events(struct perf_kwork * kwork)1263 static void print_bad_events(struct perf_kwork *kwork)
1264 {
1265 	if ((kwork->nr_lost_events != 0) && (kwork->nr_events != 0)) {
1266 		printf("  INFO: %.3f%% lost events (%ld out of %ld, in %ld chunks)\n",
1267 		       (double)kwork->nr_lost_events /
1268 		       (double)kwork->nr_events * 100.0,
1269 		       kwork->nr_lost_events, kwork->nr_events,
1270 		       kwork->nr_lost_chunks);
1271 	}
1272 }
1273 
work_sort(struct perf_kwork * kwork,struct kwork_class * class)1274 static void work_sort(struct perf_kwork *kwork, struct kwork_class *class)
1275 {
1276 	struct rb_node *node;
1277 	struct kwork_work *data;
1278 	struct rb_root_cached *root = &class->work_root;
1279 
1280 	pr_debug("Sorting %s ...\n", class->name);
1281 	for (;;) {
1282 		node = rb_first_cached(root);
1283 		if (!node)
1284 			break;
1285 
1286 		rb_erase_cached(node, root);
1287 		data = rb_entry(node, struct kwork_work, node);
1288 		work_insert(&kwork->sorted_work_root,
1289 			       data, &kwork->sort_list);
1290 	}
1291 }
1292 
perf_kwork__sort(struct perf_kwork * kwork)1293 static void perf_kwork__sort(struct perf_kwork *kwork)
1294 {
1295 	struct kwork_class *class;
1296 
1297 	list_for_each_entry(class, &kwork->class_list, list)
1298 		work_sort(kwork, class);
1299 }
1300 
perf_kwork__check_config(struct perf_kwork * kwork,struct perf_session * session)1301 static int perf_kwork__check_config(struct perf_kwork *kwork,
1302 				    struct perf_session *session)
1303 {
1304 	int ret;
1305 	struct evsel *evsel;
1306 	struct kwork_class *class;
1307 
1308 	static struct trace_kwork_handler report_ops = {
1309 		.entry_event = report_entry_event,
1310 		.exit_event  = report_exit_event,
1311 	};
1312 	static struct trace_kwork_handler latency_ops = {
1313 		.raise_event = latency_raise_event,
1314 		.entry_event = latency_entry_event,
1315 	};
1316 	static struct trace_kwork_handler timehist_ops = {
1317 		.raise_event = timehist_raise_event,
1318 		.entry_event = timehist_entry_event,
1319 		.exit_event  = timehist_exit_event,
1320 	};
1321 
1322 	switch (kwork->report) {
1323 	case KWORK_REPORT_RUNTIME:
1324 		kwork->tp_handler = &report_ops;
1325 		break;
1326 	case KWORK_REPORT_LATENCY:
1327 		kwork->tp_handler = &latency_ops;
1328 		break;
1329 	case KWORK_REPORT_TIMEHIST:
1330 		kwork->tp_handler = &timehist_ops;
1331 		break;
1332 	default:
1333 		pr_debug("Invalid report type %d\n", kwork->report);
1334 		return -1;
1335 	}
1336 
1337 	list_for_each_entry(class, &kwork->class_list, list)
1338 		if ((class->class_init != NULL) &&
1339 		    (class->class_init(class, session) != 0))
1340 			return -1;
1341 
1342 	if (kwork->cpu_list != NULL) {
1343 		ret = perf_session__cpu_bitmap(session,
1344 					       kwork->cpu_list,
1345 					       kwork->cpu_bitmap);
1346 		if (ret < 0) {
1347 			pr_err("Invalid cpu bitmap\n");
1348 			return -1;
1349 		}
1350 	}
1351 
1352 	if (kwork->time_str != NULL) {
1353 		ret = perf_time__parse_str(&kwork->ptime, kwork->time_str);
1354 		if (ret != 0) {
1355 			pr_err("Invalid time span\n");
1356 			return -1;
1357 		}
1358 	}
1359 
1360 	list_for_each_entry(evsel, &session->evlist->core.entries, core.node) {
1361 		if (kwork->show_callchain && !evsel__has_callchain(evsel)) {
1362 			pr_debug("Samples do not have callchains\n");
1363 			kwork->show_callchain = 0;
1364 			symbol_conf.use_callchain = 0;
1365 		}
1366 	}
1367 
1368 	return 0;
1369 }
1370 
perf_kwork__read_events(struct perf_kwork * kwork)1371 static int perf_kwork__read_events(struct perf_kwork *kwork)
1372 {
1373 	int ret = -1;
1374 	struct perf_session *session = NULL;
1375 
1376 	struct perf_data data = {
1377 		.path  = input_name,
1378 		.mode  = PERF_DATA_MODE_READ,
1379 		.force = kwork->force,
1380 	};
1381 
1382 	session = perf_session__new(&data, &kwork->tool);
1383 	if (IS_ERR(session)) {
1384 		pr_debug("Error creating perf session\n");
1385 		return PTR_ERR(session);
1386 	}
1387 
1388 	symbol__init(&session->header.env);
1389 
1390 	if (perf_kwork__check_config(kwork, session) != 0)
1391 		goto out_delete;
1392 
1393 	if (session->tevent.pevent &&
1394 	    tep_set_function_resolver(session->tevent.pevent,
1395 				      machine__resolve_kernel_addr,
1396 				      &session->machines.host) < 0) {
1397 		pr_err("Failed to set libtraceevent function resolver\n");
1398 		goto out_delete;
1399 	}
1400 
1401 	if (kwork->report == KWORK_REPORT_TIMEHIST)
1402 		timehist_print_header();
1403 
1404 	ret = perf_session__process_events(session);
1405 	if (ret) {
1406 		pr_debug("Failed to process events, error %d\n", ret);
1407 		goto out_delete;
1408 	}
1409 
1410 	kwork->nr_events      = session->evlist->stats.nr_events[0];
1411 	kwork->nr_lost_events = session->evlist->stats.total_lost;
1412 	kwork->nr_lost_chunks = session->evlist->stats.nr_events[PERF_RECORD_LOST];
1413 
1414 out_delete:
1415 	perf_session__delete(session);
1416 	return ret;
1417 }
1418 
process_skipped_events(struct perf_kwork * kwork,struct kwork_work * work)1419 static void process_skipped_events(struct perf_kwork *kwork,
1420 				   struct kwork_work *work)
1421 {
1422 	int i;
1423 	unsigned long long count;
1424 
1425 	for (i = 0; i < KWORK_TRACE_MAX; i++) {
1426 		count = nr_list_entry(&work->atom_list[i]);
1427 		kwork->nr_skipped_events[i] += count;
1428 		kwork->nr_skipped_events[KWORK_TRACE_MAX] += count;
1429 	}
1430 }
1431 
perf_kwork_add_work(struct perf_kwork * kwork,struct kwork_class * class,struct kwork_work * key)1432 struct kwork_work *perf_kwork_add_work(struct perf_kwork *kwork,
1433 				       struct kwork_class *class,
1434 				       struct kwork_work *key)
1435 {
1436 	struct kwork_work *work = NULL;
1437 
1438 	work = work_new(key);
1439 	if (work == NULL)
1440 		return NULL;
1441 
1442 	work_insert(&class->work_root, work, &kwork->cmp_id);
1443 	return work;
1444 }
1445 
sig_handler(int sig)1446 static void sig_handler(int sig)
1447 {
1448 	/*
1449 	 * Simply capture termination signal so that
1450 	 * the program can continue after pause returns
1451 	 */
1452 	pr_debug("Captuer signal %d\n", sig);
1453 }
1454 
perf_kwork__report_bpf(struct perf_kwork * kwork)1455 static int perf_kwork__report_bpf(struct perf_kwork *kwork)
1456 {
1457 	int ret;
1458 
1459 	signal(SIGINT, sig_handler);
1460 	signal(SIGTERM, sig_handler);
1461 
1462 	ret = perf_kwork__trace_prepare_bpf(kwork);
1463 	if (ret)
1464 		return -1;
1465 
1466 	printf("Starting trace, Hit <Ctrl+C> to stop and report\n");
1467 
1468 	perf_kwork__trace_start();
1469 
1470 	/*
1471 	 * a simple pause, wait here for stop signal
1472 	 */
1473 	pause();
1474 
1475 	perf_kwork__trace_finish();
1476 
1477 	perf_kwork__report_read_bpf(kwork);
1478 
1479 	perf_kwork__report_cleanup_bpf();
1480 
1481 	return 0;
1482 }
1483 
perf_kwork__report(struct perf_kwork * kwork)1484 static int perf_kwork__report(struct perf_kwork *kwork)
1485 {
1486 	int ret;
1487 	struct rb_node *next;
1488 	struct kwork_work *work;
1489 
1490 	if (kwork->use_bpf)
1491 		ret = perf_kwork__report_bpf(kwork);
1492 	else
1493 		ret = perf_kwork__read_events(kwork);
1494 
1495 	if (ret != 0)
1496 		return -1;
1497 
1498 	perf_kwork__sort(kwork);
1499 
1500 	setup_pager();
1501 
1502 	ret = report_print_header(kwork);
1503 	next = rb_first_cached(&kwork->sorted_work_root);
1504 	while (next) {
1505 		work = rb_entry(next, struct kwork_work, node);
1506 		process_skipped_events(kwork, work);
1507 
1508 		if (work->nr_atoms != 0) {
1509 			report_print_work(kwork, work);
1510 			if (kwork->summary) {
1511 				kwork->all_runtime += work->total_runtime;
1512 				kwork->all_count += work->nr_atoms;
1513 			}
1514 		}
1515 		next = rb_next(next);
1516 	}
1517 	print_separator(ret);
1518 
1519 	if (kwork->summary) {
1520 		print_summary(kwork);
1521 		print_separator(ret);
1522 	}
1523 
1524 	print_bad_events(kwork);
1525 	print_skipped_events(kwork);
1526 	printf("\n");
1527 
1528 	return 0;
1529 }
1530 
1531 typedef int (*tracepoint_handler)(struct perf_tool *tool,
1532 				  struct evsel *evsel,
1533 				  struct perf_sample *sample,
1534 				  struct machine *machine);
1535 
perf_kwork__process_tracepoint_sample(struct perf_tool * tool,union perf_event * event __maybe_unused,struct perf_sample * sample,struct evsel * evsel,struct machine * machine)1536 static int perf_kwork__process_tracepoint_sample(struct perf_tool *tool,
1537 						 union perf_event *event __maybe_unused,
1538 						 struct perf_sample *sample,
1539 						 struct evsel *evsel,
1540 						 struct machine *machine)
1541 {
1542 	int err = 0;
1543 
1544 	if (evsel->handler != NULL) {
1545 		tracepoint_handler f = evsel->handler;
1546 
1547 		err = f(tool, evsel, sample, machine);
1548 	}
1549 
1550 	return err;
1551 }
1552 
perf_kwork__timehist(struct perf_kwork * kwork)1553 static int perf_kwork__timehist(struct perf_kwork *kwork)
1554 {
1555 	/*
1556 	 * event handlers for timehist option
1557 	 */
1558 	kwork->tool.comm	 = perf_event__process_comm;
1559 	kwork->tool.exit	 = perf_event__process_exit;
1560 	kwork->tool.fork	 = perf_event__process_fork;
1561 	kwork->tool.attr	 = perf_event__process_attr;
1562 	kwork->tool.tracing_data = perf_event__process_tracing_data;
1563 	kwork->tool.build_id	 = perf_event__process_build_id;
1564 	kwork->tool.ordered_events = true;
1565 	kwork->tool.ordering_requires_timestamps = true;
1566 	symbol_conf.use_callchain = kwork->show_callchain;
1567 
1568 	if (symbol__validate_sym_arguments()) {
1569 		pr_err("Failed to validate sym arguments\n");
1570 		return -1;
1571 	}
1572 
1573 	setup_pager();
1574 
1575 	return perf_kwork__read_events(kwork);
1576 }
1577 
setup_event_list(struct perf_kwork * kwork,const struct option * options,const char * const usage_msg[])1578 static void setup_event_list(struct perf_kwork *kwork,
1579 			     const struct option *options,
1580 			     const char * const usage_msg[])
1581 {
1582 	int i;
1583 	struct kwork_class *class;
1584 	char *tmp, *tok, *str;
1585 
1586 	if (kwork->event_list_str == NULL)
1587 		goto null_event_list_str;
1588 
1589 	str = strdup(kwork->event_list_str);
1590 	for (tok = strtok_r(str, ", ", &tmp);
1591 	     tok; tok = strtok_r(NULL, ", ", &tmp)) {
1592 		for (i = 0; i < KWORK_CLASS_MAX; i++) {
1593 			class = kwork_class_supported_list[i];
1594 			if (strcmp(tok, class->name) == 0) {
1595 				list_add_tail(&class->list, &kwork->class_list);
1596 				break;
1597 			}
1598 		}
1599 		if (i == KWORK_CLASS_MAX) {
1600 			usage_with_options_msg(usage_msg, options,
1601 					       "Unknown --event key: `%s'", tok);
1602 		}
1603 	}
1604 	free(str);
1605 
1606 null_event_list_str:
1607 	/*
1608 	 * config all kwork events if not specified
1609 	 */
1610 	if (list_empty(&kwork->class_list)) {
1611 		for (i = 0; i < KWORK_CLASS_MAX; i++) {
1612 			list_add_tail(&kwork_class_supported_list[i]->list,
1613 				      &kwork->class_list);
1614 		}
1615 	}
1616 
1617 	pr_debug("Config event list:");
1618 	list_for_each_entry(class, &kwork->class_list, list)
1619 		pr_debug(" %s", class->name);
1620 	pr_debug("\n");
1621 }
1622 
perf_kwork__record(struct perf_kwork * kwork,int argc,const char ** argv)1623 static int perf_kwork__record(struct perf_kwork *kwork,
1624 			      int argc, const char **argv)
1625 {
1626 	const char **rec_argv;
1627 	unsigned int rec_argc, i, j;
1628 	struct kwork_class *class;
1629 
1630 	const char *const record_args[] = {
1631 		"record",
1632 		"-a",
1633 		"-R",
1634 		"-m", "1024",
1635 		"-c", "1",
1636 	};
1637 
1638 	rec_argc = ARRAY_SIZE(record_args) + argc - 1;
1639 
1640 	list_for_each_entry(class, &kwork->class_list, list)
1641 		rec_argc += 2 * class->nr_tracepoints;
1642 
1643 	rec_argv = calloc(rec_argc + 1, sizeof(char *));
1644 	if (rec_argv == NULL)
1645 		return -ENOMEM;
1646 
1647 	for (i = 0; i < ARRAY_SIZE(record_args); i++)
1648 		rec_argv[i] = strdup(record_args[i]);
1649 
1650 	list_for_each_entry(class, &kwork->class_list, list) {
1651 		for (j = 0; j < class->nr_tracepoints; j++) {
1652 			rec_argv[i++] = strdup("-e");
1653 			rec_argv[i++] = strdup(class->tp_handlers[j].name);
1654 		}
1655 	}
1656 
1657 	for (j = 1; j < (unsigned int)argc; j++, i++)
1658 		rec_argv[i] = argv[j];
1659 
1660 	BUG_ON(i != rec_argc);
1661 
1662 	pr_debug("record comm: ");
1663 	for (j = 0; j < rec_argc; j++)
1664 		pr_debug("%s ", rec_argv[j]);
1665 	pr_debug("\n");
1666 
1667 	return cmd_record(i, rec_argv);
1668 }
1669 
cmd_kwork(int argc,const char ** argv)1670 int cmd_kwork(int argc, const char **argv)
1671 {
1672 	static struct perf_kwork kwork = {
1673 		.class_list          = LIST_HEAD_INIT(kwork.class_list),
1674 		.tool = {
1675 			.mmap		= perf_event__process_mmap,
1676 			.mmap2		= perf_event__process_mmap2,
1677 			.sample		= perf_kwork__process_tracepoint_sample,
1678 			.ordered_events = true,
1679 		},
1680 		.atom_page_list      = LIST_HEAD_INIT(kwork.atom_page_list),
1681 		.sort_list           = LIST_HEAD_INIT(kwork.sort_list),
1682 		.cmp_id              = LIST_HEAD_INIT(kwork.cmp_id),
1683 		.sorted_work_root    = RB_ROOT_CACHED,
1684 		.tp_handler          = NULL,
1685 		.profile_name        = NULL,
1686 		.cpu_list            = NULL,
1687 		.time_str            = NULL,
1688 		.force               = false,
1689 		.event_list_str      = NULL,
1690 		.summary             = false,
1691 		.sort_order          = NULL,
1692 		.show_callchain      = false,
1693 		.max_stack           = 5,
1694 		.timestart           = 0,
1695 		.timeend             = 0,
1696 		.nr_events           = 0,
1697 		.nr_lost_chunks      = 0,
1698 		.nr_lost_events      = 0,
1699 		.all_runtime         = 0,
1700 		.all_count           = 0,
1701 		.nr_skipped_events   = { 0 },
1702 	};
1703 	static const char default_report_sort_order[] = "runtime, max, count";
1704 	static const char default_latency_sort_order[] = "avg, max, count";
1705 	const struct option kwork_options[] = {
1706 	OPT_INCR('v', "verbose", &verbose,
1707 		 "be more verbose (show symbol address, etc)"),
1708 	OPT_BOOLEAN('D', "dump-raw-trace", &dump_trace,
1709 		    "dump raw trace in ASCII"),
1710 	OPT_STRING('k', "kwork", &kwork.event_list_str, "kwork",
1711 		   "list of kwork to profile (irq, softirq, workqueue, etc)"),
1712 	OPT_BOOLEAN('f', "force", &kwork.force, "don't complain, do it"),
1713 	OPT_END()
1714 	};
1715 	const struct option report_options[] = {
1716 	OPT_STRING('s', "sort", &kwork.sort_order, "key[,key2...]",
1717 		   "sort by key(s): runtime, max, count"),
1718 	OPT_STRING('C', "cpu", &kwork.cpu_list, "cpu",
1719 		   "list of cpus to profile"),
1720 	OPT_STRING('n', "name", &kwork.profile_name, "name",
1721 		   "event name to profile"),
1722 	OPT_STRING(0, "time", &kwork.time_str, "str",
1723 		   "Time span for analysis (start,stop)"),
1724 	OPT_STRING('i', "input", &input_name, "file",
1725 		   "input file name"),
1726 	OPT_BOOLEAN('S', "with-summary", &kwork.summary,
1727 		    "Show summary with statistics"),
1728 #ifdef HAVE_BPF_SKEL
1729 	OPT_BOOLEAN('b', "use-bpf", &kwork.use_bpf,
1730 		    "Use BPF to measure kwork runtime"),
1731 #endif
1732 	OPT_PARENT(kwork_options)
1733 	};
1734 	const struct option latency_options[] = {
1735 	OPT_STRING('s', "sort", &kwork.sort_order, "key[,key2...]",
1736 		   "sort by key(s): avg, max, count"),
1737 	OPT_STRING('C', "cpu", &kwork.cpu_list, "cpu",
1738 		   "list of cpus to profile"),
1739 	OPT_STRING('n', "name", &kwork.profile_name, "name",
1740 		   "event name to profile"),
1741 	OPT_STRING(0, "time", &kwork.time_str, "str",
1742 		   "Time span for analysis (start,stop)"),
1743 	OPT_STRING('i', "input", &input_name, "file",
1744 		   "input file name"),
1745 #ifdef HAVE_BPF_SKEL
1746 	OPT_BOOLEAN('b', "use-bpf", &kwork.use_bpf,
1747 		    "Use BPF to measure kwork latency"),
1748 #endif
1749 	OPT_PARENT(kwork_options)
1750 	};
1751 	const struct option timehist_options[] = {
1752 	OPT_STRING('k', "vmlinux", &symbol_conf.vmlinux_name,
1753 		   "file", "vmlinux pathname"),
1754 	OPT_STRING(0, "kallsyms", &symbol_conf.kallsyms_name,
1755 		   "file", "kallsyms pathname"),
1756 	OPT_BOOLEAN('g', "call-graph", &kwork.show_callchain,
1757 		    "Display call chains if present"),
1758 	OPT_UINTEGER(0, "max-stack", &kwork.max_stack,
1759 		   "Maximum number of functions to display backtrace."),
1760 	OPT_STRING(0, "symfs", &symbol_conf.symfs, "directory",
1761 		    "Look for files with symbols relative to this directory"),
1762 	OPT_STRING(0, "time", &kwork.time_str, "str",
1763 		   "Time span for analysis (start,stop)"),
1764 	OPT_STRING('C', "cpu", &kwork.cpu_list, "cpu",
1765 		   "list of cpus to profile"),
1766 	OPT_STRING('n', "name", &kwork.profile_name, "name",
1767 		   "event name to profile"),
1768 	OPT_STRING('i', "input", &input_name, "file",
1769 		   "input file name"),
1770 	OPT_PARENT(kwork_options)
1771 	};
1772 	const char *kwork_usage[] = {
1773 		NULL,
1774 		NULL
1775 	};
1776 	const char * const report_usage[] = {
1777 		"perf kwork report [<options>]",
1778 		NULL
1779 	};
1780 	const char * const latency_usage[] = {
1781 		"perf kwork latency [<options>]",
1782 		NULL
1783 	};
1784 	const char * const timehist_usage[] = {
1785 		"perf kwork timehist [<options>]",
1786 		NULL
1787 	};
1788 	const char *const kwork_subcommands[] = {
1789 		"record", "report", "latency", "timehist", NULL
1790 	};
1791 
1792 	argc = parse_options_subcommand(argc, argv, kwork_options,
1793 					kwork_subcommands, kwork_usage,
1794 					PARSE_OPT_STOP_AT_NON_OPTION);
1795 	if (!argc)
1796 		usage_with_options(kwork_usage, kwork_options);
1797 
1798 	setup_event_list(&kwork, kwork_options, kwork_usage);
1799 	sort_dimension__add(&kwork, "id", &kwork.cmp_id);
1800 
1801 	if (strlen(argv[0]) > 2 && strstarts("record", argv[0]))
1802 		return perf_kwork__record(&kwork, argc, argv);
1803 	else if (strlen(argv[0]) > 2 && strstarts("report", argv[0])) {
1804 		kwork.sort_order = default_report_sort_order;
1805 		if (argc > 1) {
1806 			argc = parse_options(argc, argv, report_options, report_usage, 0);
1807 			if (argc)
1808 				usage_with_options(report_usage, report_options);
1809 		}
1810 		kwork.report = KWORK_REPORT_RUNTIME;
1811 		setup_sorting(&kwork, report_options, report_usage);
1812 		return perf_kwork__report(&kwork);
1813 	} else if (strlen(argv[0]) > 2 && strstarts("latency", argv[0])) {
1814 		kwork.sort_order = default_latency_sort_order;
1815 		if (argc > 1) {
1816 			argc = parse_options(argc, argv, latency_options, latency_usage, 0);
1817 			if (argc)
1818 				usage_with_options(latency_usage, latency_options);
1819 		}
1820 		kwork.report = KWORK_REPORT_LATENCY;
1821 		setup_sorting(&kwork, latency_options, latency_usage);
1822 		return perf_kwork__report(&kwork);
1823 	} else if (strlen(argv[0]) > 2 && strstarts("timehist", argv[0])) {
1824 		if (argc > 1) {
1825 			argc = parse_options(argc, argv, timehist_options, timehist_usage, 0);
1826 			if (argc)
1827 				usage_with_options(timehist_usage, timehist_options);
1828 		}
1829 		kwork.report = KWORK_REPORT_TIMEHIST;
1830 		return perf_kwork__timehist(&kwork);
1831 	} else
1832 		usage_with_options(kwork_usage, kwork_options);
1833 
1834 	return 0;
1835 }
1836