Adrian Hunter
56b2b649a5
perf intel-pt: Fix "context_switch event has no tid" error
[ Upstream commit 7d537a8d2e76bc4fc71e34545ceaa463ac2cd928 ]
A context_switch event can have no tid because pids can be detached from
a task while the task is still running (in do_exit()). Note this won't
happen with per-task contexts because then tracing stops at
perf_event_exit_task()
If a task with no tid gets preempted, or a dying task gets preempted and
its parent releases it, when it subsequently gets switched back in,
Intel PT will not be able to determine what task is running and prints
an error "context_switch event has no tid". However, it is not really an
error because the task is in kernel space and the decoder can continue
to decode successfully. Fix by changing the error to be only a logged
message, and make allowance for tid == -1.
Example:
Using 5.9-rc4 with Preemptible Kernel (Low-Latency Desktop) e.g.
$ uname -r
5.9.0-rc4
$ grep PREEMPT .config
# CONFIG_PREEMPT_NONE is not set
# CONFIG_PREEMPT_VOLUNTARY is not set
CONFIG_PREEMPT=y
CONFIG_PREEMPT_COUNT=y
CONFIG_PREEMPTION=y
CONFIG_PREEMPT_RCU=y
CONFIG_PREEMPT_NOTIFIERS=y
CONFIG_DRM_I915_PREEMPT_TIMEOUT=640
CONFIG_DEBUG_PREEMPT=y
# CONFIG_PREEMPT_TRACER is not set
# CONFIG_PREEMPTIRQ_DELAY_TEST is not set
Before:
$ cat forkit.c
#include <sys/types.h>
#include <unistd.h>
#include <sys/wait.h>
int main()
{
pid_t child;
int status = 0;
child = fork();
if (child == 0)
return 123;
wait(&status);
return 0;
}
$ gcc -o forkit forkit.c
$ sudo ~/bin/perf record --kcore -a -m,64M -e intel_pt/cyc/k &
[1] 11016
$ taskset 2 ./forkit
$ sudo pkill perf
$ [ perf record: Woken up 1 times to write data ]
[ perf record: Captured and wrote 17.262 MB perf.data ]
[1]+ Terminated sudo ~/bin/perf record --kcore -a -m,64M -e intel_pt/cyc/k
$ sudo ~/bin/perf script --show-task-events --show-switch-events --itrace=iqqe-o -C 1 --ns | grep -C 2 forkit
context_switch event has no tid
taskset 11019 [001] 66663.270045029: 1 instructions:k: ffffffffb1d9f844 strnlen_user+0xb4 ([kernel.kallsyms])
taskset 11019 [001] 66663.270201816: 1 instructions:k: ffffffffb1a83121 unmap_page_range+0x561 ([kernel.kallsyms])
forkit 11019 [001] 66663.270327553: PERF_RECORD_COMM exec: forkit:11019/11019
forkit 11019 [001] 66663.270420028: 1 instructions:k: ffffffffb1db9537 __clear_user+0x27 ([kernel.kallsyms])
forkit 11019 [001] 66663.270648704: 1 instructions:k: ffffffffb18829e6 do_user_addr_fault+0xf6 ([kernel.kallsyms])
forkit 11019 [001] 66663.270833163: 1 instructions:k: ffffffffb230a825 irqentry_exit_to_user_mode+0x15 ([kernel.kallsyms])
forkit 11019 [001] 66663.271092359: 1 instructions:k: ffffffffb1aea3d9 lock_page_memcg+0x9 ([kernel.kallsyms])
forkit 11019 [001] 66663.271207092: PERF_RECORD_FORK(11020:11020):(11019:11019)
forkit 11019 [001] 66663.271234775: PERF_RECORD_SWITCH_CPU_WIDE OUT next pid/tid: 11020/11020
forkit 11020 [001] 66663.271238407: PERF_RECORD_SWITCH_CPU_WIDE IN prev pid/tid: 11019/11019
forkit 11020 [001] 66663.271312066: 1 instructions:k: ffffffffb1a88140 handle_mm_fault+0x10 ([kernel.kallsyms])
forkit 11020 [001] 66663.271476225: PERF_RECORD_EXIT(11020:11020):(11019:11019)
forkit 11020 [001] 66663.271497488: PERF_RECORD_SWITCH_CPU_WIDE OUT preempt next pid/tid: 11019/11019
forkit 11019 [001] 66663.271500523: PERF_RECORD_SWITCH_CPU_WIDE IN prev pid/tid: 11020/11020
forkit 11019 [001] 66663.271517241: 1 instructions:k: ffffffffb24012cd error_entry+0x6d ([kernel.kallsyms])
forkit 11019 [001] 66663.271664080: PERF_RECORD_EXIT(11019:11019):(1386:1386)
After:
$ sudo ~/bin/perf script --show-task-events --show-switch-events --itrace=iqqe-o -C 1 --ns | grep -C 2 forkit
taskset 11019 [001] 66663.270045029: 1 instructions:k: ffffffffb1d9f844 strnlen_user+0xb4 ([kernel.kallsyms])
taskset 11019 [001] 66663.270201816: 1 instructions:k: ffffffffb1a83121 unmap_page_range+0x561 ([kernel.kallsyms])
forkit 11019 [001] 66663.270327553: PERF_RECORD_COMM exec: forkit:11019/11019
forkit 11019 [001] 66663.270420028: 1 instructions:k: ffffffffb1db9537 __clear_user+0x27 ([kernel.kallsyms])
forkit 11019 [001] 66663.270648704: 1 instructions:k: ffffffffb18829e6 do_user_addr_fault+0xf6 ([kernel.kallsyms])
forkit 11019 [001] 66663.270833163: 1 instructions:k: ffffffffb230a825 irqentry_exit_to_user_mode+0x15 ([kernel.kallsyms])
forkit 11019 [001] 66663.271092359: 1 instructions:k: ffffffffb1aea3d9 lock_page_memcg+0x9 ([kernel.kallsyms])
forkit 11019 [001] 66663.271207092: PERF_RECORD_FORK(11020:11020):(11019:11019)
forkit 11019 [001] 66663.271234775: PERF_RECORD_SWITCH_CPU_WIDE OUT next pid/tid: 11020/11020
forkit 11020 [001] 66663.271238407: PERF_RECORD_SWITCH_CPU_WIDE IN prev pid/tid: 11019/11019
forkit 11020 [001] 66663.271312066: 1 instructions:k: ffffffffb1a88140 handle_mm_fault+0x10 ([kernel.kallsyms])
forkit 11020 [001] 66663.271476225: PERF_RECORD_EXIT(11020:11020):(11019:11019)
forkit 11020 [001] 66663.271497488: PERF_RECORD_SWITCH_CPU_WIDE OUT preempt next pid/tid: 11019/11019
forkit 11019 [001] 66663.271500523: PERF_RECORD_SWITCH_CPU_WIDE IN prev pid/tid: 11020/11020
forkit 11019 [001] 66663.271517241: 1 instructions:k: ffffffffb24012cd error_entry+0x6d ([kernel.kallsyms])
forkit 11019 [001] 66663.271664080: PERF_RECORD_EXIT(11019:11019):(1386:1386)
forkit 11019 [001] 66663.271688752: PERF_RECORD_SWITCH_CPU_WIDE OUT next pid/tid: -1/-1
:-1 -1 [001] 66663.271692086: PERF_RECORD_SWITCH_CPU_WIDE IN prev pid/tid: 11019/11019
:-1 -1 [001] 66663.271707466: 1 instructions:k: ffffffffb18eb096 update_load_avg+0x306 ([kernel.kallsyms])
Fixes: 86c2786994 ("perf intel-pt: Add support for PERF_RECORD_SWITCH")
Signed-off-by: Adrian Hunter <adrian.hunter@intel.com>
Cc: Andi Kleen <ak@linux.intel.com>
Cc: Jiri Olsa <jolsa@redhat.com>
Cc: Yu-cheng Yu <yu-cheng.yu@intel.com>
Link: http://lore.kernel.org/lkml/20200909084923.9096-3-adrian.hunter@intel.com
Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
Signed-off-by: Sasha Levin <sashal@kernel.org>
|
2020-10-29 09:05:38 +01:00 |
| .. |
|
arch
|
perf record: Fix module size on s390
|
2019-08-25 10:51:21 +02:00 |
|
bench
|
perf bench numa: Fix cpu0 binding
|
2019-09-06 10:19:33 +02:00 |
|
Documentation
|
perf record/stat: Explicitly call out event modifiers in the documentation
|
2020-09-12 11:47:32 +02:00 |
|
jvmti
|
|
|
|
pmu-events
|
perf jevents: Fix period for Intel fixed counters
|
2019-11-06 12:18:06 +01:00 |
|
python
|
|
|
|
scripts
|
perf script python: Fix export-to-postgresql.py occasional failure
|
2018-10-18 09:13:21 +02:00 |
|
tests
|
perf test: Free formats for perf pmu parse test
|
2020-09-23 08:46:15 +02:00 |
|
trace
|
perf trace: Check if MAP_32BIT is defined (again)
|
2017-01-26 08:24:36 +01:00 |
|
ui
|
perf hists browser: Restore ESC as "Zoom out" of DSO/thread/etc
|
2020-03-11 07:53:08 +01:00 |
|
util
|
perf intel-pt: Fix "context_switch event has no tid" error
|
2020-10-29 09:05:38 +01:00 |
|
.gitignore
|
|
|
|
Build
|
|
|
|
builtin-annotate.c
|
|
|
|
builtin-bench.c
|
|
|
|
builtin-buildid-cache.c
|
|
|
|
builtin-buildid-list.c
|
|
|
|
builtin-config.c
|
|
|
|
builtin-data.c
|
|
|
|
builtin-diff.c
|
perf diff: Fix -o/--order option behavior (again)
|
2017-02-14 15:25:42 -08:00 |
|
builtin-evlist.c
|
|
|
|
builtin-help.c
|
perf help: Remove needless use of strncpy()
|
2019-07-10 09:55:36 +02:00 |
|
builtin-inject.c
|
|
|
|
builtin-kmem.c
|
perf kmem: Fix memory leak in compact_gfp_flags()
|
2019-11-10 11:23:16 +01:00 |
|
builtin-kvm.c
|
|
|
|
builtin-list.c
|
|
|
|
builtin-lock.c
|
|
|
|
builtin-mem.c
|
perf mem: Fix --all-user/--all-kernel options
|
2017-01-26 08:24:35 +01:00 |
|
builtin-probe.c
|
perf probe: Do not show the skipped events
|
2020-06-20 10:24:22 +02:00 |
|
builtin-record.c
|
|
|
|
builtin-report.c
|
perf report: Fix NULL pointer dereference in hists__fprintf_nr_sample_events()
|
2020-06-30 15:38:30 -04:00 |
|
builtin-sched.c
|
|
|
|
builtin-script.c
|
perf tools: Fix snprint warnings for gcc 8
|
2020-07-31 16:44:07 +02:00 |
|
builtin-stat.c
|
perf stat: Reset previous counts on repeat with interval
|
2019-10-17 13:42:17 -07:00 |
|
builtin-timechart.c
|
|
|
|
builtin-top.c
|
perf top: Fix stdio interface input handling with glibc 2.28+
|
2020-10-14 09:48:15 +02:00 |
|
builtin-trace.c
|
perf trace: Add mmap alias for s390
|
2018-04-13 19:48:17 +02:00 |
|
builtin-version.c
|
|
|
|
builtin.h
|
|
|
|
check-headers.sh
|
tools include: Adopt linux/bits.h
|
2019-05-31 06:48:13 -07:00 |
|
command-list.txt
|
|
|
|
CREDITS
|
|
|
|
design.txt
|
|
|
|
Makefile
|
tools: Let O= makes handle a relative path with -C option
|
2020-04-02 17:20:36 +02:00 |
|
Makefile.config
|
perf tools: Fix build with ARCH=x86_64
|
2018-01-23 19:57:07 +01:00 |
|
Makefile.perf
|
perf tools: Move headers check into bash script
|
2018-06-05 10:28:56 +02:00 |
|
MANIFEST
|
tools include: Introduce linux/bug.h, from the kernel sources
|
2018-06-05 10:28:56 +02:00 |
|
perf-archive.sh
|
|
|
|
perf-completion.sh
|
|
|
|
perf-read-vdso.c
|
|
|
|
perf-sys.h
|
|
|
|
perf-with-kcore.sh
|
|
|
|
perf.c
|
|
|
|
perf.h
|
perf tools: Increase MAX_NR_CPUS and MAX_CACHES
|
2019-08-04 09:33:22 +02:00 |