Thu Aug 2 18:36:39 EEST 2007 + opcontrol --vmlinux=/usr/src/linux-2.6.22-ARCH/vmlinux + opcontrol --start Using default event: CPU_CLK_UNHALTED:100000:0:1:1 Daemon started. Profiler running. + sleep 5 + opcontrol --shutdown Stopping profiling. Killing daemon. + echo + echo + echo + opreport CPU: PIII, speed 798.017 MHz (estimated) Counted CPU_CLK_UNHALTED events (clocks processor is not halted) with a unit mask of 0x00 (No unit mask) count 100000 CPU_CLK_UNHALT...| samples| %| ------------------ 2970 61.0734 vmlinux 577 11.8651 libc-2.6.so 450 9.2535 ide_core 399 8.2048 ld-2.6.so 244 5.0175 bash 46 0.9459 ISO8859-1.so 28 0.5758 ext3 21 0.4318 jbd 20 0.4113 grep 11 0.2262 processor 11 0.2262 imap-login CPU_CLK_UNHALT...| samples| %| ------------------ 10 90.9091 imap-login 1 9.0909 anon (tgid:3941 range:0xb7fd9000-0xb7fda000) 10 0.2056 oprofile 9 0.1851 ide_disk 8 0.1645 badblocks CPU_CLK_UNHALT...| samples| %| ------------------ 5 62.5000 badblocks 3 37.5000 anon (tgid:5590 range:0xb7ef6000-0xb7ef7000) 6 0.1234 gawk 5 0.1028 skge 5 0.1028 ophelp 5 0.1028 libcrypto.so.0.9.8 5 0.1028 libpopt.so.0.0.0 5 0.1028 dovecot 4 0.0823 libext2fs.so.2.4 4 0.0823 reiserfs 3 0.0617 libpcre.so.0.0.1 3 0.0617 dovecot-auth 2 0.0411 libncurses.so.5.6 2 0.0411 screen-4.0.3 2 0.0411 libnetsnmp.so.15.0.0 2 0.0411 locale-archive 1 0.0206 tr 1 0.0206 libreadline.so.5.2 1 0.0206 librt-2.6.so 1 0.0206 libssl.so.0.9.8 1 0.0206 imap CPU_CLK_UNHALT...| samples| %| ------------------ 1 100.000 anon (tgid:4125 range:0xb7f99000-0xb7f9a000) 1 0.0206 sshd + echo + echo + echo + opreport -l /usr/src/linux-2.6.22-ARCH/vmlinux CPU: PIII, speed 798.017 MHz (estimated) Counted CPU_CLK_UNHALTED events (clocks processor is not halted) with a unit mask of 0x00 (No unit mask) count 100000 samples % symbol name 471 15.8586 acpi_pm_read 416 14.0067 schedule 192 6.4646 __switch_to 111 3.7374 do_wp_page 77 2.5926 find_next_bit 71 2.3906 __handle_mm_fault 65 2.1886 __blockdev_direct_IO 64 2.1549 dequeue_task 49 1.6498 delay_tsc 43 1.4478 unmap_vmas 40 1.3468 do_page_fault 39 1.3131 get_page_from_freelist 30 1.0101 follow_page 30 1.0101 page_fault 29 0.9764 mask_and_ack_8259A 28 0.9428 filemap_nopage 27 0.9091 native_load_tls 24 0.8081 blk_rq_map_sg 24 0.8081 find_get_page 23 0.7744 __link_path_walk 23 0.7744 page_address 20 0.6734 find_vma 19 0.6397 kmem_cache_free 19 0.6397 put_page 18 0.6061 enable_8259A_irq 18 0.6061 radix_tree_lookup 17 0.5724 dio_bio_submit 17 0.5724 strnlen_user 16 0.5387 kmem_cache_alloc 15 0.5051 sysenter_past_esp 14 0.4714 copy_process 13 0.4377 __generic_file_aio_write_nolock 13 0.4377 permission 12 0.4040 __alloc_pages 12 0.4040 __mutex_lock_slowpath 12 0.4040 current_fs_time 12 0.4040 do_mmap_pgoff 12 0.4040 get_user_pages 11 0.3704 generic_make_request 10 0.3367 __d_lookup 10 0.3367 copy_page_range 10 0.3367 find_busiest_group 10 0.3367 kmem_cache_zalloc 9 0.3030 do_lookup 9 0.3030 generic_file_direct_IO 9 0.3030 load_elf_binary 9 0.3030 memcpy 9 0.3030 restore_nocheck 8 0.2694 file_update_time 8 0.2694 inotify_inode_queue_event 7 0.2357 __copy_to_user_ll 7 0.2357 _spin_lock_irqsave 7 0.2357 block_llseek 7 0.2357 deactivate_task 7 0.2357 do_exit 7 0.2357 flush_tlb_page 7 0.2357 generic_unplug_device 7 0.2357 irq_entries_start 7 0.2357 up_read 6 0.2020 __fput 6 0.2020 __make_request 6 0.2020 acpi_os_read_port 6 0.2020 bio_alloc_bioset 6 0.2020 cache_reap 6 0.2020 do_generic_mapping_read 6 0.2020 native_flush_tlb_single 6 0.2020 sys_mprotect 5 0.1684 __add_entropy_words 5 0.1684 __bio_add_page 5 0.1684 copy_strings 5 0.1684 do_path_lookup 5 0.1684 generic_permission 5 0.1684 lru_cache_add_active 5 0.1684 number 5 0.1684 proc_sys_lookup_table_one 5 0.1684 vfs_write 5 0.1684 vm_normal_page 5 0.1684 vm_stat_account 4 0.1347 __kmalloc 4 0.1347 __mutex_unlock_slowpath 4 0.1347 bio_add_page 4 0.1347 blk_backing_dev_unplug 4 0.1347 cpu_idle 4 0.1347 dio_bio_add_page 4 0.1347 dio_bio_complete 4 0.1347 dio_get_page 4 0.1347 do_munmap 4 0.1347 do_sys_poll 4 0.1347 fget_light 4 0.1347 file_read_actor 4 0.1347 filemap_write_and_wait 4 0.1347 find_vma_prepare 4 0.1347 getname 4 0.1347 kernel_read 4 0.1347 notifier_call_chain 4 0.1347 percpu_counter_mod 4 0.1347 preempt_schedule 4 0.1347 rb_first 4 0.1347 secure_ip_id 4 0.1347 strncpy_from_user 4 0.1347 sys_mmap2 3 0.1010 __blocking_notifier_call_chain 3 0.1010 __dentry_open 3 0.1010 __find_get_block 3 0.1010 __find_get_block_slow 3 0.1010 __get_user_4 3 0.1010 __mark_inode_dirty 3 0.1010 __mod_timer 3 0.1010 __pte_alloc 3 0.1010 __vm_enough_memory 3 0.1010 __wake_up_bit 3 0.1010 _atomic_dec_and_lock 3 0.1010 anon_vma_prepare 3 0.1010 blk_remove_plug 3 0.1010 cfq_dispatch_requests 3 0.1010 clear_user 3 0.1010 cond_resched 3 0.1010 copy_from_user 3 0.1010 copy_to_user 3 0.1010 debug_mutex_add_waiter 3 0.1010 dio_cleanup 3 0.1010 dnotify_parent 3 0.1010 do_fork 3 0.1010 do_wait 3 0.1010 dummy_vm_enough_memory 3 0.1010 elv_dispatch_sort 3 0.1010 flush_tlb_mm 3 0.1010 generic_file_direct_write 3 0.1010 getnstimeofday 3 0.1010 lock_timer_base 3 0.1010 may_expand_vm 3 0.1010 mod_timer 3 0.1010 need_resched 3 0.1010 page_cache_readahead 3 0.1010 page_remove_rmap 3 0.1010 path_walk 3 0.1010 pit_next_event 3 0.1010 preempt_schedule_irq 3 0.1010 rb_erase 3 0.1010 restore_all 3 0.1010 should_remove_suid 3 0.1010 submit_page_section 3 0.1010 sys_close 3 0.1010 unmap_region 3 0.1010 vma_link 3 0.1010 vma_prio_tree_add 3 0.1010 vsnprintf 3 0.1010 wake_up_new_task 2 0.0673 __atomic_notifier_call_chain 2 0.0673 __copy_user_intel 2 0.0673 __dec_zone_state 2 0.0673 __do_softirq 2 0.0673 __first_cpu 2 0.0673 __generic_unplug_device 2 0.0673 __inc_zone_page_state 2 0.0673 __inc_zone_state 2 0.0673 add_timer_randomness 2 0.0673 alloc_inode 2 0.0673 blk_do_ordered 2 0.0673 blk_queue_bounce 2 0.0673 blk_recount_segments 2 0.0673 blkdev_direct_IO 2 0.0673 cache_alloc_refill 2 0.0673 cfq_add_rq_rb 2 0.0673 cfq_insert_request 2 0.0673 cfq_remove_request 2 0.0673 debug_mutex_lock_common 2 0.0673 del_timer 2 0.0673 detach_pid 2 0.0673 do_IRQ 2 0.0673 do_select 2 0.0673 do_sync_read 2 0.0673 do_sync_write 2 0.0673 drain_array 2 0.0673 dummy_file_mmap 2 0.0673 elf_map 2 0.0673 elv_completed_request 2 0.0673 elv_insert 2 0.0673 error_code 2 0.0673 file_ra_state_init 2 0.0673 find_extend_vma 2 0.0673 find_mergeable_anon_vma 2 0.0673 free_hot_cold_page 2 0.0673 free_pgtables 2 0.0673 generic_segment_checks 2 0.0673 get_next_timer_interrupt 2 0.0673 get_nr_files 2 0.0673 get_unmapped_area 2 0.0673 hrtimer_interrupt 2 0.0673 idle_cpu 2 0.0673 internal_add_timer 2 0.0673 io_schedule 2 0.0673 ip_append_data 2 0.0673 kmap_atomic 2 0.0673 kmap_atomic_prot 2 0.0673 ktime_get_ts 2 0.0673 link_path_walk 2 0.0673 locks_remove_flock 2 0.0673 max_block 2 0.0673 mempool_alloc 2 0.0673 mempool_free 2 0.0673 mutex_remove_waiter 2 0.0673 nameidata_to_filp 2 0.0673 new_inode 2 0.0673 open_namei 2 0.0673 pipe_read 2 0.0673 prio_tree_insert 2 0.0673 rb_insert_color 2 0.0673 rcu_pending 2 0.0673 remove_suid 2 0.0673 resume_userspace 2 0.0673 rw_verify_area 2 0.0673 sched_clock 2 0.0673 scheduler_tick 2 0.0673 special_mapping_nopage 2 0.0673 split_vma 2 0.0673 submit_bio 2 0.0673 sys_llseek 2 0.0673 sys_rt_sigaction 2 0.0673 sys_write 2 0.0673 syscall_exit 2 0.0673 sysctl_head_next 2 0.0673 system_call 2 0.0673 timespec_trunc 2 0.0673 touch_atime 2 0.0673 vfs_fstat 2 0.0673 vfs_read 2 0.0673 vma_merge 2 0.0673 vma_prio_tree_insert 2 0.0673 xrlim_allow 1 0.0337 I_BDEV 1 0.0337 __brelse 1 0.0337 __do_page_cache_readahead 1 0.0337 __end_that_request_first 1 0.0337 __free_pages 1 0.0337 __getblk 1 0.0337 __init_rwsem 1 0.0337 __ip_route_output_key 1 0.0337 __kfree_skb 1 0.0337 __lru_add_drain 1 0.0337 __page_set_anon_rmap 1 0.0337 __pagevec_lru_add_active 1 0.0337 __pollwait 1 0.0337 __rcu_process_callbacks 1 0.0337 __udp4_lib_rcv 1 0.0337 __vma_link 1 0.0337 __vma_link_rb 1 0.0337 __writeback_single_inode 1 0.0337 acpi_hw_register_read 1 0.0337 acpi_os_write_port 1 0.0337 add_wait_queue 1 0.0337 anon_pipe_buf_release 1 0.0337 anon_vma_unlink 1 0.0337 arch_get_unmapped_area_topdown 1 0.0337 arch_pick_mmap_layout 1 0.0337 arch_setup_additional_pages 1 0.0337 bio_get_nr_vecs 1 0.0337 blkdev_get_blocks 1 0.0337 blockable_page_cache_readahead 1 0.0337 can_share_swap_page 1 0.0337 cfq_choose_req 1 0.0337 cfq_cic_rb_lookup 1 0.0337 cfq_completed_request 1 0.0337 cfq_init_prio_data 1 0.0337 cfq_queue_empty 1 0.0337 cfq_service_tree_add 1 0.0337 cfq_set_request 1 0.0337 check_userspace 1 0.0337 clear_inode 1 0.0337 clockevents_program_event 1 0.0337 copy_thread_group_keys 1 0.0337 count 1 0.0337 cp_new_stat64 1 0.0337 d_alloc 1 0.0337 d_callback 1 0.0337 d_hash_and_lookup 1 0.0337 debug_mutex_free_waiter 1 0.0337 debug_mutex_unlock 1 0.0337 dev_queue_xmit 1 0.0337 disk_round_stats 1 0.0337 do_notify_parent 1 0.0337 do_notify_resume 1 0.0337 do_softirq 1 0.0337 do_sys_open 1 0.0337 down_read_trylock 1 0.0337 dummy_bprm_set_security 1 0.0337 dummy_capable 1 0.0337 dummy_inode_getattr 1 0.0337 dummy_task_wait 1 0.0337 dup_fd 1 0.0337 end_that_request_last 1 0.0337 enqueue_hrtimer 1 0.0337 exit_aio 1 0.0337 exit_sem 1 0.0337 fd_install 1 0.0337 flock64_to_posix_lock 1 0.0337 flush_old_exec 1 0.0337 fn_hash_lookup 1 0.0337 fput 1 0.0337 free_block 1 0.0337 free_pgd_range 1 0.0337 generic_drop_inode 1 0.0337 generic_file_aio_read 1 0.0337 generic_file_llseek 1 0.0337 generic_fillattr 1 0.0337 get_dcookie 1 0.0337 get_empty_filp 1 0.0337 get_request 1 0.0337 get_unused_fd 1 0.0337 handle_level_irq 1 0.0337 hrtimer_try_to_cancel 1 0.0337 icmp_send 1 0.0337 inode_has_buffers 1 0.0337 install_special_mapping 1 0.0337 ip_push_pending_frames 1 0.0337 iput 1 0.0337 key_put 1 0.0337 kfree 1 0.0337 kfree_skb 1 0.0337 kfree_skbmem 1 0.0337 ksoftirqd 1 0.0337 kthread_should_stop 1 0.0337 local_bh_enable_ip 1 0.0337 lock_hrtimer_base 1 0.0337 lock_sock_nested 1 0.0337 lookup_mnt 1 0.0337 mark_page_accessed 1 0.0337 may_open 1 0.0337 mempool_alloc_slab 1 0.0337 mm_release 1 0.0337 msecs_to_jiffies 1 0.0337 native_flush_tlb 1 0.0337 native_io_delay 1 0.0337 native_load_esp0 1 0.0337 native_set_pte_at 1 0.0337 netif_receive_skb 1 0.0337 padzero 1 0.0337 page_add_file_rmap 1 0.0337 pfifo_fast_enqueue 1 0.0337 pipe_poll 1 0.0337 pipe_release 1 0.0337 pipe_write 1 0.0337 prepare_binprm 1 0.0337 proc_flush_task 1 0.0337 profile_pc 1 0.0337 profile_tick 1 0.0337 pty_close 1 0.0337 put_files_struct 1 0.0337 put_pid 1 0.0337 quicklist_trim 1 0.0337 raise_softirq 1 0.0337 rb_next 1 0.0337 rb_prev 1 0.0337 rcu_needs_cpu 1 0.0337 rcu_start_batch 1 0.0337 recalc_sigpending_tsk 1 0.0337 recalc_task_prio 1 0.0337 release_pages 1 0.0337 release_vm86_irqs 1 0.0337 remove_vma 1 0.0337 restore_sigcontext 1 0.0337 ret_from_exception 1 0.0337 run_local_timers 1 0.0337 schedule_delayed_work 1 0.0337 set_binfmt 1 0.0337 setup_arg_pages 1 0.0337 show_stat 1 0.0337 sig_ignored 1 0.0337 sigprocmask 1 0.0337 skb_clone 1 0.0337 sys_fstat64 1 0.0337 sys_lseek 1 0.0337 sys_read 1 0.0337 sys_rt_sigprocmask 1 0.0337 sys_select 1 0.0337 task_rq_lock 1 0.0337 tasklet_action 1 0.0337 tcp_ack 1 0.0337 tick_do_update_jiffies64 1 0.0337 tick_nohz_stop_sched_tick 1 0.0337 try_to_del_timer_sync 1 0.0337 try_to_wake_up 1 0.0337 unix_create1 1 0.0337 unix_poll 1 0.0337 vfs_getattr 1 0.0337 vfs_llseek 1 0.0337 vm_acct_memory 1 0.0337 vma_adjust 1 0.0337 vma_prio_tree_remove 1 0.0337 work_resched