Tue Aug 7 17:47:43 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.031 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| %| ------------------ 40037 96.5142 vmlinux 413 0.9956 libc-2.6.so 225 0.5424 oprofiled CPU_CLK_UNHALT...| samples| %| ------------------ 224 99.5556 oprofiled 1 0.4444 anon (tgid:5252 range:0xb7fb6000-0xb7fb7000) 215 0.5183 bash 208 0.5014 ld-2.6.so 87 0.2097 ext3 87 0.2097 oprofile 68 0.1639 libata 54 0.1302 ISO8859-1.so 21 0.0506 jbd 11 0.0265 badblocks CPU_CLK_UNHALT...| samples| %| ------------------ 8 72.7273 badblocks 3 27.2727 anon (tgid:5166 range:0xb7f1d000-0xb7f1e000) 7 0.0169 imap-login 6 0.0145 grep CPU_CLK_UNHALT...| samples| %| ------------------ 5 83.3333 grep 1 16.6667 anon (tgid:5267 range:0x805b000-0x807c000) 6 0.0145 libext2fs.so.2.4 6 0.0145 locale-archive 5 0.0121 sd_mod 3 0.0072 gawk 3 0.0072 libcrypto.so.0.9.8 2 0.0048 tr 2 0.0048 libncurses.so.5.6 2 0.0048 screen-4.0.3 2 0.0048 libnetsnmp.so.15.0.0 2 0.0048 dovecot 2 0.0048 sshd 1 0.0024 ls 1 0.0024 libdl-2.6.so 1 0.0024 libnss_files-2.6.so 1 0.0024 libpcre.so.0.0.1 1 0.0024 libreadline.so.5.2 1 0.0024 dirname 1 0.0024 which 1 0.0024 libpopt.so.0.0.0 1 0.0024 dovecot-auth + echo + echo + echo + opreport -l /usr/src/linux-2.6.22-ARCH/vmlinux CPU: PIII, speed 798.031 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 28617 71.4764 cpu_idle 4163 10.3979 poll_idle 3220 8.0426 quicklist_trim 1465 3.6591 check_pgt_cache 414 1.0340 delay_tsc 188 0.4696 do_wp_page 115 0.2872 iowrite8 82 0.2048 ioread8 67 0.1673 mask_and_ack_8259A 65 0.1623 __handle_mm_fault 52 0.1299 get_page_from_freelist 45 0.1124 __copy_to_user_ll 44 0.1099 __blockdev_direct_IO 43 0.1074 unmap_vmas 34 0.0849 put_page 30 0.0749 page_fault 28 0.0699 schedule 22 0.0549 follow_page 20 0.0500 __bio_add_page 20 0.0500 blk_rq_map_sg 19 0.0475 __d_lookup 19 0.0475 enable_8259A_irq 17 0.0425 kmem_cache_free 16 0.0400 filemap_nopage 16 0.0400 kmem_cache_alloc 15 0.0375 __link_path_walk 15 0.0375 find_get_page 14 0.0350 copy_process 14 0.0350 page_address 12 0.0300 __switch_to 12 0.0300 blk_recount_segments 12 0.0300 getnstimeofday 11 0.0275 generic_make_request 11 0.0275 radix_tree_lookup 11 0.0275 read_tsc 11 0.0275 sysenter_past_esp 10 0.0250 __generic_file_aio_write_nolock 10 0.0250 copy_page_range 9 0.0225 cfq_insert_request 9 0.0225 do_generic_mapping_read 9 0.0225 do_page_fault 9 0.0225 enqueue_hrtimer 9 0.0225 find_vma 9 0.0225 ktime_get_ts 9 0.0225 load_elf_binary 8 0.0200 __mod_timer 8 0.0200 cfq_dispatch_requests 8 0.0200 dio_send_cur_page 8 0.0200 get_next_timer_interrupt 8 0.0200 handle_level_irq 8 0.0200 mempool_free 8 0.0200 rb_insert_color 8 0.0200 sched_clock 8 0.0200 strnlen_user 8 0.0200 submit_page_section 7 0.0175 __mutex_lock_slowpath 7 0.0175 blk_backing_dev_unplug 7 0.0175 dio_bio_add_page 7 0.0175 do_IRQ 7 0.0175 hrtimer_force_reprogram 7 0.0175 iowrite32 7 0.0175 try_to_wake_up 6 0.0150 __do_softirq 6 0.0150 do_mmap_pgoff 6 0.0150 fget_light 6 0.0150 find_busiest_group 6 0.0150 permission 6 0.0150 rb_erase 6 0.0150 scsi_dispatch_cmd 6 0.0150 task_running_tick 5 0.0125 __add_entropy_words 5 0.0125 __const_udelay 5 0.0125 __mutex_unlock_slowpath 5 0.0125 __rcu_pending 5 0.0125 _atomic_dec_and_lock 5 0.0125 blk_remove_plug 5 0.0125 block_llseek 5 0.0125 cfq_set_request 5 0.0125 clockevents_program_event 5 0.0125 debug_mutex_add_waiter 5 0.0125 del_timer 5 0.0125 dio_bio_complete 5 0.0125 elv_completed_request 5 0.0125 get_request 5 0.0125 hweight32 5 0.0125 kfree 5 0.0125 lock_timer_base 5 0.0125 memcpy 5 0.0125 native_load_tls 5 0.0125 preempt_schedule 5 0.0125 scsi_get_command 5 0.0125 submit_bio 5 0.0125 tick_nohz_stop_sched_tick 5 0.0125 update_wall_time 4 0.0100 __dentry_open 4 0.0100 __end_that_request_first 4 0.0100 __make_request 4 0.0100 _spin_lock_irqsave 4 0.0100 add_timer_randomness 4 0.0100 alloc_inode 4 0.0100 bio_add_page 4 0.0100 block_read_full_page 4 0.0100 cfq_remove_request 4 0.0100 cond_resched 4 0.0100 dequeue_task 4 0.0100 dio_get_page 4 0.0100 do_sync_write 4 0.0100 dup_fd 4 0.0100 elv_insert 4 0.0100 error_code 4 0.0100 filp_close 4 0.0100 find_next_zero_bit 4 0.0100 flush_tlb_mm 4 0.0100 free_pgtables 4 0.0100 generic_permission 4 0.0100 generic_unplug_device 4 0.0100 get_user_pages 4 0.0100 hrtimer_try_to_cancel 4 0.0100 lock_hrtimer_base 4 0.0100 max_block 4 0.0100 rw_verify_area 4 0.0100 scsi_request_fn 4 0.0100 select_nohz_load_balancer 4 0.0100 tick_nohz_restart_sched_tick 4 0.0100 unlink_file_vma 3 0.0075 __alloc_pages 3 0.0075 __copy_from_user_ll 3 0.0075 __copy_user_intel 3 0.0075 __find_get_block 3 0.0075 __find_get_block_slow 3 0.0075 __fput 3 0.0075 __kmalloc 3 0.0075 __mutex_init 3 0.0075 __pte_alloc 3 0.0075 __rcu_process_callbacks 3 0.0075 __scsi_put_command 3 0.0075 __wake_up_bit 3 0.0075 anon_vma_link 3 0.0075 arch_get_unmapped_area_topdown 3 0.0075 bio_alloc_bioset 3 0.0075 bio_free 3 0.0075 bio_init 3 0.0075 blk_queue_bounce 3 0.0075 blkdev_get_blocks 3 0.0075 cache_alloc_refill 3 0.0075 call_rcu 3 0.0075 cfq_completed_request 3 0.0075 cfq_service_tree_add 3 0.0075 d_alloc 3 0.0075 debug_mutex_lock_common 3 0.0075 dnotify_parent 3 0.0075 do_path_lookup 3 0.0075 do_softirq 3 0.0075 do_sync_read 3 0.0075 do_sys_poll 3 0.0075 drive_stat_acct 3 0.0075 elv_next_request 3 0.0075 file_update_time 3 0.0075 free_block 3 0.0075 generic_file_aio_write_nolock 3 0.0075 generic_file_direct_write 3 0.0075 generic_segment_checks 3 0.0075 get_empty_filp 3 0.0075 get_request_wait 3 0.0075 get_unused_fd 3 0.0075 getname 3 0.0075 hrtimer_forward 3 0.0075 hrtimer_start 3 0.0075 inode_init_once 3 0.0075 inotify_d_instantiate 3 0.0075 io_schedule 3 0.0075 irq_entries_start 3 0.0075 kmem_cache_zalloc 3 0.0075 kunmap_atomic 3 0.0075 mark_page_accessed 3 0.0075 mutex_remove_waiter 3 0.0075 notifier_call_chain 3 0.0075 pipe_read 3 0.0075 rb_next 3 0.0075 recalc_task_prio 3 0.0075 run_timer_softirq 3 0.0075 scsi_device_unbusy 3 0.0075 scsi_finish_command 3 0.0075 scsi_io_completion 3 0.0075 scsi_run_queue 3 0.0075 set_normalized_timespec 3 0.0075 smp_apic_timer_interrupt 3 0.0075 sys_lseek 3 0.0075 vfs_write 2 0.0050 __atomic_notifier_call_chain 2 0.0050 __blk_put_request 2 0.0050 __d_path 2 0.0050 __dec_zone_state 2 0.0050 __do_page_cache_readahead 2 0.0050 __getblk 2 0.0050 __inc_zone_page_state 2 0.0050 __inc_zone_state 2 0.0050 __remove_hrtimer 2 0.0050 __rmqueue 2 0.0050 __scsi_get_command 2 0.0050 __wake_up 2 0.0050 account_system_time 2 0.0050 alloc_pid 2 0.0050 anon_vma_unlink 2 0.0050 atomic_notifier_call_chain 2 0.0050 bit_waitqueue 2 0.0050 blk_do_ordered 2 0.0050 cfq_init_prio_data 2 0.0050 cfq_queue_empty 2 0.0050 clocksource_get_next 2 0.0050 common_interrupt 2 0.0050 copy_to_user 2 0.0050 current_fs_time 2 0.0050 deactivate_task 2 0.0050 debug_mutex_unlock 2 0.0050 dentry_iput 2 0.0050 dio_bio_end_io 2 0.0050 dio_new_bio 2 0.0050 disk_round_stats 2 0.0050 do_exit 2 0.0050 do_lookup 2 0.0050 do_mremap 2 0.0050 do_sigaction 2 0.0050 do_wait 2 0.0050 dummy_capget 2 0.0050 dummy_inode_permission 2 0.0050 elv_may_queue 2 0.0050 elv_queue_empty 2 0.0050 elv_rqhash_del 2 0.0050 end_that_request_last 2 0.0050 enqueue_task 2 0.0050 exit_itimers 2 0.0050 fget 2 0.0050 file_move 2 0.0050 file_read_actor 2 0.0050 find_or_create_page 2 0.0050 flush_signal_handlers 2 0.0050 flush_tlb_page 2 0.0050 fput 2 0.0050 free_hot_cold_page 2 0.0050 free_page_and_swap_cache 2 0.0050 generic_fillattr 2 0.0050 get_signal_to_deliver 2 0.0050 init_request_from_bio 2 0.0050 inotify_inode_queue_event 2 0.0050 internal_add_timer 2 0.0050 irq_enter 2 0.0050 irq_exit 2 0.0050 kill_fasync 2 0.0050 kref_put 2 0.0050 ktime_get 2 0.0050 link_path_walk 2 0.0050 mempool_alloc 2 0.0050 mm_release 2 0.0050 native_load_esp0 2 0.0050 native_read_tsc 2 0.0050 page_cache_readahead 2 0.0050 pipe_poll 2 0.0050 radix_tree_insert 2 0.0050 raise_softirq 2 0.0050 rcu_needs_cpu 2 0.0050 recalc_sigpending_tsk 2 0.0050 release_pages 2 0.0050 release_task 2 0.0050 run_posix_cpu_timers 2 0.0050 sched_balance_self 2 0.0050 scheduler_tick 2 0.0050 scsi_done 2 0.0050 scsi_prep_fn 2 0.0050 scsi_put_command 2 0.0050 scsi_softirq_done 2 0.0050 sys_fstat64 2 0.0050 sys_mprotect 2 0.0050 sys_open 2 0.0050 tasklet_action 2 0.0050 tick_do_update_jiffies64 2 0.0050 tick_nohz_update_jiffies 2 0.0050 unlock_buffer 2 0.0050 vm_normal_page 2 0.0050 vma_adjust 2 0.0050 vma_prio_tree_add 2 0.0050 vsnprintf 1 0.0025 __activate_task 1 0.0025 __block_prepare_write 1 0.0025 __dequeue_signal 1 0.0025 __elv_add_request 1 0.0025 __free_pages_ok 1 0.0025 __generic_unplug_device 1 0.0025 __get_free_pages 1 0.0025 __get_user_4 1 0.0025 __mark_inode_dirty 1 0.0025 __mod_zone_page_state 1 0.0025 __mutex_lock_interruptible_slowpath 1 0.0025 __page_set_anon_rmap 1 0.0025 __path_lookup_intent_open 1 0.0025 __remove_shared_vm_struct 1 0.0025 __scsi_done 1 0.0025 __sigqueue_alloc 1 0.0025 __sock_create 1 0.0025 __tasklet_schedule 1 0.0025 __tcp_push_pending_frames 1 0.0025 __user_walk_fd 1 0.0025 __vm_enough_memory 1 0.0025 alloc_page_buffers 1 0.0025 anon_vma_prepare 1 0.0025 arch_align_stack 1 0.0025 arch_setup_additional_pages 1 0.0025 autoremove_wake_function 1 0.0025 bio_endio 1 0.0025 bio_fs_destructor 1 0.0025 bio_put 1 0.0025 blk_plug_device 1 0.0025 blk_run_queue 1 0.0025 blk_start_queueing 1 0.0025 blk_unplug_timeout 1 0.0025 blkdev_direct_IO 1 0.0025 cache_reap 1 0.0025 can_vma_merge_after 1 0.0025 cfq_add_rq_rb 1 0.0025 cfq_cic_rb_lookup 1 0.0025 cfq_may_queue 1 0.0025 cleanup_timers 1 0.0025 clear_bdi_congested 1 0.0025 clear_inode 1 0.0025 clocksource_watchdog 1 0.0025 copy_strings_kernel 1 0.0025 cp_new_stat64 1 0.0025 create_empty_buffers 1 0.0025 credit_entropy_store 1 0.0025 d_rehash 1 0.0025 datagram_poll 1 0.0025 dec_zone_page_state 1 0.0025 dequeue_signal 1 0.0025 dev_watchdog 1 0.0025 dio_bio_submit 1 0.0025 dio_cleanup 1 0.0025 dio_complete 1 0.0025 dio_zero_block 1 0.0025 do_select 1 0.0025 do_sys_open 1 0.0025 do_timer 1 0.0025 down_read_trylock 1 0.0025 dput 1 0.0025 drain_array 1 0.0025 dummy_bprm_alloc_security 1 0.0025 dummy_file_alloc_security 1 0.0025 dummy_task_alloc_security 1 0.0025 elf_map 1 0.0025 elv_dequeue_request 1 0.0025 elv_dispatch_sort 1 0.0025 elv_rb_add 1 0.0025 elv_rb_del 1 0.0025 elv_rqhash_add 1 0.0025 elv_set_request 1 0.0025 exit_mmap 1 0.0025 expand_files 1 0.0025 fasync_helper 1 0.0025 fd_install 1 0.0025 file_ra_state_init 1 0.0025 find_extend_vma 1 0.0025 find_next_bit 1 0.0025 find_vma_prev 1 0.0025 flush_old_exec 1 0.0025 flush_thread 1 0.0025 free_pid 1 0.0025 free_poll_entry 1 0.0025 generic_file_aio_read 1 0.0025 generic_file_direct_IO 1 0.0025 generic_file_open 1 0.0025 get_device 1 0.0025 get_nr_files 1 0.0025 get_task_mm 1 0.0025 hrtimer_get_next_event 1 0.0025 hrtimer_interrupt 1 0.0025 hrtimer_reprogram 1 0.0025 idle_cpu 1 0.0025 init_new_context 1 0.0025 init_page_buffers 1 0.0025 init_timer 1 0.0025 inode_change_ok 1 0.0025 inode_sub_bytes 1 0.0025 inotify_dentry_parent_queue_event 1 0.0025 iov_fault_in_pages_read 1 0.0025 ip_local_deliver 1 0.0025 ip_output 1 0.0025 kmap_atomic_prot 1 0.0025 kref_get 1 0.0025 kthread_should_stop 1 0.0025 lapic_next_event 1 0.0025 locks_remove_flock 1 0.0025 lru_cache_add_active 1 0.0025 may_open 1 0.0025 mempool_free_slab 1 0.0025 mod_timer 1 0.0025 mutex_unlock 1 0.0025 native_apic_write 1 0.0025 native_flush_tlb 1 0.0025 native_flush_tlb_single 1 0.0025 native_set_pte_at 1 0.0025 neigh_lookup 1 0.0025 new_inode 1 0.0025 note_interrupt 1 0.0025 notify_change 1 0.0025 number 1 0.0025 ordered_bio_endio 1 0.0025 page_add_file_rmap 1 0.0025 page_remove_rmap 1 0.0025 page_waitqueue 1 0.0025 path_lookup_open 1 0.0025 path_walk 1 0.0025 percpu_counter_mod 1 0.0025 pipe_write 1 0.0025 prepare_to_copy 1 0.0025 prio_tree_insert 1 0.0025 proc_flush_task 1 0.0025 proc_lookup 1 0.0025 put_io_context 1 0.0025 raise_softirq_irqoff 1 0.0025 rb_first 1 0.0025 rb_prev 1 0.0025 rcu_pending 1 0.0025 rcu_process_callbacks 1 0.0025 read_chan 1 0.0025 release_vm86_irqs 1 0.0025 remove_suid 1 0.0025 restore_nocheck 1 0.0025 resume_userspace 1 0.0025 ret_from_intr 1 0.0025 rq_init 1 0.0025 sched_exit 1 0.0025 schedule_tail 1 0.0025 schedule_timeout 1 0.0025 scsi_add_timer 1 0.0025 scsi_alloc_sgtable 1 0.0025 scsi_end_request 1 0.0025 scsi_free_sgtable 1 0.0025 scsi_get_cmd_from_req 1 0.0025 scsi_next_command 1 0.0025 search_binary_handler 1 0.0025 secure_ip_id 1 0.0025 seq_printf 1 0.0025 sha_transform 1 0.0025 show_stat 1 0.0025 special_mapping_nopage 1 0.0025 split_vma 1 0.0025 strncpy_from_user 1 0.0025 sys_brk 1 0.0025 sys_close 1 0.0025 sys_dup2 1 0.0025 sys_faccessat 1 0.0025 sys_gettimeofday 1 0.0025 sys_lookup_dcookie 1 0.0025 sys_mkdirat 1 0.0025 sys_read 1 0.0025 sys_rt_sigprocmask 1 0.0025 sys_set_thread_area 1 0.0025 sys_socketcall 1 0.0025 sys_wait4 1 0.0025 sys_write 1 0.0025 task_rq_lock 1 0.0025 tcp_ack 1 0.0025 tcp_poll 1 0.0025 tcp_v4_rcv 1 0.0025 tick_sched_timer 1 0.0025 tty_ioctl 1 0.0025 unix_poll 1 0.0025 up_write 1 0.0025 vfs_getattr 1 0.0025 vfs_llseek 1 0.0025 vfs_mkdir 1 0.0025 vfs_read 1 0.0025 vm_stat_account 1 0.0025 vma_link 1 0.0025 vma_merge 1 0.0025 vma_prio_tree_insert 1 0.0025 vma_prio_tree_remove 1 0.0025 wake_up_bit 1 0.0025 wake_up_new_task + date Tue Aug 7 17:47:50 EEST 2007