This is the mail archive of the systemtap@sourceware.org mailing list for the systemtap project.


Index Nav: [Date Index] [Subject Index] [Author Index] [Thread Index]
Message Nav: [Date Prev] [Date Next] [Thread Prev] [Thread Next]
Other format: [Raw text]

Re: [Fwd: Re: Regarding systemtap support for AArch64]


> 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 kr
/ # insmod kretprobe_example.ko func=sys_sync
Planted return probe at sys_sync: ffffffc00015b518
/ # sync
sys_sync returned 0 and took 8390 ns to execute
/ # 
/ # 
/ # 
/ # 
/ # 
/ # ls
bin                   jprobe_example.ko     root
data                  kprobe_dbg_addr.ko    sdcard
debug                 kprobe_dbg_sym.ko     sys
dev                   kprobe_example.ko     system
home                  kretprobe_example.ko  tmp
init                  proc
/ # 
/ # 
/ # 
/ # 
/ # 
/ # rmm
/ # rmmod rmm
/ # rmmod kr
/ # rmmod kretprobe_example.ko 
kretprobe at ffffffc00015b518 unregistered
Missed probing 0 instances of sys_sync
/ # uinsnsminsm
/ # insmod kr
/ # insmod kretprobe_example.ko func=do_fork
Planted return probe at do_fork: ffffffc000092dec
/ # 
/ # ls
do_fork returned 18874385 and took 7370 ns to execute
bin                   jprobe_example.ko     root
data                  kprobe_dbg_addr.ko    sdcard
debug                 kprobe_dbg_sym.ko     sys
dev                   kprobe_example.ko     system
home                  kretprobe_example.ko  tmp
init                  proc
/ # 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 kr
/ # rmmod kretprobe_example.ko 
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 
/ # ps
/ # ls
/ # insmod kretprobe_example.ko func=do_fork
/ # rmmod kretprobe_example.ko 
/ # insmod kretprobe_example.ko func=do_forkvfs_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
bin                   jprobe_example.ko     root
data                  kprobe_dbg_addr.ko    sdcard
debug                 kprobe_dbg_sym.ko     sys
dev                   kprobe_example.ko     system
home                  kretprobe_example.ko  tmp
init                  proc
/ # 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
bin                   jprobe_example.ko     root
data                  kprobe_dbg_addr.ko    sdcard
debug                 kprobe_dbg_sym.ko     sys
dev                   kprobe_example.ko     system
home                  kretprobe_example.ko  tmp
init                  proc
/ # 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 vfs_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 vfs_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 vfs_wkr
/ # insmod kretprobe_example.ko func=vfs_wruite
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
bin                   jprobe_example.ko     root
vfs_write returned 2012380160 and took 7360 ns to execute
data                  kprobe_dbg_addr.ko    sdcard
vfs_write returned 2012380160 and took 8390 ns to execute
debug                 kprobe_dbg_sym.ko     sys
vfs_write returned 2012380160 and took 7380 ns to execute
dev                   kprobe_example.ko     system
vfs_write returned 2012380160 and took 8390 ns to execute
home                  kretprobe_example.ko  tmp
vfs_write returned 2012380160 and took 7370 ns to execute
init                  proc
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
bin                   jprobe_example.ko     root
vfs_write returned 2012380160 and took 7370 ns to execute
data                  kprobe_dbg_addr.ko    sdcard
vfs_write returned 2012380160 and took 8380 ns to execute
debug                 kprobe_dbg_sym.ko     sys
vfs_write returned 2012380160 and took 7380 ns to execute
dev                   kprobe_example.ko     system
vfs_write returned 2012380160 and took 8390 ns to execute
home                  kretprobe_example.ko  tmp
vfs_write returned 2012380160 and took 7380 ns to execute
init                  proc
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
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 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
bin                   jprobe_example.ko     root
vfs_write returned 2012380160 and took 7370 ns to execute
data                  kprobe_dbg_addr.ko    sdcard
vfs_write returned 2012380160 and took 8390 ns to execute
debug                 kprobe_dbg_sym.ko     sys
vfs_write returned 2012380160 and took 7370 ns to execute
dev                   kprobe_example.ko     system
vfs_write returned 2012380160 and took 8390 ns to execute
home                  kretprobe_example.ko  tmp
vfs_write returned 2012380160 and took 7370 ns to execute
init                  proc
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 vfs_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 vfs_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
/ # 
/ # 

Index Nav: [Date Index] [Subject Index] [Author Index] [Thread Index]
Message Nav: [Date Prev] [Date Next] [Thread Prev] [Thread Next]