NMI 跟踪事件

这些事件通常显示在此处

/sys/kernel/tracing/events/nmi

nmi_handler

如果你怀疑 NMI 处理程序占用了大量 CPU 时间,你可能需要使用这个跟踪点。如果内核发现处理程序运行时间过长,它会发出警告

INFO: NMI handler took too long to run: 9.207 msecs

而这个跟踪点将允许你深入分析并获取更多细节。

假设你怀疑 perf_event_nmi_handler() 导致了一些问题,并且你只想专门跟踪该处理程序。你需要找到它的地址

$ grep perf_event_nmi_handler /proc/kallsyms
ffffffff81625600 t perf_event_nmi_handler

再假设你只对该函数确实占用了大量 CPU 时间(比如每次一毫秒)的情况感兴趣。请注意,内核的输出单位是毫秒,但过滤器的输入单位是纳秒!你可以使用 ‘delta_ns’ 进行过滤

cd /sys/kernel/tracing/events/nmi/nmi_handler
echo 'handler==0xffffffff81625600 && delta_ns>1000000' > filter
echo 1 > enable

那么你的输出看起来会是这样

$ cat /sys/kernel/tracing/trace_pipe
<idle>-0     [000] d.h3   505.397558: nmi_handler: perf_event_nmi_handler() delta_ns: 3236765 handled: 1
<idle>-0     [000] d.h3   505.805893: nmi_handler: perf_event_nmi_handler() delta_ns: 3174234 handled: 1
<idle>-0     [000] d.h3   506.158206: nmi_handler: perf_event_nmi_handler() delta_ns: 3084642 handled: 1
<idle>-0     [000] d.h3   506.334346: nmi_handler: perf_event_nmi_handler() delta_ns: 3080351 handled: 1