Thu Aug 2 18:33:52 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| %| ------------------ 1829 48.3734 processor 1029 27.2150 vmlinux 356 9.4155 libc-2.6.so 200 5.2896 bash 157 4.1523 ld-2.6.so 52 1.3753 ISO8859-1.so 31 0.8199 ext3 29 0.7670 oprofiled CPU_CLK_UNHALT...| samples| %| ------------------ 28 96.5517 oprofiled 1 3.4483 anon (tgid:5517 range:0xb7f71000-0xb7f72000) 26 0.6876 jbd 19 0.5025 ide_core 8 0.2116 grep 7 0.1851 oprofile 5 0.1322 gawk 5 0.1322 locale-archive 4 0.1058 badblocks 3 0.0793 screen-4.0.3 3 0.0793 sshd 2 0.0529 libext2fs.so.2.4 2 0.0529 expr 2 0.0529 libcrypto.so.0.9.8 1 0.0264 ls 1 0.0264 rm 1 0.0264 tr 1 0.0264 libhistory.so.5.2 1 0.0264 libm-2.6.so 1 0.0264 libncurses.so.5.6 1 0.0264 libpthread-2.6.so 1 0.0264 libreadline.so.5.2 1 0.0264 skge 1 0.0264 id 1 0.0264 libnetsnmpmibs.so.15.0.0 1 0.0264 snmpd + 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 160 15.5491 do_wp_page 123 11.9534 native_safe_halt 49 4.7619 __handle_mm_fault 34 3.3042 unmap_vmas 27 2.6239 get_page_from_freelist 23 2.2352 page_fault 15 1.4577 __d_lookup 14 1.3605 put_page 13 1.2634 copy_process 12 1.1662 __link_path_walk 12 1.1662 do_page_fault 11 1.0690 page_address 9 0.8746 __copy_to_user_ll 9 0.8746 memcpy 8 0.7775 acpi_pm_read 8 0.7775 find_vma 8 0.7775 radix_tree_lookup 7 0.6803 __wake_up_bit 7 0.6803 copy_page_range 7 0.6803 find_get_page 7 0.6803 kmem_cache_free 6 0.5831 __blockdev_direct_IO 6 0.5831 copy_strings 6 0.5831 do_mmap_pgoff 6 0.5831 filemap_nopage 6 0.5831 mask_and_ack_8259A 6 0.5831 sysenter_past_esp 5 0.4859 __find_get_block 5 0.4859 error_code 5 0.4859 flush_tlb_page 5 0.4859 free_pgtables 5 0.4859 permission 5 0.4859 strnlen_user 4 0.3887 __pagevec_lru_add_active 4 0.3887 blk_backing_dev_unplug 4 0.3887 do_generic_mapping_read 4 0.3887 do_path_lookup 4 0.3887 follow_page 4 0.3887 rb_insert_color 3 0.2915 __atomic_notifier_call_chain 3 0.2915 __do_page_cache_readahead 3 0.2915 __find_get_block_slow 3 0.2915 __fput 3 0.2915 _atomic_dec_and_lock 3 0.2915 acpi_os_read_port 3 0.2915 add_timer_randomness 3 0.2915 bit_waitqueue 3 0.2915 copy_to_user 3 0.2915 delay_tsc 3 0.2915 do_exit 3 0.2915 find_next_zero_bit 3 0.2915 flush_tlb_mm 3 0.2915 generic_fillattr 3 0.2915 generic_permission 3 0.2915 generic_segment_checks 3 0.2915 getnstimeofday 3 0.2915 ktime_get_ts 3 0.2915 kunmap_atomic 3 0.2915 load_elf_binary 3 0.2915 page_remove_rmap 3 0.2915 path_walk 3 0.2915 put_files_struct 3 0.2915 rb_erase 3 0.2915 release_pages 3 0.2915 ret_from_exception 3 0.2915 schedule 2 0.1944 __blocking_notifier_call_chain 2 0.1944 __dec_zone_state 2 0.1944 __pte_alloc 2 0.1944 __vm_enough_memory 2 0.1944 _spin_lock_irqsave 2 0.1944 account_system_time 2 0.1944 anon_vma_unlink 2 0.1944 atomic_notifier_call_chain 2 0.1944 cache_reap 2 0.1944 cfq_queue_empty 2 0.1944 clockevents_program_event 2 0.1944 current_fs_time 2 0.1944 d_alloc 2 0.1944 debug_mutex_add_waiter 2 0.1944 debug_mutex_unlock 2 0.1944 disk_round_stats 2 0.1944 dnotify_flush 2 0.1944 do_notify_resume 2 0.1944 do_softirq 2 0.1944 down_read_trylock 2 0.1944 dup_fd 2 0.1944 end_that_request_last 2 0.1944 fd_install 2 0.1944 file_read_actor 2 0.1944 flush_old_exec 2 0.1944 free_block 2 0.1944 free_hot_cold_page 2 0.1944 generic_make_request 2 0.1944 get_empty_filp 2 0.1944 get_index 2 0.1944 get_next_timer_interrupt 2 0.1944 get_signal_to_deliver 2 0.1944 irq_entries_start 2 0.1944 kmap_atomic_prot 2 0.1944 kmem_cache_alloc 2 0.1944 mutex_remove_waiter 2 0.1944 page_add_file_rmap 2 0.1944 page_waitqueue 2 0.1944 percpu_counter_mod 2 0.1944 pit_next_event 2 0.1944 prepare_to_copy 2 0.1944 prio_tree_insert 2 0.1944 rcu_start_batch 2 0.1944 recalc_sigpending_tsk 2 0.1944 remove_vma 2 0.1944 resume_userspace 2 0.1944 rm_from_queue_full 2 0.1944 sched_balance_self 2 0.1944 scheduler_tick 2 0.1944 secure_ip_id 2 0.1944 sys_close 2 0.1944 sys_rt_sigprocmask 2 0.1944 task_running_tick 2 0.1944 try_to_wake_up 2 0.1944 up_read 2 0.1944 update_wall_time 2 0.1944 vfs_permission 2 0.1944 vm_normal_page 2 0.1944 vma_link 2 0.1944 vma_merge 1 0.0972 I_BDEV 1 0.0972 __add_entropy_words 1 0.0972 __brelse 1 0.0972 __copy_from_user_ll 1 0.0972 __d_path 1 0.0972 __do_softirq 1 0.0972 __elv_add_request 1 0.0972 __end_that_request_first 1 0.0972 __free_pipe_info 1 0.0972 __freed_request 1 0.0972 __inc_zone_page_state 1 0.0972 __inc_zone_state 1 0.0972 __init_rwsem 1 0.0972 __insert_inode_hash 1 0.0972 __lru_add_drain 1 0.0972 __make_request 1 0.0972 __mutex_lock_interruptible_slowpath 1 0.0972 __mutex_unlock_slowpath 1 0.0972 __pollwait 1 0.0972 __rcu_pending 1 0.0972 __sigqueue_alloc 1 0.0972 __sock_create 1 0.0972 __switch_to 1 0.0972 __tasklet_schedule 1 0.0972 __tcp_push_pending_frames 1 0.0972 __wake_up_common 1 0.0972 acpi_get_register 1 0.0972 acpi_hw_register_read 1 0.0972 acpi_os_write_port 1 0.0972 add_disk_randomness 1 0.0972 alloc_inode 1 0.0972 alloc_pid 1 0.0972 anon_vma_link 1 0.0972 anon_vma_prepare 1 0.0972 arch_setup_additional_pages 1 0.0972 bio_alloc_bioset 1 0.0972 bio_fs_destructor 1 0.0972 blk_plug_device 1 0.0972 blk_queue_bounce 1 0.0972 blk_recount_segments 1 0.0972 cache_alloc_refill 1 0.0972 call_rcu 1 0.0972 can_share_swap_page 1 0.0972 cfq_choose_req 1 0.0972 cfq_completed_request 1 0.0972 cfq_dispatch_requests 1 0.0972 cfq_init_prio_data 1 0.0972 cfq_may_queue 1 0.0972 cfq_remove_request 1 0.0972 cfq_service_tree_add 1 0.0972 cfq_set_request 1 0.0972 check_pgt_cache 1 0.0972 check_userspace 1 0.0972 cleanup_timers 1 0.0972 clear_user 1 0.0972 common_interrupt 1 0.0972 copy_from_user 1 0.0972 copy_thread_group_keys 1 0.0972 d_instantiate 1 0.0972 deactivate_task 1 0.0972 debug_mutex_free_waiter 1 0.0972 debug_mutex_lock_common 1 0.0972 del_timer 1 0.0972 dequeue_task 1 0.0972 dio_bio_add_page 1 0.0972 do_fork 1 0.0972 do_lookup 1 0.0972 do_munmap 1 0.0972 do_pipe 1 0.0972 do_sigaction 1 0.0972 do_sync_read 1 0.0972 do_timer 1 0.0972 do_wait 1 0.0972 dummy_capable 1 0.0972 dummy_file_alloc_security 1 0.0972 elf_map 1 0.0972 elv_dequeue_request 1 0.0972 elv_insert 1 0.0972 elv_merge 1 0.0972 elv_next_request 1 0.0972 exit_itimers 1 0.0972 expand_files 1 0.0972 fasync_helper 1 0.0972 fget_light 1 0.0972 filp_close 1 0.0972 find_mergeable_anon_vma 1 0.0972 find_vma_prev 1 0.0972 finish_wait 1 0.0972 fput 1 0.0972 free_page_and_swap_cache 1 0.0972 generic_file_aio_read 1 0.0972 generic_file_open 1 0.0972 generic_pipe_buf_pin 1 0.0972 generic_unplug_device 1 0.0972 get_nr_files 1 0.0972 get_user_pages 1 0.0972 getname 1 0.0972 half_md4_transform 1 0.0972 hrtimer_get_next_event 1 0.0972 hrtimer_interrupt 1 0.0972 hrtimer_start 1 0.0972 hrtimer_try_to_cancel 1 0.0972 idle_cpu 1 0.0972 init_new_context 1 0.0972 inotify_d_instantiate 1 0.0972 inotify_dentry_parent_queue_event 1 0.0972 inotify_inode_queue_event 1 0.0972 internal_add_timer 1 0.0972 ip_push_pending_frames 1 0.0972 ip_route_output_flow 1 0.0972 kernel_read 1 0.0972 kmem_cache_zalloc 1 0.0972 kref_put 1 0.0972 load_script 1 0.0972 lru_add_drain 1 0.0972 mark_page_accessed 1 0.0972 may_expand_vm 1 0.0972 may_open 1 0.0972 mempool_alloc 1 0.0972 mm_release 1 0.0972 mmput 1 0.0972 native_flush_tlb_single 1 0.0972 native_set_pte_at 1 0.0972 next_signal 1 0.0972 normal_poll 1 0.0972 nr_iowait 1 0.0972 open_exec 1 0.0972 page_add_new_anon_rmap 1 0.0972 pid_revalidate 1 0.0972 pipe_read 1 0.0972 posix_cpu_timers_exit 1 0.0972 prio_tree_replace 1 0.0972 proc_flush_task 1 0.0972 proc_sys_lookup_table_one 1 0.0972 quicklist_trim 1 0.0972 raise_softirq 1 0.0972 rb_first 1 0.0972 rb_next 1 0.0972 recalc_task_prio 1 0.0972 release_open_intent 1 0.0972 release_vm86_irqs 1 0.0972 resched_task 1 0.0972 restore_all 1 0.0972 rq_init 1 0.0972 run_posix_cpu_timers 1 0.0972 run_timer_softirq 1 0.0972 save_i387 1 0.0972 send_signal 1 0.0972 simple_read_from_buffer 1 0.0972 split_vma 1 0.0972 sys_brk 1 0.0972 sys_clone 1 0.0972 sys_dup2 1 0.0972 sys_lseek 1 0.0972 sys_mkdir 1 0.0972 sys_rt_sigaction 1 0.0972 sys_select 1 0.0972 syscall_exit_work 1 0.0972 system_call 1 0.0972 tick_do_broadcast 1 0.0972 tick_nohz_stop_sched_tick 1 0.0972 tick_sched_timer 1 0.0972 tty_ldisc_try 1 0.0972 tty_poll 1 0.0972 unlock_buffer 1 0.0972 update_process_times 1 0.0972 vfs_getattr 1 0.0972 vfs_mkdir 1 0.0972 vfs_read 1 0.0972 vfs_write 1 0.0972 vma_adjust 1 0.0972 vma_prio_tree_add 1 0.0972 vma_prio_tree_insert 1 0.0972 vsnprintf 1 0.0972 wake_up_bit