Mon Aug 6 21:46:59 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.025 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| %| ------------------ 7917 57.2658 vmlinux 2204 15.9421 libc-2.6.so 958 6.9295 libpython2.5.so.1.0 815 5.8951 ide_core 773 5.5913 perl 242 1.7505 ld-2.6.so 216 1.5624 mpop 204 1.4756 bash 121 0.8752 libgnutls.so.13.3.0 64 0.4629 badblocks CPU_CLK_UNHALT...| samples| %| ------------------ 48 75.0000 badblocks 10 15.6250 anon (tgid:4338 range:0xb7f6c000-0xb7f6d000) 6 9.3750 anon (tgid:4339 range:0xb7f64000-0xb7f65000) 46 0.3327 ISO8859-1.so 37 0.2676 ext3 34 0.2459 imap-login CPU_CLK_UNHALT...| samples| %| ------------------ 31 91.1765 imap-login 1 2.9412 anon (tgid:3989 range:0xb7f80000-0xb7f81000) 1 2.9412 anon (tgid:3990 range:0xb7f72000-0xb7f73000) 1 2.9412 anon (tgid:4332 range:0xb7fd6000-0xb7fd7000) 33 0.2387 libext2fs.so.2.4 24 0.1736 jbd 24 0.1736 libpthread-2.6.so 21 0.1519 gawk 15 0.1085 oprofile 13 0.0940 libcrypto.so.0.9.8 12 0.0868 ide_disk 11 0.0796 skge 7 0.0506 sshd 6 0.0434 dovecot-auth CPU_CLK_UNHALT...| samples| %| ------------------ 5 83.3333 dovecot-auth 1 16.6667 anon (tgid:3971 range:0xb7f8c000-0xb7f8d000) 4 0.0289 libnetsnmp.so.15.0.0 4 0.0289 libnetsnmpmibs.so.15.0.0 4 0.0289 imap 4 0.0289 dovecot 3 0.0217 grep 3 0.0217 reiserfs 3 0.0217 locale-archive 1 0.0072 libdl-2.6.so 1 0.0072 init 1 0.0072 screen-4.0.3 + echo + echo + echo + opreport -l /usr/src/linux-2.6.22-ARCH/vmlinux CPU: PIII, speed 798.025 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 2477 31.2871 __switch_to 1625 20.5255 schedule 246 3.1072 mask_and_ack_8259A 222 2.8041 __blockdev_direct_IO 147 1.8568 follow_page 107 1.3515 put_page 106 1.3389 do_wp_page 91 1.1494 native_load_tls 88 1.1115 __bio_add_page 80 1.0105 delay_tsc 63 0.7958 __handle_mm_fault 50 0.6316 submit_page_section 49 0.6189 get_page_from_freelist 48 0.6063 dio_bio_add_page 45 0.5684 dequeue_task 41 0.5179 get_user_pages 40 0.5052 bio_alloc_bioset 39 0.4926 enable_8259A_irq 38 0.4800 dio_send_cur_page 38 0.4800 page_address 38 0.4800 sysenter_past_esp 37 0.4673 dio_get_page 37 0.4673 find_get_page 36 0.4547 bio_add_page 36 0.4547 kmem_cache_alloc 36 0.4547 unmap_vmas 35 0.4421 kmem_cache_free 34 0.4295 blk_rq_map_sg 33 0.4168 blk_recount_segments 32 0.4042 do_page_fault 31 0.3916 generic_file_direct_write 30 0.3789 __generic_file_aio_write_nolock 30 0.3789 vm_normal_page 28 0.3537 __link_path_walk 28 0.3537 __mutex_lock_slowpath 26 0.3284 irq_entries_start 25 0.3158 filemap_nopage 25 0.3158 vfs_write 23 0.2905 find_vma 22 0.2779 __d_lookup 22 0.2779 dio_bio_complete 22 0.2779 mark_page_accessed 21 0.2653 add_timer_randomness 21 0.2653 max_block 21 0.2653 page_fault 19 0.2400 restore_all 18 0.2274 __add_entropy_words 18 0.2274 preempt_schedule 17 0.2147 __copy_to_user_ll 17 0.2147 __generic_unplug_device 17 0.2147 do_sys_poll 17 0.2147 fget_light 17 0.2147 math_state_restore 16 0.2021 __mod_timer 16 0.2021 generic_file_direct_IO 16 0.2021 preempt_schedule_irq 15 0.1895 block_llseek 15 0.1895 do_sync_write 14 0.1768 current_fs_time 14 0.1768 do_generic_mapping_read 14 0.1768 mempool_free 14 0.1768 native_flush_tlb_single 14 0.1768 radix_tree_lookup 14 0.1768 rw_verify_area 13 0.1642 __alloc_pages 13 0.1642 __make_request 13 0.1642 _spin_lock_irqsave 13 0.1642 cond_resched 12 0.1516 cfq_completed_request 12 0.1516 strnlen_user 11 0.1389 __mutex_unlock_slowpath 11 0.1389 do_lookup 11 0.1389 generic_make_request 11 0.1389 get_request 11 0.1389 restore_nocheck 10 0.1263 blkdev_direct_IO 10 0.1263 load_elf_binary 10 0.1263 unix_poll 9 0.1137 cfq_insert_request 9 0.1137 cfq_set_request 9 0.1137 copy_page_range 9 0.1137 do_mmap_pgoff 9 0.1137 elv_insert 9 0.1137 generic_file_aio_write_nolock 9 0.1137 generic_permission 9 0.1137 recalc_task_prio 8 0.1010 blkdev_get_blocks 8 0.1010 cache_reap 8 0.1010 dio_new_bio 8 0.1010 file_update_time 8 0.1010 sys_llseek 8 0.1010 vsnprintf 7 0.0884 bio_put 7 0.0884 blk_backing_dev_unplug 7 0.0884 cfq_dispatch_requests 7 0.0884 copy_process 7 0.0884 device_not_available 7 0.0884 disk_round_stats 7 0.0884 elv_queue_empty 7 0.0884 free_block 7 0.0884 generic_unplug_device 7 0.0884 hrtimer_interrupt 7 0.0884 kmap_atomic_prot 7 0.0884 mutex_remove_waiter 7 0.0884 need_resched 7 0.0884 page_remove_rmap 7 0.0884 permission 6 0.0758 __blk_put_request 6 0.0758 __copy_from_user_ll 6 0.0758 __copy_user_intel 6 0.0758 __end_that_request_first 6 0.0758 __find_get_block_slow 6 0.0758 bio_init 6 0.0758 blk_remove_plug 6 0.0758 cfq_may_queue 6 0.0758 dio_cleanup 6 0.0758 dio_complete 6 0.0758 dput 6 0.0758 getname 6 0.0758 inotify_inode_queue_event 6 0.0758 kfree 6 0.0758 memcpy 6 0.0758 mempool_alloc 6 0.0758 path_walk 6 0.0758 rb_erase 6 0.0758 read_tsc 6 0.0758 task_rq_lock 6 0.0758 try_to_wake_up 6 0.0758 vfs_llseek 5 0.0632 __const_udelay 5 0.0632 __fput 5 0.0632 blk_plug_device 5 0.0632 cfq_remove_request 5 0.0632 copy_strings 5 0.0632 current_io_context 5 0.0632 debug_mutex_add_waiter 5 0.0632 dnotify_parent 5 0.0632 do_IRQ 5 0.0632 do_filp_open 5 0.0632 do_path_lookup 5 0.0632 drain_array 5 0.0632 elv_rqhash_add 5 0.0632 free_poll_entry 5 0.0632 generic_segment_checks 5 0.0632 handle_level_irq 5 0.0632 idle_cpu 5 0.0632 init_request_from_bio 5 0.0632 io_schedule 5 0.0632 ip_append_data 5 0.0632 note_interrupt 5 0.0632 proc_sys_lookup_table_one 5 0.0632 sched_clock 5 0.0632 sys_write 5 0.0632 up_read 4 0.0505 __dentry_open 4 0.0505 __freed_request 4 0.0505 __pagevec_lru_add_active 4 0.0505 _atomic_dec_and_lock 4 0.0505 bdev_read_only 4 0.0505 cfq_queue_empty 4 0.0505 copy_to_user 4 0.0505 debug_mutex_free_waiter 4 0.0505 do_munmap 4 0.0505 do_sync_read 4 0.0505 do_wait 4 0.0505 dummy_inode_permission 4 0.0505 elv_completed_request 4 0.0505 end_that_request_last 4 0.0505 filemap_write_and_wait 4 0.0505 find_extend_vma 4 0.0505 find_vma_prev 4 0.0505 generic_file_aio_read 4 0.0505 get_empty_filp 4 0.0505 get_request_wait 4 0.0505 hweight32 4 0.0505 internal_add_timer 4 0.0505 irq_exit 4 0.0505 native_read_tsc 4 0.0505 number 4 0.0505 poll_freewait 4 0.0505 proc_lookup 4 0.0505 setup_arg_pages 4 0.0505 should_remove_suid 4 0.0505 sock_poll 4 0.0505 submit_bio 4 0.0505 tick_sched_timer 4 0.0505 unmap_region 4 0.0505 vma_adjust 3 0.0379 __atomic_notifier_call_chain 3 0.0379 __do_page_cache_readahead 3 0.0379 __elv_add_request 3 0.0379 __find_get_block 3 0.0379 __mark_inode_dirty 3 0.0379 __pte_alloc 3 0.0379 anon_vma_prepare 3 0.0379 anon_vma_unlink 3 0.0379 bio_endio 3 0.0379 bit_waitqueue 3 0.0379 call_rcu 3 0.0379 cfq_init_prio_data 3 0.0379 cfq_put_queue 3 0.0379 cfq_service_tree_add 3 0.0379 clear_bdi_congested 3 0.0379 common_interrupt 3 0.0379 credit_entropy_store 3 0.0379 deactivate_task 3 0.0379 debug_mutex_lock_common 3 0.0379 debug_mutex_unlock 3 0.0379 del_timer 3 0.0379 down_read_trylock 3 0.0379 dummy_inode_getattr 3 0.0379 elv_dispatch_sort 3 0.0379 elv_may_queue 3 0.0379 elv_put_request 3 0.0379 elv_set_request 3 0.0379 enqueue_task 3 0.0379 error_code 3 0.0379 flush_old_exec 3 0.0379 free_hot_cold_page 3 0.0379 generic_fillattr 3 0.0379 kmem_cache_zalloc 3 0.0379 link_path_walk 3 0.0379 lock_timer_base 3 0.0379 locks_remove_flock 3 0.0379 may_expand_vm 3 0.0379 native_load_esp0 3 0.0379 open_namei 3 0.0379 path_lookup_open 3 0.0379 pipe_poll 3 0.0379 proc_flush_task 3 0.0379 release_vm86_irqs 3 0.0379 remove_vma 3 0.0379 rq_init 3 0.0379 sock_alloc_send_skb 3 0.0379 strncpy_from_user 3 0.0379 sys_mmap2 3 0.0379 sys_mprotect 3 0.0379 touch_atime 3 0.0379 vfs_read 3 0.0379 vm_acct_memory 3 0.0379 vm_stat_account 3 0.0379 vma_link 2 0.0253 I_BDEV 2 0.0253 __dec_zone_state 2 0.0253 __group_complete_signal 2 0.0253 __inc_zone_state 2 0.0253 __mutex_lock_interruptible_slowpath 2 0.0253 __path_lookup_intent_open 2 0.0253 __pollwait 2 0.0253 __rb_rotate_right 2 0.0253 __vm_enough_memory 2 0.0253 __vma_link_rb 2 0.0253 __wake_up 2 0.0253 __wake_up_bit 2 0.0253 alloc_inode 2 0.0253 anon_vma_ctor 2 0.0253 bio_alloc 2 0.0253 bio_free 2 0.0253 bio_fs_destructor 2 0.0253 bio_get_nr_vecs 2 0.0253 bio_phys_segments 2 0.0253 blk_queue_bounce 2 0.0253 cache_alloc_refill 2 0.0253 check_pgt_cache 2 0.0253 debug_mutex_set_owner 2 0.0253 dio_bio_end_io 2 0.0253 dio_zero_block 2 0.0253 do_notify_parent 2 0.0253 do_notify_resume 2 0.0253 do_sys_open 2 0.0253 down_read 2 0.0253 dst_alloc 2 0.0253 effective_prio 2 0.0253 elv_dequeue_request 2 0.0253 elv_next_request 2 0.0253 file_free_rcu 2 0.0253 file_read_actor 2 0.0253 filp_close 2 0.0253 find_next_bit 2 0.0253 find_next_zero_bit 2 0.0253 find_vma_prepare 2 0.0253 flush_tlb_mm 2 0.0253 flush_tlb_page 2 0.0253 generic_file_mmap 2 0.0253 get_index 2 0.0253 get_unused_fd 2 0.0253 getnstimeofday 2 0.0253 handle_IRQ_event 2 0.0253 icmp_push_reply 2 0.0253 icmp_send 2 0.0253 kill_fasync 2 0.0253 kunmap_atomic 2 0.0253 lru_cache_add_active 2 0.0253 mntput_no_expire 2 0.0253 native_read_cr0 2 0.0253 notifier_call_chain 2 0.0253 ordered_bio_endio 2 0.0253 page_cache_readahead 2 0.0253 percpu_counter_mod 2 0.0253 poll_initwait 2 0.0253 profile_munmap 2 0.0253 put_filp 2 0.0253 put_io_context 2 0.0253 quicklist_trim 2 0.0253 radix_tree_gang_lookup_tag 2 0.0253 rb_insert_color 2 0.0253 remove_suid 2 0.0253 resume_userspace 2 0.0253 sched_balance_self 2 0.0253 seq_printf 2 0.0253 smp_apic_timer_interrupt 2 0.0253 sys_brk 2 0.0253 sys_fstat64 2 0.0253 sys_read 2 0.0253 sys_rt_sigaction 2 0.0253 sys_stat64 2 0.0253 system_call 2 0.0253 vma_merge 2 0.0253 work_notifysig 2 0.0253 zone_watermark_ok 1 0.0126 __activate_task 1 0.0126 __alloc_skb 1 0.0126 __blocking_notifier_call_chain 1 0.0126 __bread 1 0.0126 __delay 1 0.0126 __do_softirq 1 0.0126 __follow_mount 1 0.0126 __iget 1 0.0126 __ip_select_ident 1 0.0126 __put_user_4 1 0.0126 __rb_rotate_left 1 0.0126 __rcu_process_callbacks 1 0.0126 __rmqueue 1 0.0126 __sigqueue_alloc 1 0.0126 __sigqueue_free 1 0.0126 __sk_dst_check 1 0.0126 __tasklet_schedule 1 0.0126 __tcp_ack_snd_check 1 0.0126 __tcp_push_pending_frames 1 0.0126 __user_walk_fd 1 0.0126 __vma_link 1 0.0126 _read_lock_irqsave 1 0.0126 account_system_time 1 0.0126 account_user_time 1 0.0126 add_disk_randomness 1 0.0126 add_wait_queue 1 0.0126 all_vm_events 1 0.0126 alloc_buffer_head 1 0.0126 alloc_pid 1 0.0126 anon_pipe_buf_release 1 0.0126 anon_vma_link 1 0.0126 arch_setup_additional_pages 1 0.0126 bio_hw_segments 1 0.0126 blk_do_ordered 1 0.0126 blk_start_queueing 1 0.0126 blockable_page_cache_readahead 1 0.0126 blocking_notifier_call_chain 1 0.0126 bmap 1 0.0126 can_vma_merge_after 1 0.0126 cfq_add_rq_rb 1 0.0126 cfq_choose_req 1 0.0126 cfq_cic_rb_lookup 1 0.0126 cfq_rb_erase 1 0.0126 check_userspace 1 0.0126 copy_semundo 1 0.0126 core_sys_select 1 0.0126 cp_new_stat64 1 0.0126 csum_partial_copy_generic 1 0.0126 d_alloc 1 0.0126 dec_zone_page_state 1 0.0126 dequeue_signal 1 0.0126 devinet_ioctl 1 0.0126 do_fork 1 0.0126 do_group_exit 1 0.0126 do_sigaction 1 0.0126 drive_stat_acct 1 0.0126 dummy_capable 1 0.0126 dummy_file_alloc_security 1 0.0126 dummy_task_kill 1 0.0126 dummy_vm_enough_memory 1 0.0126 dup_fd 1 0.0126 elv_rb_add 1 0.0126 end_that_request_first 1 0.0126 exit_itimers 1 0.0126 fasync_helper 1 0.0126 fget 1 0.0126 file_kill 1 0.0126 file_move 1 0.0126 file_ra_state_init 1 0.0126 flush_sigqueue 1 0.0126 fn_hash_lookup 1 0.0126 fput 1 0.0126 free_page_and_swap_cache 1 0.0126 free_pgtables 1 0.0126 get_futex_key 1 0.0126 get_io_context 1 0.0126 get_task_mm 1 0.0126 hrtimer_try_to_cancel 1 0.0126 icmp_glue_bits 1 0.0126 ifind_fast 1 0.0126 inotify_dentry_parent_queue_event 1 0.0126 invalidate_inode_buffers 1 0.0126 ip_route_input 1 0.0126 irq_enter 1 0.0126 local_bh_enable_ip 1 0.0126 may_open 1 0.0126 mempool_alloc_slab 1 0.0126 mempool_free_slab 1 0.0126 n_tty_receive_buf 1 0.0126 native_apic_write 1 0.0126 native_set_pte_at 1 0.0126 open_exec 1 0.0126 opost 1 0.0126 page_add_file_rmap 1 0.0126 pipe_release 1 0.0126 pipe_write 1 0.0126 prio_tree_insert 1 0.0126 prio_tree_remove 1 0.0126 proc_sys_lookup_table 1 0.0126 profile_tick 1 0.0126 pty_chars_in_buffer 1 0.0126 put_tty_queue 1 0.0126 rb_first 1 0.0126 recalc_sigpending_tsk 1 0.0126 release_open_intent 1 0.0126 release_task 1 0.0126 restore_sigcontext 1 0.0126 ret_from_exception 1 0.0126 ret_from_intr 1 0.0126 scheduler_tick 1 0.0126 search_binary_handler 1 0.0126 secure_ip_id 1 0.0126 show_stat 1 0.0126 sigprocmask 1 0.0126 snprintf 1 0.0126 sock_fasync 1 0.0126 softlockup_tick 1 0.0126 special_mapping_nopage 1 0.0126 split_vma 1 0.0126 sys_access 1 0.0126 sys_clone 1 0.0126 sys_execve 1 0.0126 sys_futex 1 0.0126 sys_open 1 0.0126 sys_rt_sigprocmask 1 0.0126 syscall_exit 1 0.0126 sysctl_head_next 1 0.0126 task_running_tick 1 0.0126 tcp_poll 1 0.0126 tcp_rcv_space_adjust 1 0.0126 tcp_recvmsg 1 0.0126 tcp_transmit_skb 1 0.0126 test_set_page_writeback 1 0.0126 tick_do_update_jiffies64 1 0.0126 tick_program_event 1 0.0126 timespec_trunc 1 0.0126 try_to_del_timer_sync 1 0.0126 tty_ioctl 1 0.0126 tty_ldisc_ref_wait 1 0.0126 udp_flush_pending_frames 1 0.0126 unuse_table 1 0.0126 update_process_times 1 0.0126 update_wall_time 1 0.0126 vfs_stat_fd 1 0.0126 vma_prio_tree_remove 1 0.0126 wake_up_inode 1 0.0126 wake_up_new_task 1 0.0126 wake_up_process 1 0.0126 write_cache_pages