[root@five ~]# perf trace record -g sleep 1 [ perf record: Woken up 1 times to write data ] [ perf record: Captured and wrote 0.051 MB perf.data (78 samples) ] [root@five ~]# perf evlist -v raw_syscalls:sys_enter: type: 2, size: 120, config: 0x154, { sample_period, sample_freq }: 1, sample_type: IP|TID|TIME|CALLCHAIN|ID|CPU|PERIOD|RAW, read_format: ID, disabled: 1, inherit: 1, mmap: 1, comm: 1, enable_on_exec: 1, task: 1, sample_id_all: 1, exclude_guest: 1, mmap2: 1, comm_exec: 1, ksymbol: 1, bpf_event: 1 raw_syscalls:sys_exit: type: 2, size: 120, config: 0x153, { sample_period, sample_freq }: 1, sample_type: IP|TID|TIME|CALLCHAIN|ID|CPU|PERIOD|RAW, read_format: ID, disabled: 1, inherit: 1, enable_on_exec: 1, sample_id_all: 1, exclude_guest: 1 # Tip: use 'perf evlist --trace-fields' to show fields for tracepoint events [root@five ~]# perf report | cat # To display the perf.data header info, please use --header/--header-only options. # # # Total Lost Samples: 0 # # Samples: 39 of event 'raw_syscalls:sys_enter' # Event count (approx.): 39 # # Children Self Trace output # ........ ........ ......................................................................... # 2.56% 2.56% NR 257 (ffffff9c, 7f2a14915b40, 80000, 0, 80000, 7f2a14915b40) | ---0x327876615f7268 0 0x7f2a1493fa20 setlocale _nl_find_locale __open64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 3 (3, cff2a70, 6355, 0, 3, 0) | ---0x327876615f7268 0 0x7f2a1493fa20 setlocale _nl_find_locale __close_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 5 (3, 7f2a149445e0, 7f2a149445e0, 0, 1, 7f2a14915b40) | ---0x327876615f7268 0 0x7f2a1493fa20 setlocale _nl_find_locale __fxstat64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 10 (564594343000, 1000, 1, 56459433a000, ffff, 7f2a1478e6c0) | ---0x41e589480000a414 _dl_sysdep_start dl_main mprotect entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 10 (7f2a1493f000, 3000, 1, 7f2a1477f000, 7f2a148e48b0, 7f2a147941e0) | ---0x41e589480000a414 _dl_sysdep_start dl_main mprotect entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 10 (7f2a1499c000, 1000, 1, 7f2a14970000, 0, 7f2a147941e0) | ---0x41e589480000a414 _dl_sysdep_start dl_main mprotect entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 11 (7f2a1494b000, 240f0, 1162b00000000, 7f2a14970000, 0, 7f2a147941e0) | ---0x41e589480000a414 _dl_sysdep_start munmap entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 158 (1002, 7f2a1494a580, ffff80d5eb6b5140, 1, 7f2a1494a580, 90) | ---0x41e589480000a414 _dl_sysdep_start init_tls entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 17 (3, 7ffe20528b50, 44, 370, 3, 370) | ---_dl_map_object _dl_map_object_from_fd __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 17 (3, 7ffe20528b70, 20, 350, 3, 350) | ---_dl_map_object _dl_map_object_from_fd __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 21 (7f2a14997920, 4, 7f2a14970238, 0, 0, 7) | ---0x41e589480000a414 _dl_sysdep_start access entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 0 (3, 7ffe20529398, 340, 0, 80000, 7f2a1499ed10) | ---_dl_map_object __GI___read_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 12 (0, 21000, 2b0, 2b0, 3, 7f2a14942a40) | ---0 __brk entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 12 (0, 564595cb7fff, 1, 2b0, 564595c96000, 7f2a14942a40) | ---0 __brk entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 12 (0, 7f2a14994d54, 50, 1a, 7ffe2052cfdc, 7f2a1499ce18) | ---0x41e589480000a414 brk entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 12 (564595cb7000, 21000, 7f2a14946540, 2b0, 3, 7f2a14942a40) | ---0 __brk entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 158 (3001, 7ffe20529fe0, 7f2a1498b2d0, ee, 3, 20) | ---0x41e589480000a414 _dl_sysdep_start entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 17 (3, 7ffe20528e90, 310, 40, ffff, 7f2a14949480) | ---_dl_map_object __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 17 (3, 7ffe20528f20, 44, 370, 7ffe20528fa0, 0) | ---_dl_map_object __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 17 (3, 7ffe20528f70, 20, 350, 7ffe20528fa0, 0) | ---_dl_map_object __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 17 (3, 7ffe20528fa0, 310, 40, 7ffe20528fa0, 40) | ---_dl_map_object __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 230 (0, 0, 7ffe20529ee0, 7ffe20529f20, 1, 0) | ---0 clock_nanosleep@@GLIBC_2.17 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 257 (ffffff9c, 7f2a14994ac1, 80000, 0, 80000, 7f2a14994ac1) | ---_dl_map_object __GI___open64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 257 (ffffff9c, 7f2a1499ed10, 80000, 0, 80000, 7f2a1499ed10) | ---_dl_map_object __GI___open64_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 3 (3, 29, 7f2a14941b10, 70000022, 7f2a1477f000, effffef5) | ---_dl_map_object __GI___close_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 5 (3, 7ffe20529240, 7ffe20529240, 370, 1, 7f2a1499e140) | ---_dl_map_object _fxstat entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 231 (0, e7, 3c, 7ffe20529f20, ffffffffffffff80, 1) | ---_exit entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 3 (1, fbad2004, 7f2a14943700, 7ffe20529f20, 0, 1) | ---__close_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 3 (2, fbad2006, 7f2a14943700, 7ffe20529f20, 0, 1) | ---__close_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 3 (3, 240f0, 1, 2, 3, 0) | ---__GI___close_nocancel entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 5 (3, 7ffe205291f0, 7ffe205291f0, 0, 1, 7f2a14994ac1) | ---_fxstat entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (0, 1c9368, 1, 802, 3, 0) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (0, 2000, 3, 22, ffffffff, 0) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (0, 240f0, 1, 2, 3, 0) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (0, cff2a70, 1, 2, 3, 0) | ---__mmap entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (7f2a147a4000, 150000, 5, 812, 3, 25000) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (7f2a148f4000, 4b000, 1, 812, 3, 175000) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (7f2a1493f000, 6000, 3, 812, 3, 1bf000) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 2.56% 2.56% NR 9 (7f2a14945000, 3368, 3, 32, ffffffff, 0) | ---mmap64 entry_SYSCALL_64_after_hwframe do_syscall_64 syscall_trace_enter.constprop.0 syscall_trace_enter.constprop.0 # Samples: 39 of event 'raw_syscalls:sys_exit' # Event count (approx.): 39 # # Children Self Trace output # ........ ........ ...................... # 12.82% 12.82% NR 3 = 0 | |--5.13%--__close_nocancel | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | |--2.56%--__GI___close_nocancel | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | |--2.56%--_dl_map_object | __GI___close_nocancel | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | --2.56%--0x327876615f7268 0 0x7f2a1493fa20 setlocale _nl_find_locale __close_nocancel entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 7.69% 7.69% NR 257 = 3 | |--5.13%--_dl_map_object | __GI___open64_nocancel | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | --2.56%--0x327876615f7268 0 0x7f2a1493fa20 setlocale _nl_find_locale __open64_nocancel entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 7.69% 7.69% NR 5 = 0 | |--2.56%--_fxstat | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | |--2.56%--_dl_map_object | _fxstat | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | --2.56%--0x327876615f7268 0 0x7f2a1493fa20 setlocale _nl_find_locale __fxstat64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 7.69% 7.69% NR 10 = 0 | ---0x41e589480000a414 _dl_sysdep_start dl_main mprotect entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 5.13% 5.13% NR 17 = 32 | ---_dl_map_object | |--2.56%--__GI___pread64_nocancel | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | --2.56%--_dl_map_object_from_fd __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 5.13% 5.13% NR 17 = 68 | ---_dl_map_object | |--2.56%--__GI___pread64_nocancel | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | --2.56%--_dl_map_object_from_fd __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 5.13% 5.13% NR 12 = 94856865734656 | |--2.56%--0x41e589480000a414 | brk | entry_SYSCALL_64_after_hwframe | syscall_exit_to_user_mode | syscall_exit_to_user_mode | --2.56%--0 __brk entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 5.13% 5.13% NR 12 = 94856865869824 | ---0 __brk entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 5.13% 5.13% NR 17 = 784 | ---_dl_map_object __GI___pread64_nocancel entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 11 = 0 | ---0x41e589480000a414 _dl_sysdep_start munmap entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 158 = 0 | ---0x41e589480000a414 _dl_sysdep_start init_tls entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 21 = -2 | ---0x41e589480000a414 _dl_sysdep_start access entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 0 = 832 | ---_dl_map_object __GI___read_nocancel entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 158 = -22 | ---0x41e589480000a414 _dl_sysdep_start entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 230 = 0 | ---0 clock_nanosleep@@GLIBC_2.17 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 59 = 0 | ---_start entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818490707968 | ---__mmap entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818708758528 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818708910080 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818710286336 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818710593536 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818710618112 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818710634496 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode 2.56% 2.56% NR 9 = 139818710642688 | ---mmap64 entry_SYSCALL_64_after_hwframe syscall_exit_to_user_mode syscall_exit_to_user_mode # # (Tip: Compare performance results with: perf diff [ ]) # [root@five ~]# [root@five ~]# perf trace --summary -i perf.data Summary of events: sleep (3076535), 78 events, 100.0% syscall calls errors total min avg max stddev (msec) (msec) (msec) (msec) (%) --------------- -------- ------ -------- --------- --------- --------- ------ clock_nanosleep 1 0 1000.043 1000.043 1000.043 1000.043 0.00% mmap 8 0 0.054 0.005 0.007 0.013 14.65% openat 3 0 0.020 0.005 0.007 0.009 17.41% mprotect 3 0 0.017 0.005 0.006 0.006 9.00% pread64 6 0 0.013 0.002 0.002 0.002 2.65% brk 4 0 0.012 0.002 0.003 0.005 24.51% close 5 0 0.011 0.002 0.002 0.004 16.57% munmap 1 0 0.008 0.008 0.008 0.008 0.00% fstat 3 0 0.007 0.002 0.002 0.003 9.15% access 1 1 0.005 0.005 0.005 0.005 0.00% arch_prctl 2 1 0.003 0.001 0.002 0.002 9.49% read 1 0 0.003 0.003 0.003 0.003 0.00% execve 1 0 0.000 0.000 0.000 0.000 0.00% [root@five ~]# [root@five ~]# perf trace --errno-summary -i perf.data Summary of events: sleep (3076535), 78 events, 100.0% syscall calls errors total min avg max stddev (msec) (msec) (msec) (msec) (%) --------------- -------- ------ -------- --------- --------- --------- ------ clock_nanosleep 1 0 1000.043 1000.043 1000.043 1000.043 0.00% mmap 8 0 0.054 0.005 0.007 0.013 14.65% openat 3 0 0.020 0.005 0.007 0.009 17.41% mprotect 3 0 0.017 0.005 0.006 0.006 9.00% pread64 6 0 0.013 0.002 0.002 0.002 2.65% brk 4 0 0.012 0.002 0.003 0.005 24.51% close 5 0 0.011 0.002 0.002 0.004 16.57% munmap 1 0 0.008 0.008 0.008 0.008 0.00% fstat 3 0 0.007 0.002 0.002 0.003 9.15% access 1 1 0.005 0.005 0.005 0.005 0.00% ENOENT: 1 arch_prctl 2 1 0.003 0.001 0.002 0.002 9.49% EINVAL: 1 read 1 0 0.003 0.003 0.003 0.003 0.00% execve 1 0 0.000 0.000 0.000 0.000 0.00% [root@five ~]#