Project

General

Profile

Bug #544 » perf-report-2.1.1.txt

Stanislav Vovk, 05/30/2013 07:47 AM

 
# ========
# captured on: Thu May 30 13:11:06 2013
# hostname : du1
# os release : 3.4.34-rt40-00001-g7f4955b
# perf version : CNX9012627.R2A140.1.gbf5b482
# arch : ppc
# nrcpus online : 4
# nrcpus avail : 4
# cpudesc : 476dd2
# cpuid : 4517,8384
# total memory : 1420008 kB
# cmdline : /root/perf record -e cpu-clock,task-clock,page-faults,minor-faults:u,major-faults,cs,migrations,alignment-faults,emulation-faults ./ltt-test-2.1.1 -l 1000000 -t 1
# event : name = cpu-clock, type = 1, config = 0x0, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 373, 374, 375, 376 }
# event : name = task-clock, type = 1, config = 0x1, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 377, 378, 379, 380 }
# event : name = page-faults, type = 1, config = 0x2, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 381, 382, 383, 384 }
# event : name = minor-faults, type = 1, config = 0x5, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 1, id = { 385, 386, 387, 388 }
# event : name = major-faults, type = 1, config = 0x6, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 389, 390, 391, 392 }
# event : name = context-switches, type = 1, config = 0x3, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 393, 394, 395, 396 }
# event : name = CPU-migrations, type = 1, config = 0x4, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 397, 398, 399, 400 }
# event : name = alignment-faults, type = 1, config = 0x7, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 401, 402, 403, 404 }
# event : name = emulation-faults, type = 1, config = 0x8, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, id = { 405, 406, 407, 408 }
# HEADER_CPU_TOPOLOGY info available, use -I to display
# ========
#
# Events: 3K cpu-clock
#
# Overhead Command Shared Object Symbol
# ........ .............. ................................ ................................................
#
24.98% ltt-test-2.1.1 ld-2.15.so [.] 0x00019d0c
24.98% ltt-test-2.1.1 [kernel.kallsyms] [k] __copy_tofrom_user
24.98% ltt-test-2.1.1 libc-2.15.so [.] __libc_calloc
24.98% ltt-test-2.1.1 [kernel.kallsyms] [k] schedule
0.03% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] 0x0001dd68
0.01% ltt-test-2.1.1 ld-2.15.so [.] __tls_get_addr
0.01% ltt-test-2.1.1 ltt-test-2.1.1 [.] __event_probe__sample_component___trace1
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] DoSyscall
0.00% ltt-test-2.1.1 libc-2.15.so [.] memcpy
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] tp_rcu_read_lock_bp
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] tp_rcu_read_unlock_bp
0.00% ltt-test-2.1.1 libc-2.15.so [.] sched_getcpu
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] __tracepoint_cb_sample_component___trace1
0.00% ltt-test-2.1.1 liburcu-bp.so.1.0.0 [.] rcu_read_unlock_bp
0.00% ltt-test-2.1.1 [vdso] [.] 0x001003b0
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] lib_ring_buffer_align_ctx
0.00% ltt-test-2.1.1 liburcu-bp.so.1.0.0 [.] rcu_read_lock_bp
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _raw_spin_unlock_irqrestore
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] __event_get_size__sample_component___trace1
0.00% ltt-test-2.1.1 libc-2.15.so [.] strlen
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _raw_spin_unlock_irq
0.00% ltt-test-2.1.1 libc-2.15.so [.] 0x0008f5b8
0.00% ltt-test-2.1.1 librt-2.15.so [.] clock_gettime
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] lib_ring_buffer_ctx_init
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] __event_get_align__sample_component___trace1
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] lib_ring_buffer_align
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sys_getcpu
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] main
0.00% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] _init
0.00% ltt-test-2.1.1 libc-2.15.so [.] memset
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] 0x00002ce0
0.00% ltt-test-2.1.1 liburcu-bp.so.1.0.0 [.] 0x00003fe0
0.00% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] lib_ring_buffer_reserve_slow
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] syscall_dotrace_cont
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] do_resched
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] cds_list_empty
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_spin_unlock
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] call___do_global_ctors_aux
0.00% ltt-test-2.1.1 libpthread-2.15.so [.] pthread_sigmask
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pipe_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_spin_lock
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] tp_rcu_dereference_sym_bp
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] migrate_enable
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sys_rt_sigprocmask
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sigprocmask
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] fput
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_mutex_lock
0.00% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] lib_ring_buffer_offset_address
0.00% ltt-test-2.1.1 libc-2.15.so [.] __sysconf
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pin_current_cpu
0.00% ltt-test-2.1.1 libpthread-2.15.so [.] 0x0000f8bc
0.00% ltt-test-2.1.1 libc-2.15.so [.] __getpagesize
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] ret_from_syscall
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __set_task_blocked
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] try_to_wake_up
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __rcu_read_unlock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pipe_iov_copy_from_user
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __schedule
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] preempt_schedule_irq
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __wake_up_common
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] migrate_disable
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] vfs_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] fget_light
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] iov_fault_in_pages_read
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] fsnotify
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_mutex_unlock
0.00% ltt-test-2.1.1 libpthread-2.15.so [.] write
0.00% ltt-test-2.1.1 libc-2.15.so [.] sigpending
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] unpin_current_cpu
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] recalc_sigpending
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] finish_task_switch
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pagefault_disable
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __d_lookup_rcu
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] file_update_time
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] mnt_want_write
0.00% ltt-test-2.1.1 libc-2.15.so [.] sigemptyset
0.00% ltt-test-2.1.1 libc-2.15.so [.] sigaddset
0.00% ltt-test-2.1.1 libc-2.15.so [.] sigismember
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] current_fs_time
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] recalc_sigpending_tsk
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] set_current_blocked
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] do_sigpending
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __srcu_read_unlock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __srcu_read_lock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] current_kernel_time
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __perf_sw_event
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pagefault_enable
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] mem_cgroup_charge_common
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rw_verify_area
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sys_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] kill_fasync
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pollwake
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _mutex_lock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _mutex_unlock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _raw_spin_lock_irq


# Events: 3K task-clock
#
# Overhead Command Shared Object Symbol
# ........ .............. ................................ ................................................
#
24.98% ltt-test-2.1.1 [kernel.kallsyms] [k] __copy_tofrom_user
24.98% ltt-test-2.1.1 ld-2.15.so [.] 0x00019d0c
24.98% ltt-test-2.1.1 libc-2.15.so [.] __libc_calloc
24.98% ltt-test-2.1.1 [kernel.kallsyms] [k] schedule
0.03% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] 0x0001dd68
0.01% ltt-test-2.1.1 ld-2.15.so [.] __tls_get_addr
0.01% ltt-test-2.1.1 ltt-test-2.1.1 [.] __event_probe__sample_component___trace1
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] DoSyscall
0.00% ltt-test-2.1.1 libc-2.15.so [.] sched_getcpu
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] __tracepoint_cb_sample_component___trace1
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] tp_rcu_read_lock_bp
0.00% ltt-test-2.1.1 libc-2.15.so [.] memcpy
0.00% ltt-test-2.1.1 liburcu-bp.so.1.0.0 [.] rcu_read_unlock_bp
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] tp_rcu_read_unlock_bp
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] lib_ring_buffer_align_ctx
0.00% ltt-test-2.1.1 [vdso] [.] 0x00100520
0.00% ltt-test-2.1.1 liburcu-bp.so.1.0.0 [.] rcu_read_lock_bp
0.00% ltt-test-2.1.1 libc-2.15.so [.] strlen
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] __event_get_size__sample_component___trace1
0.00% ltt-test-2.1.1 librt-2.15.so [.] clock_gettime
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] lib_ring_buffer_align
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] lib_ring_buffer_ctx_init
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _raw_spin_unlock_irq
0.00% ltt-test-2.1.1 libc-2.15.so [.] 0x0008f510
0.00% ltt-test-2.1.1 libc-2.15.so [.] memset
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] __event_get_align__sample_component___trace1
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] main
0.00% ltt-test-2.1.1 liburcu-bp.so.1.0.0 [.] 0x00003fe0
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _raw_spin_unlock_irqrestore
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sys_getcpu
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] syscall_dotrace_cont
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] cds_list_empty
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] 0x00002ce8
0.00% ltt-test-2.1.1 liblttng-ust-tracepoint.so.0.0.0 [.] tp_rcu_dereference_sym_bp
0.00% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] _init
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] do_resched
0.00% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] lib_ring_buffer_reserve_slow
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_spin_lock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] migrate_enable
0.00% ltt-test-2.1.1 libpthread-2.15.so [.] pthread_sigmask
0.00% ltt-test-2.1.1 libc-2.15.so [.] __sysconf
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pipe_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_spin_unlock
0.00% ltt-test-2.1.1 ltt-test-2.1.1 [.] call___do_global_ctors_aux
0.00% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] lib_ring_buffer_offset_address
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] preempt_schedule_irq
0.00% ltt-test-2.1.1 libc-2.15.so [.] sigpending
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] ret_from_syscall
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sigprocmask
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sys_rt_sigprocmask
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] vfs_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] fput
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_mutex_lock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _raw_spin_lock_irq
0.00% ltt-test-2.1.1 libpthread-2.15.so [.] 0x0000f810
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pin_current_cpu
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] current_fs_time
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __set_task_blocked
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __rcu_read_unlock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __d_lookup_rcu
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] mnt_want_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] fsnotify
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __schedule
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rt_mutex_unlock
0.00% ltt-test-2.1.1 libpthread-2.15.so [.] write
0.00% ltt-test-2.1.1 libc-2.15.so [.] __getpagesize
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] unpin_current_cpu
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] recalc_sigpending_tsk
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] recalc_sigpending
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __wake_up_common
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] finish_task_switch
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] current_kernel_time
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __perf_sw_event
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pagefault_enable
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] mem_cgroup_charge_common
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] do_sync_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] rw_verify_area
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] sys_write
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] fget_light
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] iov_fault_in_pages_read
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] pipe_iov_copy_from_user
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] file_update_time
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __fsnotify_parent
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] _mutex_lock
0.00% ltt-test-2.1.1 [kernel.kallsyms] [k] __raw_spin_lock_irqsave


# Events: 21 page-faults
#
# Overhead Command Shared Object Symbol
# ........ .............. ..................... ..................................
#
68.20% ltt-test-2.1.1 ld-2.15.so [.] 0x00017774
12.97% ltt-test-2.1.1 librt-2.15.so [.] 0x000061c0
5.22% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] lib_ring_buffer_backend_create
5.22% ltt-test-2.1.1 libc-2.15.so [.] 0x00086230
5.06% ltt-test-2.1.1 libgcc_s.so.1 [.] init_dwarf_reg_size_table
1.90% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] 0x0000c19c
0.32% ltt-test-2.1.1 [kernel.kallsyms] [k] __clear_user
0.16% ltt-test-2.1.1 perf [.] perf_evlist__prepare_workload
0.16% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] ustcomm_connect_unix_sock
0.16% ltt-test-2.1.1 libc-2.15.so [.] _setjmp
0.16% ltt-test-2.1.1 libc-2.15.so [.] strchr
0.16% ltt-test-2.1.1 libc-2.15.so [.] execvpe
0.16% ltt-test-2.1.1 libc-2.15.so [.] socket
0.16% ltt-test-2.1.1 [kernel.kallsyms] [k] __copy_tofrom_user


# Events: 17 minor-faults
#
# Overhead Command Shared Object Symbol
# ........ .............. ..................... .................................
#
58.86% ltt-test-2.1.1 ld-2.15.so [.] 0x00017774
37.27% ltt-test-2.1.1 libc-2.15.so [.] 0x000df1cc
2.65% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] 0x0000c19c
0.20% ltt-test-2.1.1 perf [.] perf_evlist__prepare_workload
0.20% ltt-test-2.1.1 liblttng-ust.so.0.0.0 [.] ustcomm_connect_unix_sock
0.20% ltt-test-2.1.1 libc-2.15.so [.] _setjmp
0.20% ltt-test-2.1.1 libc-2.15.so [.] strchr
0.20% ltt-test-2.1.1 libc-2.15.so [.] execvpe
0.20% ltt-test-2.1.1 libc-2.15.so [.] socket


# Events: 0 major-faults
#
# Overhead Command Shared Object Symbol
# ........ ....... ............. ......
#


# Events: 2K context-switches
#
# Overhead Command Shared Object Symbol
# ........ .............. ................. ........................
#
85.80% ltt-test-2.1.1 [kernel.kallsyms] [k] schedule
14.04% ltt-test-2.1.1 [kernel.kallsyms] [k] preempt_schedule_irq
0.16% ltt-test-2.1.1 [kernel.kallsyms] [k] preempt_schedule


# Events: 3 CPU-migrations
#
# Overhead Command Shared Object Symbol
# ........ .............. ................. ....................
#
50.00% ltt-test-2.1.1 [kernel.kallsyms] [k] wake_up_new_task
50.00% ltt-test-2.1.1 [kernel.kallsyms] [k] move_task


# Events: 0 alignment-faults
#
# Overhead Command Shared Object Symbol
# ........ ....... ............. ......
#


# Events: 2 emulation-faults
#
# Overhead Command Shared Object Symbol
# ........ .............. ............. .....................
#
50.00% ltt-test-2.1.1 libc-2.15.so [.] __libc_start_main
50.00% ltt-test-2.1.1 ld-2.15.so [.] 0x000154b4


#
# (For a higher level overview, try: perf report --sort comm,dso)
#
(5-5/7)