Thu Aug 2 18:31: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.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| %| ------------------ 692 46.9153 vmlinux 315 21.3559 libc-2.6.so 163 11.0508 bash CPU_CLK_UNHALT...| samples| %| ------------------ 162 99.3865 bash 1 0.6135 anon (tgid:5385 range:0xb7fb9000-0xb7fba000) 126 8.5424 ld-2.6.so 51 3.4576 ISO8859-1.so 41 2.7797 ext3 21 1.4237 jbd 16 1.0847 oprofiled 8 0.5424 skge 7 0.4746 processor 5 0.3390 grep 4 0.2712 oprofile 3 0.2034 gawk 3 0.2034 libcrypto.so.0.9.8 3 0.2034 libnetsnmpmibs.so.15.0.0 3 0.2034 imap-login 2 0.1356 ide_core 2 0.1356 libncurses.so.5.6 2 0.1356 libnetsnmp.so.15.0.0 2 0.1356 locale-archive 1 0.0678 tr 1 0.0678 ide_disk 1 0.0678 libpthread-2.6.so 1 0.0678 screen-4.0.3 1 0.0678 dovecot-auth 1 0.0678 dovecot CPU_CLK_UNHALT...| samples| %| ------------------ 1 100.000 anon (tgid:3919 range:0xb7f01000-0xb7f02000) + 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 114 16.4740 do_wp_page 36 5.2023 __handle_mm_fault 26 3.7572 get_page_from_freelist 20 2.8902 page_fault 15 2.1676 unmap_vmas 13 1.8786 __link_path_walk 11 1.5896 copy_process 11 1.5896 filemap_nopage 10 1.4451 delay_tsc 9 1.3006 __d_lookup 9 1.3006 find_get_page 8 1.1561 __mutex_lock_slowpath 8 1.1561 do_page_fault 7 1.0116 mask_and_ack_8259A 7 1.0116 page_address 6 0.8671 acpi_pm_read 6 0.8671 error_code 6 0.8671 find_vma 6 0.8671 kmem_cache_alloc 6 0.8671 kmem_cache_free 6 0.8671 pit_next_event 6 0.8671 strnlen_user 5 0.7225 __copy_to_user_ll 5 0.7225 __wake_up_bit 5 0.7225 native_flush_tlb_single 5 0.7225 put_page 4 0.5780 __find_get_block 4 0.5780 copy_page_range 4 0.5780 inode_init_once 4 0.5780 kunmap_atomic 4 0.5780 memcpy 4 0.5780 permission 4 0.5780 radix_tree_lookup 4 0.5780 restore_nocheck 4 0.5780 resume_userspace 4 0.5780 sysenter_past_esp 4 0.5780 vma_adjust 3 0.4335 __atomic_notifier_call_chain 3 0.4335 __do_page_cache_readahead 3 0.4335 add_to_page_cache 3 0.4335 clockevents_program_event 3 0.4335 current_fs_time 3 0.4335 do_mmap_pgoff 3 0.4335 do_sigaction 3 0.4335 enable_8259A_irq 3 0.4335 free_hot_cold_page 3 0.4335 get_unused_fd 3 0.4335 getnstimeofday 3 0.4335 ktime_get_ts 3 0.4335 notifier_call_chain 3 0.4335 prio_tree_remove 3 0.4335 proc_lookup 3 0.4335 ret_from_exception 3 0.4335 schedule 3 0.4335 scheduler_tick 2 0.2890 __alloc_pages 2 0.2890 __copy_user_intel 2 0.2890 __inc_zone_state 2 0.2890 __mutex_unlock_slowpath 2 0.2890 __pagevec_lru_add_active 2 0.2890 acpi_os_read_port 2 0.2890 arch_setup_additional_pages 2 0.2890 atomic_notifier_call_chain 2 0.2890 cache_reap 2 0.2890 clear_user 2 0.2890 copy_thread 2 0.2890 cp_new_stat64 2 0.2890 debug_mutex_unlock 2 0.2890 destroy_context 2 0.2890 do_generic_mapping_read 2 0.2890 do_notify_parent 2 0.2890 do_notify_resume 2 0.2890 dup_fd 2 0.2890 find_next_zero_bit 2 0.2890 fput 2 0.2890 free_pgd_range 2 0.2890 generic_permission 2 0.2890 get_index 2 0.2890 get_next_timer_interrupt 2 0.2890 getname 2 0.2890 hrtimer_get_next_event 2 0.2890 kmap_atomic_prot 2 0.2890 mark_page_accessed 2 0.2890 mm_release 2 0.2890 proc_sys_lookup_table_one 2 0.2890 quicklist_trim 2 0.2890 rb_insert_color 2 0.2890 remove_vma 2 0.2890 rw_verify_area 2 0.2890 sys_mmap2 2 0.2890 sys_rt_sigprocmask 2 0.2890 update_wall_time 2 0.2890 vm_acct_memory 2 0.2890 vm_normal_page 2 0.2890 vm_stat_account 1 0.1445 __blocking_notifier_call_chain 1 0.1445 __const_udelay 1 0.1445 __dec_zone_page_state 1 0.1445 __fput 1 0.1445 __free_pages_ok 1 0.1445 __get_free_pages 1 0.1445 __get_user_4 1 0.1445 __kmalloc 1 0.1445 __mod_timer 1 0.1445 __put_user_4 1 0.1445 __qdisc_run 1 0.1445 __rcu_process_callbacks 1 0.1445 __remove_shared_vm_struct 1 0.1445 __sigqueue_alloc 1 0.1445 __switch_to 1 0.1445 _atomic_dec_and_lock 1 0.1445 _spin_lock_irqsave 1 0.1445 account_user_time 1 0.1445 acpi_hw_register_read 1 0.1445 anon_vma_prepare 1 0.1445 balance_dirty_pages_ratelimited_nr 1 0.1445 block_read_full_page 1 0.1445 blockable_page_cache_readahead 1 0.1445 can_share_swap_page 1 0.1445 cfq_remove_request 1 0.1445 cfq_service_tree_add 1 0.1445 check_tty_count 1 0.1445 clear_inode 1 0.1445 clocksource_watchdog 1 0.1445 compute_creds 1 0.1445 copy_from_user 1 0.1445 copy_thread_group_keys 1 0.1445 copy_to_user 1 0.1445 cpu_idle 1 0.1445 create_read_pipe 1 0.1445 d_alloc 1 0.1445 d_lookup 1 0.1445 debug_mutex_lock_common 1 0.1445 debug_mutex_set_owner 1 0.1445 default_llseek 1 0.1445 dequeue_task 1 0.1445 dev_hard_start_xmit 1 0.1445 dnotify_flush 1 0.1445 do_brk 1 0.1445 do_lookup 1 0.1445 do_path_lookup 1 0.1445 do_wait 1 0.1445 down_read 1 0.1445 dput 1 0.1445 dummy_file_permission 1 0.1445 dummy_inode_permission 1 0.1445 dummy_task_create 1 0.1445 enqueue_hrtimer 1 0.1445 enqueue_task 1 0.1445 exit_aio 1 0.1445 exit_itimers 1 0.1445 exit_mm 1 0.1445 expand_files 1 0.1445 filp_close 1 0.1445 find_busiest_group 1 0.1445 find_mergeable_anon_vma 1 0.1445 find_next_bit 1 0.1445 find_vma_prev 1 0.1445 flush_old_exec 1 0.1445 flush_tlb_mm 1 0.1445 free_page_and_swap_cache 1 0.1445 free_pgtables 1 0.1445 generic_file_mmap 1 0.1445 generic_file_open 1 0.1445 generic_make_request 1 0.1445 get_nr_files 1 0.1445 get_pid_task 1 0.1445 get_unmapped_area 1 0.1445 get_write_access 1 0.1445 half_md4_transform 1 0.1445 hrtimer_reprogram 1 0.1445 hweight32 1 0.1445 in_lock_functions 1 0.1445 init_new_context 1 0.1445 ip_push_pending_frames 1 0.1445 irq_entries_start 1 0.1445 kmem_cache_zalloc 1 0.1445 ktime_divns 1 0.1445 link_path_walk 1 0.1445 load_elf_binary 1 0.1445 lock_hrtimer_base 1 0.1445 locks_remove_flock 1 0.1445 lru_cache_add_active 1 0.1445 math_state_restore 1 0.1445 may_open 1 0.1445 memory_open 1 0.1445 mm_alloc 1 0.1445 mutex_remove_waiter 1 0.1445 name_to_int 1 0.1445 native_io_delay 1 0.1445 neigh_periodic_timer 1 0.1445 net_rx_action 1 0.1445 normal_poll 1 0.1445 open_namei 1 0.1445 page_add_file_rmap 1 0.1445 page_remove_rmap 1 0.1445 page_waitqueue 1 0.1445 path_release 1 0.1445 pgd_alloc 1 0.1445 pipe_write 1 0.1445 poll_freewait 1 0.1445 proc_flush_task 1 0.1445 process_timeout 1 0.1445 profile_tick 1 0.1445 radix_tree_insert 1 0.1445 raise_softirq 1 0.1445 rb_erase 1 0.1445 release_task 1 0.1445 restore_all 1 0.1445 run_timer_softirq 1 0.1445 send_signal 1 0.1445 seq_printf 1 0.1445 set_task_comm 1 0.1445 sha_transform 1 0.1445 show_map_internal 1 0.1445 sig_ignored 1 0.1445 sk_common_release 1 0.1445 sock_wfree 1 0.1445 sys_brk 1 0.1445 sys_close 1 0.1445 sys_mkdirat 1 0.1445 sys_read 1 0.1445 sys_set_thread_area 1 0.1445 sys_sigreturn 1 0.1445 tcp_poll 1 0.1445 tcp_sendmsg 1 0.1445 tcp_v4_rcv 1 0.1445 tick_do_update_jiffies64 1 0.1445 tick_nohz_update_jiffies 1 0.1445 tick_sched_timer 1 0.1445 try_to_wake_up 1 0.1445 tty_paranoia_check 1 0.1445 tty_write 1 0.1445 unlink_file_vma 1 0.1445 unmap_region 1 0.1445 vfs_fstat 1 0.1445 vfs_llseek 1 0.1445 vfs_mkdir 1 0.1445 vfs_write 1 0.1445 vma_link 1 0.1445 vsnprintf 1 0.1445 wake_up_bit 1 0.1445 write_chan