2018-02-17 13:39:40 +08:00
|
|
|
=========================================
|
|
|
|
Uprobe-tracer: Uprobe-based Event Tracing
|
|
|
|
=========================================
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
:Author: Srikar Dronamraju
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
Overview
|
|
|
|
--------
|
|
|
|
Uprobe based trace events are similar to kprobe based trace events.
|
2017-02-16 14:00:50 +08:00
|
|
|
To enable this feature, build your kernel with CONFIG_UPROBE_EVENTS=y.
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
Similar to the kprobe-event tracer, this doesn't need to be activated via
|
|
|
|
current_tracer. Instead of that, add probe points via
|
|
|
|
/sys/kernel/debug/tracing/uprobe_events, and enable it via
|
2018-07-16 19:05:57 +08:00
|
|
|
/sys/kernel/debug/tracing/events/uprobes/<EVENT>/enable.
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
However unlike kprobe-event tracer, the uprobe event interface expects the
|
2013-04-04 00:00:39 +08:00
|
|
|
user to calculate the offset of the probepoint in the object.
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2018-11-05 17:03:04 +08:00
|
|
|
You can also use /sys/kernel/debug/tracing/dynamic_events instead of
|
|
|
|
uprobe_events. That interface will provide unified access to other
|
|
|
|
dynamic events too.
|
|
|
|
|
2012-04-11 18:30:43 +08:00
|
|
|
Synopsis of uprobe_tracer
|
|
|
|
-------------------------
|
2018-02-17 13:39:40 +08:00
|
|
|
::
|
|
|
|
|
2013-07-03 15:44:46 +08:00
|
|
|
p[:[GRP/]EVENT] PATH:OFFSET [FETCHARGS] : Set a uprobe
|
|
|
|
r[:[GRP/]EVENT] PATH:OFFSET [FETCHARGS] : Set a return uprobe (uretprobe)
|
2020-09-10 16:55:56 +08:00
|
|
|
p[:[GRP/]EVENT] PATH:OFFSET%return [FETCHARGS] : Set a return uprobe (uretprobe)
|
2013-07-03 15:44:46 +08:00
|
|
|
-:[GRP/]EVENT : Clear uprobe or uretprobe event
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
GRP : Group name. If omitted, "uprobes" is the default value.
|
|
|
|
EVENT : Event name. If omitted, the event name is generated based
|
2013-07-03 15:44:46 +08:00
|
|
|
on PATH+OFFSET.
|
2013-04-04 00:00:39 +08:00
|
|
|
PATH : Path to an executable or a library.
|
2013-07-03 15:44:46 +08:00
|
|
|
OFFSET : Offset where the probe is inserted.
|
2020-09-10 16:55:56 +08:00
|
|
|
OFFSET%return : Offset where the return probe is inserted.
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
FETCHARGS : Arguments. Each probe can have up to 128 args.
|
|
|
|
%REG : Fetch register REG
|
2013-07-03 17:34:23 +08:00
|
|
|
@ADDR : Fetch memory at ADDR (ADDR should be in userspace)
|
2013-11-25 12:42:47 +08:00
|
|
|
@+OFFSET : Fetch memory at OFFSET (OFFSET from same file as PATH)
|
2013-07-03 17:34:23 +08:00
|
|
|
$stackN : Fetch Nth entry of stack (N >= 0)
|
|
|
|
$stack : Fetch stack address.
|
2019-05-15 13:38:42 +08:00
|
|
|
$retval : Fetch return value.(\*1)
|
2016-06-09 09:38:02 +08:00
|
|
|
$comm : Fetch current task comm.
|
2019-05-15 13:38:42 +08:00
|
|
|
+|-[u]OFFS(FETCHARG) : Fetch memory at FETCHARG +|- OFFS address.(\*2)(\*3)
|
2019-06-19 23:08:27 +08:00
|
|
|
\IMM : Store an immediate value to the argument.
|
2013-07-03 17:34:23 +08:00
|
|
|
NAME=FETCHARG : Set NAME as the argument name of FETCHARG.
|
|
|
|
FETCHARG:TYPE : Set TYPE as the type of FETCHARG. Currently, basic types
|
2016-08-18 16:57:50 +08:00
|
|
|
(u8/u16/u32/u64/s8/s16/s32/s64), hexadecimal types
|
|
|
|
(x8/x16/x32/x64), "string" and bitfield are supported.
|
2013-07-03 17:34:23 +08:00
|
|
|
|
2019-05-15 13:38:42 +08:00
|
|
|
(\*1) only for return probe.
|
|
|
|
(\*2) this is useful for fetching a field of data structures.
|
|
|
|
(\*3) Unlike kprobe event, "u" prefix will just be ignored, becuse uprobe
|
|
|
|
events can access only user-space memory.
|
2013-07-03 17:34:23 +08:00
|
|
|
|
|
|
|
Types
|
|
|
|
-----
|
|
|
|
Several types are supported for fetch-args. Uprobe tracer will access memory
|
|
|
|
by given type. Prefix 's' and 'u' means those types are signed and unsigned
|
2016-08-18 16:59:21 +08:00
|
|
|
respectively. 'x' prefix implies it is unsigned. Traced arguments are shown
|
|
|
|
in decimal ('s' and 'u') or hexadecimal ('x'). Without type casting, 'x32'
|
|
|
|
or 'x64' is used depends on the architecture (e.g. x86-32 uses x32, and
|
|
|
|
x86-64 uses x64).
|
2013-07-03 17:34:23 +08:00
|
|
|
String type is a special type, which fetches a "null-terminated" string from
|
|
|
|
user space.
|
|
|
|
Bitfield is another special type, which takes 3 parameters, bit-width, bit-
|
2018-02-17 13:39:40 +08:00
|
|
|
offset, and container-size (usually 32). The syntax is::
|
2013-07-03 17:34:23 +08:00
|
|
|
|
|
|
|
b<bit-width>@<bit-offset>/<container-size>
|
|
|
|
|
2016-06-09 09:38:02 +08:00
|
|
|
For $comm, the default type is "string"; any other type is invalid.
|
|
|
|
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
Event Profiling
|
|
|
|
---------------
|
2019-02-19 14:19:28 +08:00
|
|
|
You can check the total number of probe hits per event via
|
|
|
|
/sys/kernel/debug/tracing/uprobe_profile. The first column is the filename,
|
|
|
|
the second is the event name, the third is the number of probe hits.
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
Usage examples
|
|
|
|
--------------
|
2013-04-04 00:00:39 +08:00
|
|
|
* Add a probe as a new uprobe event, write a new definition to uprobe_events
|
2018-02-17 13:39:40 +08:00
|
|
|
as below (sets a uprobe at an offset of 0x4245c0 in the executable /bin/bash)::
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2016-10-06 15:52:12 +08:00
|
|
|
echo 'p /bin/bash:0x4245c0' > /sys/kernel/debug/tracing/uprobe_events
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
* Add a probe as a new uretprobe event::
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2016-10-06 15:52:12 +08:00
|
|
|
echo 'r /bin/bash:0x4245c0' > /sys/kernel/debug/tracing/uprobe_events
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
* Unset registered event::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2016-10-06 15:52:12 +08:00
|
|
|
echo '-:p_bash_0x4245c0' >> /sys/kernel/debug/tracing/uprobe_events
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
* Print out the events that are registered::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
cat /sys/kernel/debug/tracing/uprobe_events
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
* Clear all events::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
echo > /sys/kernel/debug/tracing/uprobe_events
|
|
|
|
|
|
|
|
Following example shows how to dump the instruction pointer and %ax register
|
2018-02-17 13:39:40 +08:00
|
|
|
at the probed text address. Probe zfree function in /bin/zsh::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
# cd /sys/kernel/debug/tracing/
|
2013-04-04 00:00:39 +08:00
|
|
|
# cat /proc/`pgrep zsh`/maps | grep /bin/zsh | grep r-xp
|
2012-04-11 18:30:43 +08:00
|
|
|
00400000-0048a000 r-xp 00000000 08:03 130904 /bin/zsh
|
|
|
|
# objdump -T /bin/zsh | grep -w zfree
|
|
|
|
0000000000446420 g DF .text 0000000000000012 Base zfree
|
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
0x46420 is the offset of zfree in object /bin/zsh that is loaded at
|
|
|
|
0x00400000. Hence the command to uprobe would be::
|
2013-04-04 00:00:39 +08:00
|
|
|
|
|
|
|
# echo 'p:zfree_entry /bin/zsh:0x46420 %ip %ax' > uprobe_events
|
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
And the same for the uretprobe would be::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
# echo 'r:zfree_exit /bin/zsh:0x46420 %ip %ax' >> uprobe_events
|
2012-04-11 18:30:43 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
.. note:: User has to explicitly calculate the offset of the probe-point
|
|
|
|
in the object.
|
|
|
|
|
|
|
|
We can see the events that are registered by looking at the uprobe_events file.
|
|
|
|
::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
# cat uprobe_events
|
2013-04-04 00:00:39 +08:00
|
|
|
p:uprobes/zfree_entry /bin/zsh:0x00046420 arg1=%ip arg2=%ax
|
|
|
|
r:uprobes/zfree_exit /bin/zsh:0x00046420 arg1=%ip arg2=%ax
|
2012-05-08 19:11:26 +08:00
|
|
|
|
2018-02-17 13:39:40 +08:00
|
|
|
Format of events can be seen by viewing the file events/uprobes/zfree_entry/format.
|
|
|
|
::
|
2012-05-08 19:11:26 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
# cat events/uprobes/zfree_entry/format
|
|
|
|
name: zfree_entry
|
2012-05-08 19:11:26 +08:00
|
|
|
ID: 922
|
|
|
|
format:
|
2013-04-04 00:00:39 +08:00
|
|
|
field:unsigned short common_type; offset:0; size:2; signed:0;
|
|
|
|
field:unsigned char common_flags; offset:2; size:1; signed:0;
|
|
|
|
field:unsigned char common_preempt_count; offset:3; size:1; signed:0;
|
|
|
|
field:int common_pid; offset:4; size:4; signed:1;
|
|
|
|
field:int common_padding; offset:8; size:4; signed:1;
|
2012-05-08 19:11:26 +08:00
|
|
|
|
2013-04-04 00:00:39 +08:00
|
|
|
field:unsigned long __probe_ip; offset:12; size:4; signed:0;
|
|
|
|
field:u32 arg1; offset:16; size:4; signed:0;
|
|
|
|
field:u32 arg2; offset:20; size:4; signed:0;
|
2012-05-08 19:11:26 +08:00
|
|
|
|
|
|
|
print fmt: "(%lx) arg1=%lx arg2=%lx", REC->__probe_ip, REC->arg1, REC->arg2
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
Right after definition, each event is disabled by default. For tracing these
|
2018-02-17 13:39:40 +08:00
|
|
|
events, you need to enable it by::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
# echo 1 > events/uprobes/enable
|
|
|
|
|
2019-05-09 18:31:16 +08:00
|
|
|
Lets start tracing, sleep for some time and stop tracing.
|
2018-02-17 13:39:40 +08:00
|
|
|
::
|
2013-04-04 00:00:39 +08:00
|
|
|
|
2019-05-09 18:31:16 +08:00
|
|
|
# echo 1 > tracing_on
|
2012-04-11 18:30:43 +08:00
|
|
|
# sleep 20
|
2019-05-09 18:31:16 +08:00
|
|
|
# echo 0 > tracing_on
|
|
|
|
|
|
|
|
Also, you can disable the event by::
|
|
|
|
|
2012-04-11 18:30:43 +08:00
|
|
|
# echo 0 > events/uprobes/enable
|
|
|
|
|
|
|
|
And you can see the traced information via /sys/kernel/debug/tracing/trace.
|
2018-02-17 13:39:40 +08:00
|
|
|
::
|
2012-04-11 18:30:43 +08:00
|
|
|
|
|
|
|
# cat trace
|
|
|
|
# tracer: nop
|
|
|
|
#
|
|
|
|
# TASK-PID CPU# TIMESTAMP FUNCTION
|
|
|
|
# | | | | |
|
2013-04-04 00:00:39 +08:00
|
|
|
zsh-24842 [006] 258544.995456: zfree_entry: (0x446420) arg1=446420 arg2=79
|
|
|
|
zsh-24842 [007] 258545.000270: zfree_exit: (0x446540 <- 0x446420) arg1=446540 arg2=0
|
|
|
|
zsh-24842 [002] 258545.043929: zfree_entry: (0x446420) arg1=446420 arg2=79
|
|
|
|
zsh-24842 [004] 258547.046129: zfree_exit: (0x446540 <- 0x446420) arg1=446540 arg2=0
|
|
|
|
|
|
|
|
Output shows us uprobe was triggered for a pid 24842 with ip being 0x446420
|
|
|
|
and contents of ax register being 79. And uretprobe was triggered with ip at
|
|
|
|
0x446540 with counterpart function entry at 0x446420.
|