xref: /linux/tools/perf/tests/code-reading.c (revision a23e1966932464e1c5226cb9ac4ce1d5fc10ba22)
1 // SPDX-License-Identifier: GPL-2.0
2 #include <errno.h>
3 #include <linux/kernel.h>
4 #include <linux/types.h>
5 #include <inttypes.h>
6 #include <stdlib.h>
7 #include <unistd.h>
8 #include <stdio.h>
9 #include <string.h>
10 #include <sys/param.h>
11 #include <perf/cpumap.h>
12 #include <perf/evlist.h>
13 #include <perf/mmap.h>
14 
15 #include "debug.h"
16 #include "dso.h"
17 #include "env.h"
18 #include "parse-events.h"
19 #include "evlist.h"
20 #include "evsel.h"
21 #include "thread_map.h"
22 #include "machine.h"
23 #include "map.h"
24 #include "symbol.h"
25 #include "event.h"
26 #include "record.h"
27 #include "util/mmap.h"
28 #include "util/string2.h"
29 #include "util/synthetic-events.h"
30 #include "util/util.h"
31 #include "thread.h"
32 
33 #include "tests.h"
34 
35 #include <linux/ctype.h>
36 
37 #define BUFSZ	1024
38 #define READLEN	128
39 
40 struct state {
41 	u64 done[1024];
42 	size_t done_cnt;
43 };
44 
45 static size_t read_objdump_chunk(const char **line, unsigned char **buf,
46 				 size_t *buf_len)
47 {
48 	size_t bytes_read = 0;
49 	unsigned char *chunk_start = *buf;
50 
51 	/* Read bytes */
52 	while (*buf_len > 0) {
53 		char c1, c2;
54 
55 		/* Get 2 hex digits */
56 		c1 = *(*line)++;
57 		if (!isxdigit(c1))
58 			break;
59 		c2 = *(*line)++;
60 		if (!isxdigit(c2))
61 			break;
62 
63 		/* Store byte and advance buf */
64 		**buf = (hex(c1) << 4) | hex(c2);
65 		(*buf)++;
66 		(*buf_len)--;
67 		bytes_read++;
68 
69 		/* End of chunk? */
70 		if (isspace(**line))
71 			break;
72 	}
73 
74 	/*
75 	 * objdump will display raw insn as LE if code endian
76 	 * is LE and bytes_per_chunk > 1. In that case reverse
77 	 * the chunk we just read.
78 	 *
79 	 * see disassemble_bytes() at binutils/objdump.c for details
80 	 * how objdump chooses display endian)
81 	 */
82 	if (bytes_read > 1 && !host_is_bigendian()) {
83 		unsigned char *chunk_end = chunk_start + bytes_read - 1;
84 		unsigned char tmp;
85 
86 		while (chunk_start < chunk_end) {
87 			tmp = *chunk_start;
88 			*chunk_start = *chunk_end;
89 			*chunk_end = tmp;
90 			chunk_start++;
91 			chunk_end--;
92 		}
93 	}
94 
95 	return bytes_read;
96 }
97 
98 static size_t read_objdump_line(const char *line, unsigned char *buf,
99 				size_t buf_len)
100 {
101 	const char *p;
102 	size_t ret, bytes_read = 0;
103 
104 	/* Skip to a colon */
105 	p = strchr(line, ':');
106 	if (!p)
107 		return 0;
108 	p++;
109 
110 	/* Skip initial spaces */
111 	while (*p) {
112 		if (!isspace(*p))
113 			break;
114 		p++;
115 	}
116 
117 	do {
118 		ret = read_objdump_chunk(&p, &buf, &buf_len);
119 		bytes_read += ret;
120 		p++;
121 	} while (ret > 0);
122 
123 	/* return number of successfully read bytes */
124 	return bytes_read;
125 }
126 
127 static int read_objdump_output(FILE *f, void *buf, size_t *len, u64 start_addr)
128 {
129 	char *line = NULL;
130 	size_t line_len, off_last = 0;
131 	ssize_t ret;
132 	int err = 0;
133 	u64 addr, last_addr = start_addr;
134 
135 	while (off_last < *len) {
136 		size_t off, read_bytes, written_bytes;
137 		unsigned char tmp[BUFSZ];
138 
139 		ret = getline(&line, &line_len, f);
140 		if (feof(f))
141 			break;
142 		if (ret < 0) {
143 			pr_debug("getline failed\n");
144 			err = -1;
145 			break;
146 		}
147 
148 		/* read objdump data into temporary buffer */
149 		read_bytes = read_objdump_line(line, tmp, sizeof(tmp));
150 		if (!read_bytes)
151 			continue;
152 
153 		if (sscanf(line, "%"PRIx64, &addr) != 1)
154 			continue;
155 		if (addr < last_addr) {
156 			pr_debug("addr going backwards, read beyond section?\n");
157 			break;
158 		}
159 		last_addr = addr;
160 
161 		/* copy it from temporary buffer to 'buf' according
162 		 * to address on current objdump line */
163 		off = addr - start_addr;
164 		if (off >= *len)
165 			break;
166 		written_bytes = MIN(read_bytes, *len - off);
167 		memcpy(buf + off, tmp, written_bytes);
168 		off_last = off + written_bytes;
169 	}
170 
171 	/* len returns number of bytes that could not be read */
172 	*len -= off_last;
173 
174 	free(line);
175 
176 	return err;
177 }
178 
179 static int read_via_objdump(const char *filename, u64 addr, void *buf,
180 			    size_t len)
181 {
182 	char cmd[PATH_MAX * 2];
183 	const char *fmt;
184 	FILE *f;
185 	int ret;
186 
187 	fmt = "%s -z -d --start-address=0x%"PRIx64" --stop-address=0x%"PRIx64" %s";
188 	ret = snprintf(cmd, sizeof(cmd), fmt, test_objdump_path, addr, addr + len,
189 		       filename);
190 	if (ret <= 0 || (size_t)ret >= sizeof(cmd))
191 		return -1;
192 
193 	pr_debug("Objdump command is: %s\n", cmd);
194 
195 	/* Ignore objdump errors */
196 	strcat(cmd, " 2>/dev/null");
197 
198 	f = popen(cmd, "r");
199 	if (!f) {
200 		pr_debug("popen failed\n");
201 		return -1;
202 	}
203 
204 	ret = read_objdump_output(f, buf, &len, addr);
205 	if (len) {
206 		pr_debug("objdump read too few bytes: %zd\n", len);
207 		if (!ret)
208 			ret = len;
209 	}
210 
211 	pclose(f);
212 
213 	return ret;
214 }
215 
216 static void dump_buf(unsigned char *buf, size_t len)
217 {
218 	size_t i;
219 
220 	for (i = 0; i < len; i++) {
221 		pr_debug("0x%02x ", buf[i]);
222 		if (i % 16 == 15)
223 			pr_debug("\n");
224 	}
225 	pr_debug("\n");
226 }
227 
228 static int read_object_code(u64 addr, size_t len, u8 cpumode,
229 			    struct thread *thread, struct state *state)
230 {
231 	struct addr_location al;
232 	unsigned char buf1[BUFSZ] = {0};
233 	unsigned char buf2[BUFSZ] = {0};
234 	size_t ret_len;
235 	u64 objdump_addr;
236 	const char *objdump_name;
237 	char decomp_name[KMOD_DECOMP_LEN];
238 	bool decomp = false;
239 	int ret, err = 0;
240 	struct dso *dso;
241 
242 	pr_debug("Reading object code for memory address: %#"PRIx64"\n", addr);
243 
244 	addr_location__init(&al);
245 	if (!thread__find_map(thread, cpumode, addr, &al) || !map__dso(al.map)) {
246 		if (cpumode == PERF_RECORD_MISC_HYPERVISOR) {
247 			pr_debug("Hypervisor address can not be resolved - skipping\n");
248 			goto out;
249 		}
250 
251 		pr_debug("thread__find_map failed\n");
252 		err = -1;
253 		goto out;
254 	}
255 	dso = map__dso(al.map);
256 	pr_debug("File is: %s\n", dso->long_name);
257 
258 	if (dso->symtab_type == DSO_BINARY_TYPE__KALLSYMS && !dso__is_kcore(dso)) {
259 		pr_debug("Unexpected kernel address - skipping\n");
260 		goto out;
261 	}
262 
263 	pr_debug("On file address is: %#"PRIx64"\n", al.addr);
264 
265 	if (len > BUFSZ)
266 		len = BUFSZ;
267 
268 	/* Do not go off the map */
269 	if (addr + len > map__end(al.map))
270 		len = map__end(al.map) - addr;
271 
272 	/*
273 	 * Some architectures (ex: powerpc) have stubs (trampolines) in kernel
274 	 * modules to manage long jumps. Check if the ip offset falls in stubs
275 	 * sections for kernel modules. And skip module address after text end
276 	 */
277 	if (dso->is_kmod && al.addr > dso->text_end) {
278 		pr_debug("skipping the module address %#"PRIx64" after text end\n", al.addr);
279 		goto out;
280 	}
281 
282 	/* Read the object code using perf */
283 	ret_len = dso__data_read_offset(dso, maps__machine(thread__maps(thread)),
284 					al.addr, buf1, len);
285 	if (ret_len != len) {
286 		pr_debug("dso__data_read_offset failed\n");
287 		err = -1;
288 		goto out;
289 	}
290 
291 	/*
292 	 * Converting addresses for use by objdump requires more information.
293 	 * map__load() does that.  See map__rip_2objdump() for details.
294 	 */
295 	if (map__load(al.map)) {
296 		err = -1;
297 		goto out;
298 	}
299 
300 	/* objdump struggles with kcore - try each map only once */
301 	if (dso__is_kcore(dso)) {
302 		size_t d;
303 
304 		for (d = 0; d < state->done_cnt; d++) {
305 			if (state->done[d] == map__start(al.map)) {
306 				pr_debug("kcore map tested already");
307 				pr_debug(" - skipping\n");
308 				goto out;
309 			}
310 		}
311 		if (state->done_cnt >= ARRAY_SIZE(state->done)) {
312 			pr_debug("Too many kcore maps - skipping\n");
313 			goto out;
314 		}
315 		state->done[state->done_cnt++] = map__start(al.map);
316 	}
317 
318 	objdump_name = dso->long_name;
319 	if (dso__needs_decompress(dso)) {
320 		if (dso__decompress_kmodule_path(dso, objdump_name,
321 						 decomp_name,
322 						 sizeof(decomp_name)) < 0) {
323 			pr_debug("decompression failed\n");
324 			err = -1;
325 			goto out;
326 		}
327 
328 		decomp = true;
329 		objdump_name = decomp_name;
330 	}
331 
332 	/* Read the object code using objdump */
333 	objdump_addr = map__rip_2objdump(al.map, al.addr);
334 	ret = read_via_objdump(objdump_name, objdump_addr, buf2, len);
335 
336 	if (decomp)
337 		unlink(objdump_name);
338 
339 	if (ret > 0) {
340 		/*
341 		 * The kernel maps are inaccurate - assume objdump is right in
342 		 * that case.
343 		 */
344 		if (cpumode == PERF_RECORD_MISC_KERNEL ||
345 		    cpumode == PERF_RECORD_MISC_GUEST_KERNEL) {
346 			len -= ret;
347 			if (len) {
348 				pr_debug("Reducing len to %zu\n", len);
349 			} else if (dso__is_kcore(dso)) {
350 				/*
351 				 * objdump cannot handle very large segments
352 				 * that may be found in kcore.
353 				 */
354 				pr_debug("objdump failed for kcore");
355 				pr_debug(" - skipping\n");
356 			} else {
357 				err = -1;
358 			}
359 			goto out;
360 		}
361 	}
362 	if (ret < 0) {
363 		pr_debug("read_via_objdump failed\n");
364 		err = -1;
365 		goto out;
366 	}
367 
368 	/* The results should be identical */
369 	if (memcmp(buf1, buf2, len)) {
370 		pr_debug("Bytes read differ from those read by objdump\n");
371 		pr_debug("buf1 (dso):\n");
372 		dump_buf(buf1, len);
373 		pr_debug("buf2 (objdump):\n");
374 		dump_buf(buf2, len);
375 		err = -1;
376 		goto out;
377 	}
378 	pr_debug("Bytes read match those read by objdump\n");
379 out:
380 	addr_location__exit(&al);
381 	return err;
382 }
383 
384 static int process_sample_event(struct machine *machine,
385 				struct evlist *evlist,
386 				union perf_event *event, struct state *state)
387 {
388 	struct perf_sample sample;
389 	struct thread *thread;
390 	int ret;
391 
392 	if (evlist__parse_sample(evlist, event, &sample)) {
393 		pr_debug("evlist__parse_sample failed\n");
394 		return -1;
395 	}
396 
397 	thread = machine__findnew_thread(machine, sample.pid, sample.tid);
398 	if (!thread) {
399 		pr_debug("machine__findnew_thread failed\n");
400 		return -1;
401 	}
402 
403 	ret = read_object_code(sample.ip, READLEN, sample.cpumode, thread, state);
404 	thread__put(thread);
405 	return ret;
406 }
407 
408 static int process_event(struct machine *machine, struct evlist *evlist,
409 			 union perf_event *event, struct state *state)
410 {
411 	if (event->header.type == PERF_RECORD_SAMPLE)
412 		return process_sample_event(machine, evlist, event, state);
413 
414 	if (event->header.type == PERF_RECORD_THROTTLE ||
415 	    event->header.type == PERF_RECORD_UNTHROTTLE)
416 		return 0;
417 
418 	if (event->header.type < PERF_RECORD_MAX) {
419 		int ret;
420 
421 		ret = machine__process_event(machine, event, NULL);
422 		if (ret < 0)
423 			pr_debug("machine__process_event failed, event type %u\n",
424 				 event->header.type);
425 		return ret;
426 	}
427 
428 	return 0;
429 }
430 
431 static int process_events(struct machine *machine, struct evlist *evlist,
432 			  struct state *state)
433 {
434 	union perf_event *event;
435 	struct mmap *md;
436 	int i, ret;
437 
438 	for (i = 0; i < evlist->core.nr_mmaps; i++) {
439 		md = &evlist->mmap[i];
440 		if (perf_mmap__read_init(&md->core) < 0)
441 			continue;
442 
443 		while ((event = perf_mmap__read_event(&md->core)) != NULL) {
444 			ret = process_event(machine, evlist, event, state);
445 			perf_mmap__consume(&md->core);
446 			if (ret < 0)
447 				return ret;
448 		}
449 		perf_mmap__read_done(&md->core);
450 	}
451 	return 0;
452 }
453 
454 static int comp(const void *a, const void *b)
455 {
456 	return *(int *)a - *(int *)b;
457 }
458 
459 static void do_sort_something(void)
460 {
461 	int buf[40960], i;
462 
463 	for (i = 0; i < (int)ARRAY_SIZE(buf); i++)
464 		buf[i] = ARRAY_SIZE(buf) - i - 1;
465 
466 	qsort(buf, ARRAY_SIZE(buf), sizeof(int), comp);
467 
468 	for (i = 0; i < (int)ARRAY_SIZE(buf); i++) {
469 		if (buf[i] != i) {
470 			pr_debug("qsort failed\n");
471 			break;
472 		}
473 	}
474 }
475 
476 static void sort_something(void)
477 {
478 	int i;
479 
480 	for (i = 0; i < 10; i++)
481 		do_sort_something();
482 }
483 
484 static void syscall_something(void)
485 {
486 	int pipefd[2];
487 	int i;
488 
489 	for (i = 0; i < 1000; i++) {
490 		if (pipe(pipefd) < 0) {
491 			pr_debug("pipe failed\n");
492 			break;
493 		}
494 		close(pipefd[1]);
495 		close(pipefd[0]);
496 	}
497 }
498 
499 static void fs_something(void)
500 {
501 	const char *test_file_name = "temp-perf-code-reading-test-file--";
502 	FILE *f;
503 	int i;
504 
505 	for (i = 0; i < 1000; i++) {
506 		f = fopen(test_file_name, "w+");
507 		if (f) {
508 			fclose(f);
509 			unlink(test_file_name);
510 		}
511 	}
512 }
513 
514 static void do_something(void)
515 {
516 	fs_something();
517 
518 	sort_something();
519 
520 	syscall_something();
521 }
522 
523 enum {
524 	TEST_CODE_READING_OK,
525 	TEST_CODE_READING_NO_VMLINUX,
526 	TEST_CODE_READING_NO_KCORE,
527 	TEST_CODE_READING_NO_ACCESS,
528 	TEST_CODE_READING_NO_KERNEL_OBJ,
529 };
530 
531 static int do_test_code_reading(bool try_kcore)
532 {
533 	struct machine *machine;
534 	struct thread *thread;
535 	struct record_opts opts = {
536 		.mmap_pages	     = UINT_MAX,
537 		.user_freq	     = UINT_MAX,
538 		.user_interval	     = ULLONG_MAX,
539 		.freq		     = 500,
540 		.target		     = {
541 			.uses_mmap   = true,
542 		},
543 	};
544 	struct state state = {
545 		.done_cnt = 0,
546 	};
547 	struct perf_thread_map *threads = NULL;
548 	struct perf_cpu_map *cpus = NULL;
549 	struct evlist *evlist = NULL;
550 	struct evsel *evsel = NULL;
551 	int err = -1, ret;
552 	pid_t pid;
553 	struct map *map;
554 	bool have_vmlinux, have_kcore;
555 	struct dso *dso;
556 	const char *events[] = { "cycles", "cycles:u", "cpu-clock", "cpu-clock:u", NULL };
557 	int evidx = 0;
558 
559 	pid = getpid();
560 
561 	machine = machine__new_host();
562 	machine->env = &perf_env;
563 
564 	ret = machine__create_kernel_maps(machine);
565 	if (ret < 0) {
566 		pr_debug("machine__create_kernel_maps failed\n");
567 		goto out_err;
568 	}
569 
570 	/* Force the use of kallsyms instead of vmlinux to try kcore */
571 	if (try_kcore)
572 		symbol_conf.kallsyms_name = "/proc/kallsyms";
573 
574 	/* Load kernel map */
575 	map = machine__kernel_map(machine);
576 	ret = map__load(map);
577 	if (ret < 0) {
578 		pr_debug("map__load failed\n");
579 		goto out_err;
580 	}
581 	dso = map__dso(map);
582 	have_vmlinux = dso__is_vmlinux(dso);
583 	have_kcore = dso__is_kcore(dso);
584 
585 	/* 2nd time through we just try kcore */
586 	if (try_kcore && !have_kcore)
587 		return TEST_CODE_READING_NO_KCORE;
588 
589 	/* No point getting kernel events if there is no kernel object */
590 	if (!have_vmlinux && !have_kcore)
591 		evidx++;
592 
593 	threads = thread_map__new_by_tid(pid);
594 	if (!threads) {
595 		pr_debug("thread_map__new_by_tid failed\n");
596 		goto out_err;
597 	}
598 
599 	ret = perf_event__synthesize_thread_map(NULL, threads,
600 						perf_event__process, machine,
601 						true, false);
602 	if (ret < 0) {
603 		pr_debug("perf_event__synthesize_thread_map failed\n");
604 		goto out_err;
605 	}
606 
607 	thread = machine__findnew_thread(machine, pid, pid);
608 	if (!thread) {
609 		pr_debug("machine__findnew_thread failed\n");
610 		goto out_put;
611 	}
612 
613 	cpus = perf_cpu_map__new_online_cpus();
614 	if (!cpus) {
615 		pr_debug("perf_cpu_map__new failed\n");
616 		goto out_put;
617 	}
618 
619 	while (events[evidx]) {
620 		const char *str;
621 
622 		evlist = evlist__new();
623 		if (!evlist) {
624 			pr_debug("evlist__new failed\n");
625 			goto out_put;
626 		}
627 
628 		perf_evlist__set_maps(&evlist->core, cpus, threads);
629 
630 		str = events[evidx];
631 		pr_debug("Parsing event '%s'\n", str);
632 		ret = parse_event(evlist, str);
633 		if (ret < 0) {
634 			pr_debug("parse_events failed\n");
635 			goto out_put;
636 		}
637 
638 		evlist__config(evlist, &opts, NULL);
639 
640 		evsel = evlist__first(evlist);
641 
642 		evsel->core.attr.comm = 1;
643 		evsel->core.attr.disabled = 1;
644 		evsel->core.attr.enable_on_exec = 0;
645 
646 		ret = evlist__open(evlist);
647 		if (ret < 0) {
648 			evidx++;
649 
650 			if (events[evidx] == NULL && verbose > 0) {
651 				char errbuf[512];
652 				evlist__strerror_open(evlist, errno, errbuf, sizeof(errbuf));
653 				pr_debug("perf_evlist__open() failed!\n%s\n", errbuf);
654 			}
655 
656 			/*
657 			 * Both cpus and threads are now owned by evlist
658 			 * and will be freed by following perf_evlist__set_maps
659 			 * call. Getting reference to keep them alive.
660 			 */
661 			perf_cpu_map__get(cpus);
662 			perf_thread_map__get(threads);
663 			perf_evlist__set_maps(&evlist->core, NULL, NULL);
664 			evlist__delete(evlist);
665 			evlist = NULL;
666 			continue;
667 		}
668 		break;
669 	}
670 
671 	if (events[evidx] == NULL)
672 		goto out_put;
673 
674 	ret = evlist__mmap(evlist, UINT_MAX);
675 	if (ret < 0) {
676 		pr_debug("evlist__mmap failed\n");
677 		goto out_put;
678 	}
679 
680 	evlist__enable(evlist);
681 
682 	do_something();
683 
684 	evlist__disable(evlist);
685 
686 	ret = process_events(machine, evlist, &state);
687 	if (ret < 0)
688 		goto out_put;
689 
690 	if (!have_vmlinux && !have_kcore && !try_kcore)
691 		err = TEST_CODE_READING_NO_KERNEL_OBJ;
692 	else if (!have_vmlinux && !try_kcore)
693 		err = TEST_CODE_READING_NO_VMLINUX;
694 	else if (strstr(events[evidx], ":u"))
695 		err = TEST_CODE_READING_NO_ACCESS;
696 	else
697 		err = TEST_CODE_READING_OK;
698 out_put:
699 	thread__put(thread);
700 out_err:
701 	evlist__delete(evlist);
702 	perf_cpu_map__put(cpus);
703 	perf_thread_map__put(threads);
704 	machine__delete(machine);
705 
706 	return err;
707 }
708 
709 static int test__code_reading(struct test_suite *test __maybe_unused, int subtest __maybe_unused)
710 {
711 	int ret;
712 
713 	ret = do_test_code_reading(false);
714 	if (!ret)
715 		ret = do_test_code_reading(true);
716 
717 	switch (ret) {
718 	case TEST_CODE_READING_OK:
719 		return 0;
720 	case TEST_CODE_READING_NO_VMLINUX:
721 		pr_debug("no vmlinux\n");
722 		return 0;
723 	case TEST_CODE_READING_NO_KCORE:
724 		pr_debug("no kcore\n");
725 		return 0;
726 	case TEST_CODE_READING_NO_ACCESS:
727 		pr_debug("no access\n");
728 		return 0;
729 	case TEST_CODE_READING_NO_KERNEL_OBJ:
730 		pr_debug("no kernel obj\n");
731 		return 0;
732 	default:
733 		return -1;
734 	};
735 }
736 
737 DEFINE_SUITE("Object code reading", code_reading);
738