# ======== # captured on: Tue Jan 29 15:37:00 2013 # hostname : phenom # os release : 3.8.0-rc4+ # perf version : 3.8.rc5.g6a61671 # arch : x86_64 # nrcpus online : 4 # nrcpus avail : 4 # cpudesc : AMD Phenom(tm) 9600 Quad-Core Processor # cpuid : AuthenticAMD,16,2,2 # total memory : 3412972 kB # cmdline : /home/fweisbec/linux-2.6-tip/tools/perf/perf record -m 1024 -g -e cputime:* /home/fweisbec/kernelspin 10 # event : name = cputime:account_system, type = 2, config = 0xfc, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, excl_host = 0, excl_guest = 1, precise_ip = 0, id = { 5, 6, 7, 8 } # event : name = cputime:account_user, type = 2, config = 0xfd, config1 = 0x0, config2 = 0x0, excl_usr = 0, excl_kern = 0, excl_host = 0, excl_guest = 1, precise_ip = 0, id = { 9, 10, 11, 12 } # HEADER_CPU_TOPOLOGY info available, use -I to display # HEADER_NUMA_TOPOLOGY info available, use -I to display # pmu mappings: cpu = 4, software = 1, tracepoint = 2, ibs_fetch = 6, ibs_op = 7, breakpoint = 5 # ======== # # Samples: 1M of event 'cputime:account_system' # Event count (approx.): 4075440452 # # Overhead Command Shared Object Symbol # ........ .......... ................. ............................. # 100.00% kernelspin [kernel.kallsyms] [k] perf_trace_account_system | --- perf_trace_account_system account_system_time __vtime_account_system | |--96.57%-- vtime_user_enter | user_enter | | | |--99.99%-- syscall_trace_leave | | int_check_syscall_exit_work | | | | | |--99.99%-- brk | | | | | | | --100.00%-- __libc_start_main | | --0.01%-- [...] | --0.01%-- [...] | |--2.83%-- vtime_account_irq_exit | | | |--59.92%-- __do_softirq | | call_softirq | | do_softirq | | irq_exit | | | | | |--82.63%-- do_IRQ | | | common_interrupt | | | | | | | |--33.63%-- syscall_trace_leave | | | | int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | |--32.47%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--5.86%-- brk | | | | | | | | | --100.00%-- __libc_start_main | | | | | | | |--5.14%-- do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--3.55%-- sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--2.97%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--2.28%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--2.05%-- trace_preempt_on | | | | sub_preempt_count | | | | | | | | | |--76.79%-- perf_event_mmap | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | |--18.17%-- __percpu_counter_add | | | | | __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --5.04%-- lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--2.04%-- sub_preempt_count | | | | | | | | | |--61.80%-- perf_event_mmap | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | |--19.20%-- __percpu_counter_add | | | | | do_munmap | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --19.00%-- lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--1.74%-- add_preempt_count | | | | | | | | | |--49.17%-- __percpu_counter_add | | | | | | | | | | | |--52.53%-- do_munmap | | | | | | sys_brk | | | | | | tracesys | | | | | | brk | | | | | | __libc_start_main | | | | | | | | | | | --47.47%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | |--41.01%-- perf_event_mmap | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --9.82%-- lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--1.54%-- unmap_vmas | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--1.11%-- tlb_gather_mmu | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--0.78%-- main | | | | __libc_start_main | | | | | | | |--0.72%-- free_pgtables | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--0.67%-- int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | |--0.63%-- __rb_insert_augmented | | | | __vma_link_rb | | | | vma_link | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | --2.84%-- [...] | | | | | --17.37%-- smp_apic_timer_interrupt | | apic_timer_interrupt | | | | | |--36.61%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--31.23%-- syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--5.35%-- brk | | | | | | | --100.00%-- __libc_start_main | | | | | |--5.18%-- do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.61%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--2.23%-- sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.46%-- sub_preempt_count | | | | | | | |--85.10%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--7.84%-- lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --7.06%-- __percpu_counter_add | | | | | | | |--57.15%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --42.85%-- __vm_enough_memory | | | selinux_vm_enough_memory | | | security_vm_enough_memory_mm | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.39%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.19%-- add_preempt_count | | | | | | | |--78.86%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--15.03%-- __percpu_counter_add | | | | | | | | | |--59.56%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --40.44%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --6.11%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.96%-- trace_preempt_on | | | sub_preempt_count | | | | | | | |--73.30%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--17.71%-- __percpu_counter_add | | | | | | | | | |--65.21%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --34.79%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --8.99%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.94%-- __vma_link_rb | | | vma_link | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.91%-- remove_vma | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.89%-- tracesys | | | brk | | | __libc_start_main | | | | | |--0.83%-- unmap_vmas | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.68%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.68%-- free_pgtables | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.64%-- unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.60%-- __rb_insert_augmented | | | __vma_link_rb | | | vma_link | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.59%-- main | | | __libc_start_main | | | | | |--0.57%-- int_check_syscall_exit_work | | | brk | | | __libc_start_main | | --3.46%-- [...] | | | --40.08%-- irq_exit | | | |--75.83%-- smp_apic_timer_interrupt | | apic_timer_interrupt | | | | | |--35.59%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--30.69%-- syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--5.42%-- brk | | | | | | | --100.00%-- __libc_start_main | | | | | |--5.02%-- do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.68%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--2.19%-- sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.40%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.39%-- sub_preempt_count | | | | | | | |--84.61%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--7.96%-- __percpu_counter_add | | | | | | | | | |--58.64%-- do_munmap | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --41.36%-- __vm_enough_memory | | | | selinux_vm_enough_memory | | | | security_vm_enough_memory_mm | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --7.44%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.23%-- add_preempt_count | | | | | | | |--78.91%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--14.93%-- __percpu_counter_add | | | | | | | | | |--59.34%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --40.66%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --6.16%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.98%-- __vma_link_rb | | | vma_link | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.97%-- trace_preempt_on | | | sub_preempt_count | | | | | | | |--72.31%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--18.23%-- __percpu_counter_add | | | | | | | | | |--61.36%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --38.64%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --9.46%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.97%-- remove_vma | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.91%-- tracesys | | | brk | | | __libc_start_main | | | | | |--0.85%-- unmap_vmas | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.65%-- unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.63%-- main | | | __libc_start_main | | | | | |--0.61%-- int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--0.60%-- free_pgtables | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.56%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | --5.68%-- [...] | | | |--19.82%-- do_IRQ | | common_interrupt | | | | | |--32.94%-- syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--32.22%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--6.40%-- brk | | | | | | | --100.00%-- __libc_start_main | | | | | |--4.84%-- do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.58%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.22%-- sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--2.90%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--2.10%-- sub_preempt_count | | | | | | | |--66.77%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--16.77%-- lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --16.45%-- __percpu_counter_add | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.94%-- trace_preempt_on | | | sub_preempt_count | | | | | | | |--67.07%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--16.60%-- lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --16.33%-- __percpu_counter_add | | | __vm_enough_memory | | | selinux_vm_enough_memory | | | security_vm_enough_memory_mm | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.75%-- add_preempt_count | | | | | | | |--44.68%-- __percpu_counter_add | | | | | | | | | |--53.01%-- do_munmap | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --46.99%-- __vm_enough_memory | | | | selinux_vm_enough_memory | | | | security_vm_enough_memory_mm | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--42.85%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --12.47%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.74%-- unmap_vmas | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.85%-- tlb_gather_mmu | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.73%-- main | | | __libc_start_main | | | | | |--0.65%-- free_pgtables | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.64%-- __rb_insert_augmented | | | __vma_link_rb | | | vma_link | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.56%-- int_check_syscall_exit_work | | | brk | | | __libc_start_main | | --2.92%-- [...] | | | --4.35%-- smp_irq_work_interrupt | irq_work_interrupt | | | |--58.20%-- syscall_trace_leave | | int_check_syscall_exit_work | | brk | | __libc_start_main | | | |--40.12%-- syscall_trace_enter | | tracesys | | brk | | __libc_start_main | | | |--1.17%-- call_softirq | | do_softirq | | irq_exit | | smp_apic_timer_interrupt | | apic_timer_interrupt | | | | | |--50.98%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | --49.02%-- syscall_trace_enter | | tracesys | | brk | | __libc_start_main | | | --0.51%-- __percpu_counter_add | do_munmap | sys_brk | tracesys | brk | __libc_start_main | --0.60%-- vtime_account_system vtime_account_irq_enter | |--72.11%-- irq_enter | | | |--95.14%-- smp_apic_timer_interrupt | | apic_timer_interrupt | | | | | |--26.69%-- ata_scsi_queuecmd | | | scsi_dispatch_cmd | | | scsi_request_fn | | | __blk_run_queue_uncond | | | __blk_run_queue | | | blk_run_queue | | | scsi_run_queue | | | scsi_next_command | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--34.02%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--29.00%-- syscall_trace_leave | | | | int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | |--9.53%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--4.88%-- tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--4.82%-- unmap_vmas | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--4.80%-- do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--4.69%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--4.63%-- brk | | | | __libc_start_main | | | | | | | --3.63%-- event_filter_match | | | perf_event_mmap_ctx | | | perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--19.60%-- syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--8.50%-- scsi_request_fn | | | __blk_run_queue_uncond | | | __blk_run_queue | | | blk_run_queue | | | scsi_run_queue | | | scsi_next_command | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--32.12%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--29.80%-- syscall_trace_leave | | | | int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | |--23.61%-- brk | | | | | | | | | --100.00%-- __libc_start_main | | | | | | | --14.48%-- sub_preempt_count | | | perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--5.26%-- test_clear_page_writeback | | | end_page_writeback | | | put_io_page | | | ext4_end_bio | | | bio_endio | | | req_bio_endio | | | blk_update_request | | | blk_update_bidi_request | | | blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--37.86%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--19.72%-- syscall_trace_leave | | | | int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | |--11.38%-- brk | | | | __libc_start_main | | | | | | | |--9.39%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--8.08%-- add_preempt_count | | | | lru_add_drain | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--5.58%-- __rb_insert_augmented | | | | __vma_link_rb | | | | vma_link | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--5.42%-- unmap_vmas | | | | unmap_region | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --2.58%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--4.65%-- load_elf_binary | | | search_binary_handler | | | do_execve_common | | | do_execve | | | sys_execve | | | stub_execve | | | execve | | | | | |--4.52%-- do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.78%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--2.38%-- end_page_writeback | | | put_io_page | | | ext4_end_bio | | | bio_endio | | | req_bio_endio | | | blk_update_request | | | blk_update_bidi_request | | | blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--39.08%-- syscall_trace_leave | | | | int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | |--30.91%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--22.41%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --7.60%-- brk | | | __libc_start_main | | | | | |--2.14%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--1.63%-- sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.55%-- put_io_page | | | ext4_end_bio | | | bio_endio | | | req_bio_endio | | | blk_update_request | | | blk_update_bidi_request | | | blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--48.38%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--32.68%-- syscall_trace_leave | | | | int_check_syscall_exit_work | | | | brk | | | | __libc_start_main | | | | | | | --18.95%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.52%-- brk | | | | | | | --100.00%-- __libc_start_main | | | | | |--1.34%-- blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.33%-- sub_preempt_count | | | | | | | |--88.20%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--6.26%-- __percpu_counter_add | | | | | | | | | |--61.61%-- do_munmap | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --38.39%-- __vm_enough_memory | | | | selinux_vm_enough_memory | | | | security_vm_enough_memory_mm | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --5.54%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.24%-- T.749 | | | add_timer_randomness | | | add_disk_randomness | | | blk_update_bidi_request | | | blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--52.04%-- syscall_trace_enter | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --47.96%-- brk | | | __libc_start_main | | | | | |--1.14%-- add_preempt_count | | | | | | | |--82.94%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--12.31%-- __percpu_counter_add | | | | | | | | | |--54.66%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --45.34%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --4.75%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.06%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.90%-- trace_preempt_on | | | sub_preempt_count | | | | | | | |--77.99%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--14.84%-- __percpu_counter_add | | | | | | | | | |--61.09%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --38.91%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --7.17%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.83%-- __vma_link_rb | | | vma_link | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.82%-- remove_vma | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.78%-- bio_endio | | | req_bio_endio | | | blk_update_request | | | blk_update_bidi_request | | | blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | | | | | |--59.92%-- brk | | | | | | | --40.08%-- sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.65%-- scsi_dispatch_cmd | | | scsi_request_fn | | | __blk_run_queue_uncond | | | __blk_run_queue | | | blk_run_queue | | | scsi_run_queue | | | scsi_next_command | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.65%-- unmap_vmas | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.64%-- int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--0.64%-- tracesys | | | brk | | | __libc_start_main | | | | | |--0.60%-- ext4_end_bio | | | bio_endio | | | req_bio_endio | | | blk_update_request | | | blk_update_bidi_request | | | blk_end_bidi_request | | | blk_end_request | | | scsi_io_completion | | | scsi_finish_command | | | scsi_softirq_done | | | blk_done_softirq | | | __do_softirq | | | call_softirq | | | do_softirq | | | irq_exit | | | do_IRQ | | | common_interrupt | | | syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--0.50%-- free_pgtables | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | --4.67%-- [...] | | | |--2.65%-- do_IRQ | | common_interrupt | | | | | |--44.82%-- syscall_trace_enter | | | tracesys | | | brk | | | __libc_start_main | | | | | |--8.96%-- do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--7.18%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--5.58%-- sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--4.42%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--4.13%-- sub_preempt_count | | | | | | | |--74.15%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--13.93%-- __percpu_counter_add | | | | do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --11.92%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.58%-- trace_preempt_on | | | sub_preempt_count | | | | | | | |--74.15%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--13.13%-- __percpu_counter_add | | | | __vm_enough_memory | | | | selinux_vm_enough_memory | | | | security_vm_enough_memory_mm | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --12.72%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.17%-- add_preempt_count | | | | | | | |--52.91%-- perf_event_mmap | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | |--35.90%-- __percpu_counter_add | | | | | | | | | |--53.38%-- __vm_enough_memory | | | | | selinux_vm_enough_memory | | | | | security_vm_enough_memory_mm | | | | | do_brk | | | | | sys_brk | | | | | tracesys | | | | | brk | | | | | __libc_start_main | | | | | | | | | --46.62%-- do_munmap | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --11.19%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--3.08%-- brk | | | | | |--2.69%-- unmap_vmas | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--2.61%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--1.50%-- tlb_gather_mmu | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.37%-- int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | |--1.11%-- __rb_insert_augmented | | | __vma_link_rb | | | vma_link | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--1.11%-- free_pgtables | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.76%-- event_filter_match | | | perf_event_mmap_ctx | | | perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.70%-- perf_event_mmap_ctx | | | perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.64%-- trace_preempt_off | | | add_preempt_count | | | __percpu_counter_add | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.60%-- __percpu_counter_add | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.53%-- remove_vma | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.52%-- _cond_resched | | | unmap_single_vma | | | unmap_vmas | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--0.50%-- vma_rb_erase | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | --0.44%-- [...] | | | --2.21%-- smp_irq_work_interrupt | irq_work_interrupt | | | |--97.79%-- syscall_trace_enter | | tracesys | | brk | | __libc_start_main | | | |--1.22%-- call_softirq | | do_softirq | | irq_exit | | smp_apic_timer_interrupt | | apic_timer_interrupt | | | | | |--61.97%-- syscall_trace_leave | | | int_check_syscall_exit_work | | | brk | | | __libc_start_main | | | | | --38.03%-- syscall_trace_enter | | tracesys | | brk | | __libc_start_main | | | --1.00%-- __percpu_counter_add | do_munmap | sys_brk | tracesys | brk | __libc_start_main | --27.89%-- __do_softirq call_softirq do_softirq irq_exit | |--94.65%-- smp_apic_timer_interrupt | apic_timer_interrupt | | | |--49.62%-- syscall_trace_enter | | tracesys | | brk | | __libc_start_main | | | |--8.54%-- do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--6.46%-- perf_event_mmap | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--3.62%-- sys_brk | | tracesys | | brk | | __libc_start_main | | | |--3.35%-- syscall_trace_leave | | int_check_syscall_exit_work | | brk | | __libc_start_main | | | |--2.47%-- sub_preempt_count | | | | | |--83.42%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--9.00%-- lru_add_drain | | | unmap_region | | | do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --7.59%-- __percpu_counter_add | | | | | |--55.07%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --44.93%-- __vm_enough_memory | | selinux_vm_enough_memory | | security_vm_enough_memory_mm | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--2.40%-- do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--2.22%-- brk | | | | | --100.00%-- __libc_start_main | | | |--2.14%-- add_preempt_count | | | | | |--79.00%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--15.15%-- __percpu_counter_add | | | | | | | |--61.39%-- __vm_enough_memory | | | | selinux_vm_enough_memory | | | | security_vm_enough_memory_mm | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --38.61%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --5.85%-- lru_add_drain | | unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--1.74%-- trace_preempt_on | | sub_preempt_count | | | | | |--72.01%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | |--18.33%-- __percpu_counter_add | | | | | | | |--63.07%-- __vm_enough_memory | | | | selinux_vm_enough_memory | | | | security_vm_enough_memory_mm | | | | do_brk | | | | sys_brk | | | | tracesys | | | | brk | | | | __libc_start_main | | | | | | | --36.93%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --9.66%-- lru_add_drain | | unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--1.72%-- remove_vma | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--1.66%-- __vma_link_rb | | vma_link | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--1.50%-- unmap_vmas | | unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--1.50%-- tracesys | | brk | | __libc_start_main | | | |--1.11%-- unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--1.06%-- free_pgtables | | unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--0.94%-- lru_add_drain | | unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--0.94%-- int_check_syscall_exit_work | | brk | | __libc_start_main | | | |--0.86%-- __rb_insert_augmented | | __vma_link_rb | | vma_link | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--0.77%-- perf_event_mmap_ctx | | perf_event_mmap | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--0.73%-- __percpu_counter_add | | | | | |--67.03%-- __vm_enough_memory | | | selinux_vm_enough_memory | | | security_vm_enough_memory_mm | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --32.97%-- do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--0.68%-- trace_preempt_off | | add_preempt_count | | | | | |--77.33%-- perf_event_mmap | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --22.67%-- __percpu_counter_add | | | | | |--53.36%-- __vm_enough_memory | | | selinux_vm_enough_memory | | | security_vm_enough_memory_mm | | | do_brk | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --46.64%-- do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--0.57%-- cred_has_capability | | selinux_capable | | selinux_vm_enough_memory | | security_vm_enough_memory_mm | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | --3.38%-- [...] | --5.35%-- do_IRQ common_interrupt | |--49.68%-- syscall_trace_enter | tracesys | brk | __libc_start_main | |--7.82%-- do_brk | sys_brk | tracesys | brk | __libc_start_main | |--5.91%-- perf_event_mmap | do_brk | sys_brk | tracesys | brk | __libc_start_main | |--5.28%-- do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--4.76%-- sys_brk | tracesys | brk | __libc_start_main | |--3.77%-- sub_preempt_count | | | |--69.53%-- perf_event_mmap | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--15.90%-- lru_add_drain | | unmap_region | | do_munmap | | sys_brk | | tracesys | | brk | | __libc_start_main | | | --14.57%-- __percpu_counter_add | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--3.21%-- add_preempt_count | | | |--50.12%-- perf_event_mmap | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--35.64%-- __percpu_counter_add | | | | | |--51.17%-- do_munmap | | | sys_brk | | | tracesys | | | brk | | | __libc_start_main | | | | | --48.83%-- __vm_enough_memory | | selinux_vm_enough_memory | | security_vm_enough_memory_mm | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | --14.24%-- lru_add_drain | unmap_region | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--3.02%-- trace_preempt_on | sub_preempt_count | | | |--62.98%-- perf_event_mmap | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | |--19.01%-- __percpu_counter_add | | __vm_enough_memory | | selinux_vm_enough_memory | | security_vm_enough_memory_mm | | do_brk | | sys_brk | | tracesys | | brk | | __libc_start_main | | | --18.01%-- lru_add_drain | unmap_region | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--2.79%-- unmap_vmas | unmap_region | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--2.53%-- brk | |--1.90%-- syscall_trace_leave | int_check_syscall_exit_work | brk | __libc_start_main | |--1.56%-- tlb_gather_mmu | unmap_region | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--1.21%-- free_pgtables | unmap_region | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--0.99%-- int_check_syscall_exit_work | brk | __libc_start_main | |--0.95%-- __rb_insert_augmented | __vma_link_rb | vma_link | do_brk | sys_brk | tracesys | brk | __libc_start_main | |--0.72%-- remove_vma | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--0.64%-- perf_event_mmap_ctx | perf_event_mmap | do_brk | sys_brk | tracesys | brk | __libc_start_main | |--0.62%-- _cond_resched | unmap_single_vma | unmap_vmas | unmap_region | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--0.61%-- event_filter_match | perf_event_mmap_ctx | perf_event_mmap | do_brk | sys_brk | tracesys | brk | __libc_start_main | |--0.59%-- vma_rb_erase | do_munmap | sys_brk | tracesys | brk | __libc_start_main | |--0.54%-- tracesys | brk | __libc_start_main --0.91%-- [...] # Samples: 1M of event 'cputime:account_user' # Event count (approx.): 2426285158 # # Overhead Command Shared Object Symbol # ........ .......... ................. ........................... # 100.00% kernelspin [kernel.kallsyms] [k] perf_trace_account_user | --- perf_trace_account_user account_user_time vtime_account_user | |--99.62%-- user_exit | | | |--99.98%-- syscall_trace_enter | | tracesys | | | | | |--100.00%-- brk | | | | | | | --100.00%-- __libc_start_main | | --0.00%-- [...] | --0.02%-- [...] --0.38%-- [...] # # (For a higher level overview, try: perf report --sort comm,dso) #