This is the mail archive of the
systemtap@sourceware.org
mailing list for the systemtap project.
Re: [Fwd: Re: Regarding systemtap support for AArch64]
- From: Sandeepa Prabhu <sandeepa dot prabhu at linaro dot org>
- To: William Cohen <wcohen at redhat dot com>
- Cc: Petr Machata <pmachata at redhat dot com>, Mark Wielaard <mjw at redhat dot com>, Masami Hiramatsu <masami dot hiramatsu dot pt at hitachi dot com>, systemtap at sourceware dot org, Deepak Saxena <dsaxena at linaro dot org>, Krishna Dani <krishna dot mohan at linaro dot org>, Jakub Pavelek <jakub dot pavelek at linaro dot org>, Mark Salter <msalter at redhat dot com>
- Date: Wed, 6 Nov 2013 08:14:05 +0530
- Subject: Re: [Fwd: Re: Regarding systemtap support for AArch64]
- Authentication-results: sourceware.org; auth=none
- References: <1383340682 dot 3850 dot 864 dot camel at bordewijk dot wildebeest dot org> <m2habsnq4w dot fsf at redhat dot com> <5277FBF2 dot 2080108 at redhat dot com> <m2wqknmumc dot fsf at redhat dot com> <52791818 dot 9070809 at redhat dot com>
> These examples systemtap might not be the best. It is just printing information for the first vfs.read or vfs.read.return encountered, so the precise values are not known for the run of these examples. These are more sanity checks. Do the values here make sense? The $return value doesn't; it should be either a positive number or some small negative number. The number printed for $return matches with what is in x0 of pt_reg, so getting a value from what appears to be the right place. However, that value in x0 looks questionable.
>
> I wonder if there might be some issue with the patches implementing the arm64 kprobes support and that the registers are not be saved properly.
Hi Will,
Just I am back from Linaro connect week, and did not get enough chance
to run systemtap with my patchset yet. but, quickly tried sample
ret-probe on some functions like vfs_read, vfs_write and do_fork,
sys_sync etc to see the return values, find the attached the log
files. sys_sync return value is zero and looks proper, but not sure
of vfs_read/write or do_fork that return ssize_t or a positive number,
I suppose register context is restored properly since all the api work
normally after restoring, need to check the copy of regs being passed
to the pre/post handlers is correct or not. I will check my code and
get back.
Thanks,
Sandeepa
>
> -Will
>
Initializing cgroup subsys cpu
Linux version 3.12.0-rc4+ (sandeepa@linaro-workstation) (gcc version 4.7.3 20130328 (prerelease) (crosstool-NG linaro-1.13.1-4.7-2013.04-20130415 - Linaro GCC 2013.04) ) #2 SMP Thu Oct 17 15:39:11 IST 2013
CPU: AArch64 Processor [410fd0f0] revision 0
Machine: RTSM_VE_AEMv8A
bootconsole [earlycon0] enabled
PERCPU: Embedded 10 pages/cpu @ffffffc87ffa8000 s11904 r8192 d20864 u40960
Built 1 zonelists in Zone order, mobility grouping on. Total pages: 1034240
Kernel command line: console=ttyAMA0 root=/dev/mmcblk0p2 earlyprintk=pl011,0x1c090000 consolelog=9 rw
PID hash table entries: 4096 (order: 3, 32768 bytes)
Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
software IO TLB [mem 0x8f8000000-0x8fc000000] (64MB) mapped at [ffffffc878000000-ffffffc87bffffff]
Memory: 4058248K/4194304K available (3331K kernel code, 203K rwdata, 1168K rodata, 175K init, 159K bss, 136056K reserved)
Virtual kernel memory layout:
vmalloc : 0xffffff8000000000 - 0xffffffbbffff0000 (245759 MB)
vmemmap : 0xffffffbc01c00000 - 0xffffffbc1f800000 ( 476 MB)
modules : 0xffffffbffc000000 - 0xffffffc000000000 ( 64 MB)
memory : 0xffffffc000000000 - 0xffffffc880000000 ( 34816 MB)
.init : 0xffffffc0004e6000 - 0xffffffc000511e80 ( 176 kB)
.text : 0xffffffc000080000 - 0xffffffc0004e5cf4 ( 4504 kB)
.data : 0xffffffc000514000 - 0xffffffc000546d80 ( 204 kB)
SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Hierarchical RCU implementation.
RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
NR_IRQS:64 nr_irqs:64 0
Architected cp15 timer(s) running at 100.00MHz (phys).
Console: colour dummy device 80x25
Calibrating delay loop (skipped), value calculated using timer frequency.. 200.00 BogoMIPS (lpj=1000000)
pid_max: default: 32768 minimum: 301
Mount-cache hash table entries: 256
hw perfevents: enabled with arm/armv8-pmuv3 PMU driver, 9 counters available
CPU1: Booted secondary processor
CPU2: Booted secondary processor
CPU3: Booted secondary processor
Brought up 4 CPUs
SMP: Total of 4 processors activated.
devtmpfs: initialized
atomic64 test passed
NET: Registered protocol family 16
of_amba_device_create(): amba_device_add() failed (-19) for /smb/motherboard/iofpga@3,00000000/sysctl@020000
vdso: 2 pages (1 code, 1 data) at base ffffffc000548000
hw-breakpoint: found 16 breakpoint and 16 watchpoint registers.
Serial: AMBA PL011 UART driver
1c090000.uart: ttyAMA0 at MMIO 0x1c090000 (irq = 37, base_baud = 0) is a PL011 rev2
console [ttyAMA0] enabled, bootconsole disabled
console [ttyAMA0] enabled, bootconsole disabled
1c0a0000.uart: ttyAMA1 at MMIO 0x1c0a0000 (irq = 38, base_baud = 0) is a PL011 rev2
1c0b0000.uart: ttyAMA2 at MMIO 0x1c0b0000 (irq = 39, base_baud = 0) is a PL011 rev2
1c0c0000.uart: ttyAMA3 at MMIO 0x1c0c0000 (irq = 40, base_baud = 0) is a PL011 rev2
bio: create slab <bio-0> at 0
SCSI subsystem initialized
Switched to clocksource arch_sys_counter
NET: Registered protocol family 2
TCP established hash table entries: 32768 (order: 7, 524288 bytes)
TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
TCP: Hash tables configured (established 32768 bind 32768)
TCP: reno registered
UDP hash table entries: 2048 (order: 4, 65536 bytes)
UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
NET: Registered protocol family 1
RPC: Registered named UNIX socket transport module.
RPC: Registered udp transport module.
RPC: Registered tcp transport module.
RPC: Registered tcp NFSv4.1 backchannel transport module.
Unpacking initramfs...
Freeing initrd memory: 1068K (ffffffc010000000 - ffffffc01010b000)
fuse init (API version 7.22)
msgmni has been set to 7928
io scheduler noop registered
io scheduler cfq registered (default)
Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
smc91x: not found (-19).
mousedev: PS/2 mouse device common for all mice
TCP: cubic registered
NET: Registered protocol family 17
<NUL>Freeing unused kernel memory: 172K (ffffffc0004e6000 - ffffffc000511000)
Wed Nov 18 01:00:00 UTC 2009
sh: can't access tty; job control turned off
/ #
/ #
/ #
/ # insm
/ # insmod [Jkr
/ # insmod kretprobe_example.ko [Jfunc=sys_sync
Planted return probe at sys_sync: ffffffc00015b518
/ # sync
sys_sync returned 0 and took 8390 ns to execute
/ #
/ #
/ #
/ #
/ #
/ # ls
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
[1;32minit[0m [1;34mproc[0m
/ #
/ #
/ #
/ #
/ #
/ # rmm
/ # rmmod [J[J[J[J[J[J[Jrmm
/ # rmmod [Jkr
/ # rmmod kretprobe_example.ko [J
kretprobe at ffffffc00015b518 unregistered
Missed probing 0 instances of sys_sync
/ # uins[J[J[J[Jnsm[J[J[Jinsm
/ # insmod [Jkr
/ # insmod kretprobe_example.ko [Jfunc=do_fork
Planted return probe at do_fork: ffffffc000092dec
/ #
/ # ls
do_fork returned 18874385 and took 7370 ns to execute
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
[1;32minit[0m [1;34mproc[0m
/ # ps
do_fork returned 18874385 and took 7370 ns to execute
PID USER COMMAND
1 0 /bin/busybox sh
2 0 [kthreadd]
3 0 [ksoftirqd/0]
4 0 [kworker/0:0]
5 0 [kworker/0:0H]
6 0 [kworker/u8:0]
7 0 [migration/0]
8 0 [rcu_bh]
9 0 [rcu_sched]
10 0 [migration/1]
11 0 [ksoftirqd/1]
12 0 [kworker/1:0]
13 0 [kworker/1:0H]
14 0 [migration/2]
15 0 [ksoftirqd/2]
16 0 [kworker/2:0]
17 0 [kworker/2:0H]
18 0 [migration/3]
19 0 [ksoftirqd/3]
20 0 [kworker/3:0]
21 0 [kworker/3:0H]
22 0 [khelper]
23 0 [kdevtmpfs]
24 0 [kworker/u8:1]
168 0 [writeback]
170 0 [bioset]
172 0 [kblockd]
266 0 [kworker/3:1]
267 0 [rpciod]
282 0 [kswapd0]
327 0 [fsnotify_mark]
348 0 [nfsiod]
413 0 [kworker/0:1]
414 0 [kpsmoused]
433 0 [deferwq]
434 0 [kworker/1:1]
452 0 [kworker/2:1]
461 0 ps
/ #
/ #
/ # rmm
/ # rmmod [Jkr
/ # rmmod kretprobe_example.ko [J
do_fork returned 18874385 and took 8650 ns to execute
kretprobe at ffffffc000092dec unregistered
Missed probing 0 instances of do_fork
/ #
/ #
/ #
/ # rmmod kretprobe_example.ko [J
/ # ps[J
/ # ls[J
/ # insmod kretprobe_example.ko func=do_fork[J
/ # rmmod kretprobe_example.ko [J
/ # insmod kretprobe_example.ko func=do_fork[J[J[J[J[J[J[J[Jvfs_read
Planted return probe at vfs_read: ffffffc000132364
/ # vfs_read returned 2012380160 and took 8390 ns to execute
/ # vfs_read returned 2012380160 and took 8390 ns to execute
/ # vfs_read returned 2012380160 and took 7370 ns to execute
lvfs_read returned 2012380160 and took 7370 ns to execute
svfs_read returned 2012380160 and took 7370 ns to execute
vfs_read returned 2010203904 and took 7360 ns to execute
vfs_read returned 2010203904 and took 8500 ns to execute
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
[1;32minit[0m [1;34mproc[0m
/ # vfs_read returned 2012380160 and took 7360 ns to execute
/ # vfs_read returned 2012380160 and took 7370 ns to execute
pvfs_read returned 2012380160 and took 7370 ns to execute
svfs_read returned 2012380160 and took 7370 ns to execute
vfs_read returned 2010204416 and took 7360 ns to execute
vfs_read returned 2010204416 and took 8490 ns to execute
PID USER COMMAND
vfs_read returned 2144132864 and took 8390 ns to execute
vfs_read returned 2144130304 and took 8490 ns to execute
vfs_read returned 2144130304 and took 8390 ns to execute
1 0 /bin/busybox sh
vfs_read returned 2144130816 and took 8400 ns to execute
vfs_read returned 2144130560 and took 8390 ns to execute
2 0 [kthreadd]
vfs_read returned 2144129536 and took 8390 ns to execute
vfs_read returned 2144130048 and took 8390 ns to execute
3 0 [ksoftirqd/0]
vfs_read returned 2144129792 and took 8390 ns to execute
vfs_read returned 2144129280 and took 8400 ns to execute
4 0 [kworker/0:0]
vfs_read returned 2144129024 and took 8390 ns to execute
vfs_read returned 2144092160 and took 8390 ns to execute
5 0 [kworker/0:0H]
vfs_read returned 2144092672 and took 8390 ns to execute
vfs_read returned 2144092928 and took 8390 ns to execute
6 0 [kworker/u8:0]
vfs_read returned 2144092416 and took 8400 ns to execute
vfs_read returned 2144093440 and took 8390 ns to execute
7 0 [migration/0]
vfs_read returned 2144093696 and took 8390 ns to execute
vfs_read returned 2144093184 and took 8390 ns to execute
8 0 [rcu_bh]
vfs_read returned 2144094208 and took 8390 ns to execute
vfs_read returned 2144094464 and took 8390 ns to execute
9 0 [rcu_sched]
vfs_read returned 2144093952 and took 8390 ns to execute
vfs_read returned 2144094976 and took 8390 ns to execute
10 0 [migration/1]
vfs_read returned 2144095232 and took 8390 ns to execute
vfs_read returned 2144094720 and took 8380 ns to execute
11 0 [ksoftirqd/1]
vfs_read returned 2144095744 and took 8380 ns to execute
vfs_read returned 2144096000 and took 8390 ns to execute
12 0 [kworker/1:0]
vfs_read returned 2144095488 and took 8390 ns to execute
vfs_read returned 2004431104 and took 8380 ns to execute
13 0 [kworker/1:0H]
vfs_read returned 2004431360 and took 8390 ns to execute
vfs_read returned 2004430848 and took 8390 ns to execute
14 0 [migration/2]
vfs_read returned 2004431872 and took 8390 ns to execute
vfs_read returned 2004432128 and took 8390 ns to execute
15 0 [ksoftirqd/2]
vfs_read returned 2004431616 and took 8390 ns to execute
vfs_read returned 2004432640 and took 8390 ns to execute
16 0 [kworker/2:0]
vfs_read returned 2004432896 and took 8390 ns to execute
vfs_read returned 2004432384 and took 8390 ns to execute
17 0 [kworker/2:0H]
vfs_read returned 2004433408 and took 8390 ns to execute
vfs_read returned 2004433664 and took 8390 ns to execute
18 0 [migration/3]
vfs_read returned 2004433152 and took 8390 ns to execute
vfs_read returned 2004434176 and took 8390 ns to execute
19 0 [ksoftirqd/3]
vfs_read returned 2004434432 and took 8390 ns to execute
vfs_read returned 2004433920 and took 8390 ns to execute
20 0 [kworker/3:0]
vfs_read returned 2004434688 and took 8390 ns to execute
vfs_read returned 2004434944 and took 8390 ns to execute
21 0 [kworker/3:0H]
vfs_read returned 2004435200 and took 8390 ns to execute
vfs_read returned 2004435712 and took 8390 ns to execute
22 0 [khelper]
vfs_read returned 2004435968 and took 8390 ns to execute
vfs_read returned 2004435456 and took 8390 ns to execute
23 0 [kdevtmpfs]
vfs_read returned 2004436480 and took 8390 ns to execute
vfs_read returned 2004436736 and took 8390 ns to execute
24 0 [kworker/u8:1]
vfs_read returned 2004436224 and took 8390 ns to execute
vfs_read returned 2004437248 and took 8390 ns to execute
168 0 [writeback]
vfs_read returned 2004437504 and took 8390 ns to execute
vfs_read returned 2004436992 and took 8390 ns to execute
170 0 [bioset]
vfs_read returned 2004438016 and took 8390 ns to execute
vfs_read returned 2004438272 and took 8390 ns to execute
172 0 [kblockd]
vfs_read returned 2004437760 and took 8390 ns to execute
vfs_read returned 2004438784 and took 8380 ns to execute
266 0 [kworker/3:1]
vfs_read returned 2004438528 and took 8390 ns to execute
vfs_read returned 2004439040 and took 8390 ns to execute
267 0 [rpciod]
vfs_read returned 2004439552 and took 8390 ns to execute
vfs_read returned 2004439808 and took 8390 ns to execute
282 0 [kswapd0]
vfs_read returned 2004439296 and took 8390 ns to execute
vfs_read returned 2004440320 and took 8390 ns to execute
327 0 [fsnotify_mark]
vfs_read returned 2004440576 and took 8390 ns to execute
vfs_read returned 2004440064 and took 8390 ns to execute
348 0 [nfsiod]
vfs_read returned 2004441088 and took 8390 ns to execute
vfs_read returned 2004441344 and took 8400 ns to execute
413 0 [kworker/0:1]
vfs_read returned 2004440832 and took 8390 ns to execute
vfs_read returned 2004441856 and took 8390 ns to execute
414 0 [kpsmoused]
vfs_read returned 2004442112 and took 8390 ns to execute
vfs_read returned 2004441600 and took 8390 ns to execute
433 0 [deferwq]
vfs_read returned 2004442624 and took 8390 ns to execute
vfs_read returned 2004442880 and took 8390 ns to execute
434 0 [kworker/1:1]
vfs_read returned 2004442368 and took 8390 ns to execute
vfs_read returned 2010887680 and took 8390 ns to execute
452 0 [kworker/2:1]
vfs_read returned 2010886912 and took 7370 ns to execute
vfs_read returned 2010888704 and took 8490 ns to execute
vfs_read returned 2010888704 and took 8390 ns to execute
467 0 ps
/ # vfs_read returned 2012380160 and took 7370 ns to execute
/ # vfs_read returned 2012380160 and took 7370 ns to execute
lvfs_read returned 2012380160 and took 7380 ns to execute
svfs_read returned 2012380160 and took 8390 ns to execute
vfs_read returned 2010204928 and took 7370 ns to execute
vfs_read returned 2010204928 and took 8490 ns to execute
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
[1;32minit[0m [1;34mproc[0m
/ # vfs_read returned 2012380160 and took 7360 ns to execute
/ # vfs_read returned 2012380160 and took 7370 ns to execute
pvfs_read returned 2012380160 and took 8650 ns to execute
svfs_read returned 2012380160 and took 7360 ns to execute
vfs_read returned 2010202368 and took 7360 ns to execute
vfs_read returned 2010202368 and took 8480 ns to execute
PID USER COMMAND
vfs_read returned 2010886912 and took 8400 ns to execute
vfs_read returned 2010886656 and took 8500 ns to execute
vfs_read returned 2010886656 and took 8390 ns to execute
1 0 /bin/busybox sh
vfs_read returned 2010887424 and took 8390 ns to execute
vfs_read returned 2010887936 and took 8390 ns to execute
2 0 [kthreadd]
vfs_read returned 2010888448 and took 8380 ns to execute
vfs_read returned 2010888960 and took 8390 ns to execute
3 0 [ksoftirqd/0]
vfs_read returned 2010889472 and took 8390 ns to execute
vfs_read returned 2010886144 and took 8390 ns to execute
4 0 [kworker/0:0]
vfs_read returned 2010887168 and took 8390 ns to execute
vfs_read returned 2004442368 and took 8400 ns to execute
5 0 [kworker/0:0H]
vfs_read returned 2004442880 and took 8390 ns to execute
vfs_read returned 2004442624 and took 8390 ns to execute
6 0 [kworker/u8:0]
vfs_read returned 2004441600 and took 8400 ns to execute
vfs_read returned 2004442112 and took 8390 ns to execute
7 0 [migration/0]
vfs_read returned 2004441856 and took 8390 ns to execute
vfs_read returned 2004440832 and took 8400 ns to execute
8 0 [rcu_bh]
vfs_read returned 2004441344 and took 8380 ns to execute
vfs_read returned 2004441088 and took 8390 ns to execute
9 0 [rcu_sched]
vfs_read returned 2004440064 and took 8390 ns to execute
vfs_read returned 2004440576 and took 8390 ns to execute
10 0 [migration/1]
vfs_read returned 2004440320 and took 8390 ns to execute
vfs_read returned 2004439296 and took 8380 ns to execute
11 0 [ksoftirqd/1]
vfs_read returned 2004439808 and took 8390 ns to execute
vfs_read returned 2004439552 and took 8400 ns to execute
12 0 [kworker/1:0]
vfs_read returned 2004439040 and took 8390 ns to execute
vfs_read returned 2004438528 and took 8390 ns to execute
13 0 [kworker/1:0H]
vfs_read returned 2004438784 and took 8390 ns to execute
vfs_read returned 2004437760 and took 8390 ns to execute
14 0 [migration/2]
vfs_read returned 2004438272 and took 8390 ns to execute
vfs_read returned 2004438016 and took 8390 ns to execute
15 0 [ksoftirqd/2]
vfs_read returned 2004436992 and took 8390 ns to execute
vfs_read returned 2004437504 and took 8380 ns to execute
16 0 [kworker/2:0]
vfs_read returned 2004437248 and took 8390 ns to execute
vfs_read returned 2004436224 and took 8390 ns to execute
17 0 [kworker/2:0H]
vfs_read returned 2004436736 and took 8390 ns to execute
vfs_read returned 2004436480 and took 8390 ns to execute
18 0 [migration/3]
vfs_read returned 2004435456 and took 8390 ns to execute
vfs_read returned 2004435968 and took 8390 ns to execute
19 0 [ksoftirqd/3]
vfs_read returned 2004435712 and took 8390 ns to execute
vfs_read returned 2004435200 and took 8390 ns to execute
20 0 [kworker/3:0]
vfs_read returned 2004434944 and took 8380 ns to execute
vfs_read returned 2004434688 and took 8390 ns to execute
21 0 [kworker/3:0H]
vfs_read returned 2004433920 and took 8390 ns to execute
vfs_read returned 2004434432 and took 8390 ns to execute
22 0 [khelper]
vfs_read returned 2004434176 and took 8390 ns to execute
vfs_read returned 2004433152 and took 8400 ns to execute
23 0 [kdevtmpfs]
vfs_read returned 2004433664 and took 8390 ns to execute
vfs_read returned 2004433408 and took 8390 ns to execute
24 0 [kworker/u8:1]
vfs_read returned 2004432384 and took 8390 ns to execute
vfs_read returned 2004432896 and took 8400 ns to execute
168 0 [writeback]
vfs_read returned 2004432640 and took 8390 ns to execute
vfs_read returned 2004431616 and took 8390 ns to execute
170 0 [bioset]
vfs_read returned 2004432128 and took 8400 ns to execute
vfs_read returned 2004431872 and took 8390 ns to execute
172 0 [kblockd]
vfs_read returned 2004430848 and took 8390 ns to execute
vfs_read returned 2004431360 and took 8390 ns to execute
266 0 [kworker/3:1]
vfs_read returned 2004431104 and took 8390 ns to execute
vfs_read returned 2144095488 and took 8390 ns to execute
267 0 [rpciod]
vfs_read returned 2144096000 and took 8390 ns to execute
vfs_read returned 2144095744 and took 8390 ns to execute
282 0 [kswapd0]
vfs_read returned 2144094720 and took 8390 ns to execute
vfs_read returned 2144095232 and took 8390 ns to execute
327 0 [fsnotify_mark]
vfs_read returned 2144094976 and took 8390 ns to execute
vfs_read returned 2144093952 and took 8390 ns to execute
348 0 [nfsiod]
vfs_read returned 2144094464 and took 8390 ns to execute
vfs_read returned 2144094208 and took 8390 ns to execute
413 0 [kworker/0:1]
vfs_read returned 2144093184 and took 8390 ns to execute
vfs_read returned 2144093696 and took 8390 ns to execute
414 0 [kpsmoused]
vfs_read returned 2144093440 and took 8390 ns to execute
vfs_read returned 2144092416 and took 8390 ns to execute
433 0 [deferwq]
vfs_read returned 2144092928 and took 8390 ns to execute
vfs_read returned 2144092672 and took 8390 ns to execute
434 0 [kworker/1:1]
vfs_read returned 2144092160 and took 8390 ns to execute
vfs_read returned 2144131840 and took 8390 ns to execute
452 0 [kworker/2:1]
vfs_read returned 2144129024 and took 7370 ns to execute
vfs_read returned 2144129280 and took 8480 ns to execute
vfs_read returned 2144129280 and took 8400 ns to execute
469 0 ps
/ # vfs_read returned 2012380160 and took 7370 ns to execute
/ # vfs_read returned 2012380160 and took 7380 ns to execute
rvfs_read returned 2012380160 and took 7370 ns to execute
mvfs_read returned 2012380160 and took 7370 ns to execute
mvfs_read returned 2012380160 and took 7370 ns to execute
/ # rmmod [Jvfs_read returned 2012380160 and took 7360 ns to execute
kvfs_read returned 2012380160 and took 8380 ns to execute
rvfs_read returned 2012380160 and took 7370 ns to execute
/ # rmmod kretprobe_example.ko [Jvfs_read returned 2012380160 and took 8650 ns to execute
vfs_read returned 2010204672 and took 8480 ns to execute
vfs_read returned 2010204672 and took 8480 ns to execute
kretprobe at ffffffc000132364 unregistered
Missed probing 0 instances of vfs_read
/ #
/ #
/ #
/ #
/ #
/ #
/ # insm
/ # insmod [Jvfs_w[J[J[J[J[Jkr
/ # insmod kretprobe_example.ko [Jfunc=vfs_wru[Jite
Planted return probe at vfs_write: ffffffc0001321c0
vfs_write returned 2012380160 and took 8400 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
lvfs_write returned 2012380160 and took 8390 ns to execute
svfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413952 and took 7370 ns to execute
vfs_write returned 2012380160 and took 7370 ns to execute
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
vfs_write returned 2012380160 and took 7360 ns to execute
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
vfs_write returned 2012380160 and took 8390 ns to execute
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
vfs_write returned 2012380160 and took 7380 ns to execute
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
vfs_write returned 2012380160 and took 8390 ns to execute
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
vfs_write returned 2012380160 and took 7370 ns to execute
[1;32minit[0m [1;34mproc[0m
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8380 ns to execute
pvfs_write returned 2012380160 and took 8390 ns to execute
svfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413952 and took 7370 ns to execute
vfs_write returned 2012380160 and took 8650 ns to execute
PID USER COMMAND
vfs_write returned 2012380160 and took 8650 ns to execute
1 0 /bin/busybox sh
vfs_write returned 2012380160 and took 7360 ns to execute
2 0 [kthreadd]
vfs_write returned 2012380160 and took 7370 ns to execute
3 0 [ksoftirqd/0]
vfs_write returned 2012380160 and took 7370 ns to execute
4 0 [kworker/0:0]
vfs_write returned 2012380160 and took 8390 ns to execute
5 0 [kworker/0:0H]
vfs_write returned 2012380160 and took 8390 ns to execute
6 0 [kworker/u8:0]
vfs_write returned 2012380160 and took 7360 ns to execute
7 0 [migration/0]
vfs_write returned 2012380160 and took 8390 ns to execute
8 0 [rcu_bh]
vfs_write returned 2012380160 and took 8380 ns to execute
9 0 [rcu_sched]
vfs_write returned 2012380160 and took 7360 ns to execute
10 0 [migration/1]
vfs_write returned 2012380160 and took 7370 ns to execute
11 0 [ksoftirqd/1]
vfs_write returned 2012380160 and took 7370 ns to execute
12 0 [kworker/1:0]
vfs_write returned 2012380160 and took 8390 ns to execute
13 0 [kworker/1:0H]
vfs_write returned 2012380160 and took 7370 ns to execute
14 0 [migration/2]
vfs_write returned 2012380160 and took 7370 ns to execute
15 0 [ksoftirqd/2]
vfs_write returned 2012380160 and took 7380 ns to execute
16 0 [kworker/2:0]
vfs_write returned 2012380160 and took 8390 ns to execute
17 0 [kworker/2:0H]
vfs_write returned 2012380160 and took 7370 ns to execute
18 0 [migration/3]
vfs_write returned 2012380160 and took 7380 ns to execute
19 0 [ksoftirqd/3]
vfs_write returned 2012380160 and took 7380 ns to execute
20 0 [kworker/3:0]
vfs_write returned 2012380160 and took 8390 ns to execute
21 0 [kworker/3:0H]
vfs_write returned 2012380160 and took 8390 ns to execute
22 0 [khelper]
vfs_write returned 2012380160 and took 8390 ns to execute
23 0 [kdevtmpfs]
vfs_write returned 2012380160 and took 8390 ns to execute
24 0 [kworker/u8:1]
vfs_write returned 2012380160 and took 8390 ns to execute
168 0 [writeback]
vfs_write returned 2012380160 and took 8390 ns to execute
170 0 [bioset]
vfs_write returned 2012380160 and took 8390 ns to execute
172 0 [kblockd]
vfs_write returned 2012380160 and took 7360 ns to execute
266 0 [kworker/3:1]
vfs_write returned 2012380160 and took 8390 ns to execute
267 0 [rpciod]
vfs_write returned 2012380160 and took 8390 ns to execute
282 0 [kswapd0]
vfs_write returned 2012380160 and took 8390 ns to execute
327 0 [fsnotify_mark]
vfs_write returned 2012380160 and took 8390 ns to execute
348 0 [nfsiod]
vfs_write returned 2012380160 and took 7370 ns to execute
413 0 [kworker/0:1]
vfs_write returned 2012380160 and took 8390 ns to execute
414 0 [kpsmoused]
vfs_write returned 2012380160 and took 8390 ns to execute
433 0 [deferwq]
vfs_write returned 2012380160 and took 7370 ns to execute
434 0 [kworker/1:1]
vfs_write returned 2012380160 and took 7370 ns to execute
452 0 [kworker/2:1]
vfs_write returned 2012380160 and took 7370 ns to execute
475 0 ps
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
lvfs_write returned 2012380160 and took 8380 ns to execute
svfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413952 and took 7370 ns to execute
vfs_write returned 2012380160 and took 7360 ns to execute
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
vfs_write returned 2012380160 and took 7370 ns to execute
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
vfs_write returned 2012380160 and took 8380 ns to execute
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
vfs_write returned 2012380160 and took 7380 ns to execute
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
vfs_write returned 2012380160 and took 8390 ns to execute
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
vfs_write returned 2012380160 and took 7380 ns to execute
[1;32minit[0m [1;34mproc[0m
vfs_write returned 2012380160 and took 8380 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8380 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
cvfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413952 and took 7370 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
sh: vfs_write returned 2012380160 and took 7380 ns to execute
c: not foundvfs_write returned 2012380160 and took 7370 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
cvfs_write returned 2012380160 and took 8390 ns to execute
lvfs_write returned 2012380160 and took 8390 ns to execute
evfs_write returned 2012380160 and took 8390 ns to execute
avfs_write returned 2012380160 and took 8390 ns to execute
rvfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413952 and took 7370 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
[H[Jvfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8400 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
lvfs_write returned 2012380160 and took 8390 ns to execute
svfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413952 and took 7370 ns to execute
vfs_write returned 2012380160 and took 7370 ns to execute
[1;34mbin[0m [0;0mjprobe_example.ko[0m [1;34mroot[0m
vfs_write returned 2012380160 and took 7370 ns to execute
[1;34mdata[0m [0;0mkprobe_dbg_addr.ko[0m [1;34msdcard[0m
vfs_write returned 2012380160 and took 8390 ns to execute
[1;36mdebug[0m [0;0mkprobe_dbg_sym.ko[0m [1;34msys[0m
vfs_write returned 2012380160 and took 7370 ns to execute
[1;34mdev[0m [0;0mkprobe_example.ko[0m [1;34msystem[0m
vfs_write returned 2012380160 and took 8390 ns to execute
[1;34mhome[0m [0;0mkretprobe_example.ko[0m [1;34mtmp[0m
vfs_write returned 2012380160 and took 7370 ns to execute
[1;32minit[0m [1;34mproc[0m
vfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 8390 ns to execute
rvfs_write returned 2012380160 and took 8390 ns to execute
mvfs_write returned 2012380160 and took 8400 ns to execute
mvfs_write returned 2012380160 and took 8390 ns to execute
/ # vfs_write returned 2012380160 and took 7370 ns to execute
rmmod [Jvfs_write returned 2012380160 and took 8400 ns to execute
kvfs_write returned 2012380160 and took 8390 ns to execute
rvfs_write returned 2012380160 and took 7360 ns to execute
/ # vfs_write returned 2012380160 and took 7370 ns to execute
rmmod kretprobe_example.ko [Jvfs_write returned 2012380160 and took 8390 ns to execute
vfs_write returned 2004413184 and took 8390 ns to execute
kretprobe at ffffffc0001321c0 unregistered
Missed probing 0 instances of vfs_write
/ #
/ #