+ date Sat Aug 18 00:13:48 EEST 2007 + rm -rf /var/lib/oprofile/ + opcontrol --vmlinux=/home/jimis/dist/src/linux-2.6.22.3/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.02 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| %| ------------------ 3020 34.9902 vmlinux 1920 22.2454 libc-2.6.1.so 1274 14.7607 libpython2.5.so.1.0 1140 13.2082 perl 432 5.0052 mpop CPU_CLK_UNHALT...| samples| %| ------------------ 430 99.5370 mpop 2 0.4630 [vdso] (tgid:16432 range:0xb7f24000-0xb7f25000) 253 2.9313 bash CPU_CLK_UNHALT...| samples| %| ------------------ 252 99.6047 bash 1 0.3953 [vdso] (tgid:16796 range:0xb7fbe000-0xb7fbf000) 229 2.6532 ld-2.6.1.so 105 1.2165 libgnutls.so.13.3.0 49 0.5677 ISO8859-1.so 46 0.5330 libgcrypt.so.11.2.3 38 0.4403 libpthread-2.6.1.so 27 0.3128 badblocks CPU_CLK_UNHALT...| samples| %| ------------------ 17 62.9630 badblocks 8 29.6296 [vdso] (tgid:16297 range:0xb7fb6000-0xb7fb7000) 2 7.4074 [vdso] (tgid:16298 range:0xb7f62000-0xb7f63000) 20 0.2317 screen-4.0.3 CPU_CLK_UNHALT...| samples| %| ------------------ 19 95.0000 screen-4.0.3 1 5.0000 [vdso] (tgid:16282 range:0xb7f25000-0xb7f26000) 11 0.1274 slocate CPU_CLK_UNHALT...| samples| %| ------------------ 7 63.6364 slocate 4 36.3636 [vdso] (tgid:16652 range:0xb7efe000-0xb7eff000) 10 0.1159 imap-login CPU_CLK_UNHALT...| samples| %| ------------------ 8 80.0000 imap-login 2 20.0000 [vdso] (tgid:15896 range:0xb7ef9000-0xb7efa000) 9 0.1043 libncurses.so.5.6 8 0.0927 gawk 7 0.0811 grep 6 0.0695 python2.5 CPU_CLK_UNHALT...| samples| %| ------------------ 5 83.3333 [vdso] (tgid:16627 range:0xb7efd000-0xb7efe000) 1 16.6667 [vdso] (tgid:16686 range:0xb7fa7000-0xb7fa8000) 6 0.0695 libnetsnmp.so.15.0.0 5 0.0579 libext2fs.so.2.4 3 0.0348 dovecot CPU_CLK_UNHALT...| samples| %| ------------------ 2 66.6667 dovecot 1 33.3333 [vdso] (tgid:1923 range:0xb7ef7000-0xb7ef8000) 3 0.0348 sshd CPU_CLK_UNHALT...| samples| %| ------------------ 2 66.6667 sshd 1 33.3333 [vdso] (tgid:15858 range:0xb7f64000-0xb7f65000) 2 0.0232 libcrypto.so.0.9.8 1 0.0116 ls 1 0.0116 tr 1 0.0116 libpcre.so.0.0.1 1 0.0116 which 1 0.0116 libnetsnmpagent.so.15.0.0 1 0.0116 libnetsnmpmibs.so.15.0.0 1 0.0116 locale-archive 1 0.0116 dovecot-auth + echo + echo + echo + opreport -l /home/jimis/dist/src/linux-2.6.22.3/vmlinux CPU: PIII, speed 798.02 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 282 9.3377 pre_reset 231 7.6490 rpc_print_iostats 222 7.3510 do_syscall_trace 146 4.8344 ide_do_request 144 4.7682 dump_task_regs 131 4.3377 rpc_proc_open 122 4.0397 congestion_wait 98 3.2450 vsscanf 52 1.7219 __switch_to 33 1.0927 interruptible_sleep_on 32 1.0596 check_object 32 1.0596 setup_sigcontext 32 1.0596 sys_sigaction 31 1.0265 block_truncate_page 31 1.0265 sys_fadvise64_64 31 1.0265 test_set_page_writeback 28 0.9272 __blkdev_put 28 0.9272 sys_madvise 26 0.8609 svc_seq_show 22 0.7285 do_wp_page 22 0.7285 zap_pte 21 0.6954 dump_task_extended_fpu 21 0.6954 elv_next_request 21 0.6954 generic_file_buffered_write 21 0.6954 unmap_vmas 20 0.6623 zoneinfo_show 19 0.6291 __relay_reset 19 0.6291 cont_prepare_write 18 0.5960 bdget 18 0.5960 calibrate_delay 18 0.5960 do_page_fault 17 0.5629 ide_setup_pci_devices 17 0.5629 scsi_cmd_ioctl 17 0.5629 vsnprintf 16 0.5298 bd_release_from_disk 16 0.5298 bdev_clear_inode 16 0.5298 do_ide_setup_pci_device 15 0.4967 kobject_rename 14 0.4636 blkdev_close 14 0.4636 print_hex_dump 13 0.4305 access_process_vm 13 0.4305 do_open 12 0.3974 bio_split 12 0.3974 blk_release_queue 12 0.3974 do_sendfile 12 0.3974 handle_vm86_fault 11 0.3642 __handle_mm_fault 11 0.3642 bd_claim_by_disk 11 0.3642 rpc_proc_show 10 0.3311 __switch_to_xtra 10 0.3311 posix_cpu_nsleep 9 0.2980 do_generic_mapping_read 9 0.2980 install_file_pte 9 0.2980 sg_scsi_ioctl 9 0.2980 vgacon_startup 8 0.2649 do_mpage_readpage 8 0.2649 prio_tree_insert 8 0.2649 test_clear_page_writeback 7 0.2318 init_once 7 0.2318 locks_mandatory_area 7 0.2318 loop_alloc 7 0.2318 register_blkdev 7 0.2318 rt_mutex_setprio 6 0.1987 daemonize 6 0.1987 idedisk_check_hpa 6 0.1987 pcibios_setup 6 0.1987 ptrace_request 6 0.1987 shrink_zone 6 0.1987 sys_chdir 6 0.1987 sys_mq_open 5 0.1656 as_update_iohist 5 0.1656 blk_cleanup_queue 5 0.1656 blkdev_direct_IO 5 0.1656 current_io_context 5 0.1656 do_sysctl_strategy 5 0.1656 ext3_xattr_list 5 0.1656 ide_setup_ports 5 0.1656 igrab 5 0.1656 on_freelist 5 0.1656 register_chrdev 5 0.1656 show_partition 5 0.1656 vfs_rename 5 0.1656 vgacon_scrolldelta 5 0.1656 vm_normal_page 5 0.1656 vmtruncate 5 0.1656 write_cache_pages 4 0.1325 __free_slab 4 0.1325 __generic_file_aio_write_nolock 4 0.1325 __make_request 4 0.1325 bd_forget 4 0.1325 blk_queue_make_request 4 0.1325 dio_get_page 4 0.1325 dio_new_bio 4 0.1325 do_exit 4 0.1325 do_fcntl 4 0.1325 elv_insert 4 0.1325 generic_file_sendfile 4 0.1325 generic_ide_suspend 4 0.1325 generic_segment_checks 4 0.1325 init_idedisk_capacity 4 0.1325 kill_litter_super 4 0.1325 load_elf_binary 4 0.1325 lock_timer 4 0.1325 number 4 0.1325 page_cache_read 4 0.1325 prio_tree_next 4 0.1325 process_slab 4 0.1325 read_cache_page_async 4 0.1325 setup_irq 4 0.1325 sg_io 4 0.1325 sync_page_range 3 0.0993 __break_lease 3 0.0993 __end_that_request_first 3 0.0993 add_to_page_cache 3 0.0993 athlon_setup_ctrs 3 0.0993 blk_execute_rq_nowait 3 0.0993 blk_hw_contig_segment 3 0.0993 blk_ordered_req_seq 3 0.0993 blk_queue_find_tag 3 0.0993 blk_rq_map_kern 3 0.0993 cap_bprm_apply_creds 3 0.0993 cap_settime 3 0.0993 cap_task_post_setuid 3 0.0993 check_disk_change 3 0.0993 check_slab 3 0.0993 d_invalidate 3 0.0993 dio_cleanup 3 0.0993 dio_complete 3 0.0993 do_mremap 3 0.0993 do_sync_write 3 0.0993 do_wait 3 0.0993 elv_completed_request 3 0.0993 est_time_show 3 0.0993 ext3_xattr_set_handle 3 0.0993 fcntl_setlk 3 0.0993 generic_ide_ioctl 3 0.0993 ioctl_by_bdev 3 0.0993 iput 3 0.0993 ll_back_merge_fn 3 0.0993 lookup_bdev 3 0.0993 open_by_devnum 3 0.0993 page_address_in_vma 3 0.0993 pipe_write 3 0.0993 posix_cpu_nsleep_restart 3 0.0993 relay_file_read 3 0.0993 rpc_proc_init 3 0.0993 sb_min_blocksize 3 0.0993 sb_set_blocksize 3 0.0993 svc_proc_unregister 3 0.0993 sys_mprotect 3 0.0993 sys_mq_timedsend 3 0.0993 tcp_rcv_state_process 3 0.0993 vma_adjust 2 0.0662 __blk_free_tags 2 0.0662 __journal_abort_hard 2 0.0662 __journal_drop_transaction 2 0.0662 __pte_alloc 2 0.0662 __vmalloc_area_node 2 0.0662 add_to_page_cache_lru 2 0.0662 aio_complete 2 0.0662 alloc_node_mem_map 2 0.0662 as_can_break_anticipation 2 0.0662 as_choose_req 2 0.0662 as_read_batch_expire_store 2 0.0662 as_read_expire_store 2 0.0662 assign_all_busses 2 0.0662 background_writeout 2 0.0662 bdput 2 0.0662 bio_alloc_bioset 2 0.0662 bio_endio 2 0.0662 blk_alloc_queue_node 2 0.0662 blk_end_sync_rq 2 0.0662 blkdev_open 2 0.0662 block_uevent_filter 2 0.0662 cdev_del 2 0.0662 cdev_get 2 0.0662 copy_page_range 2 0.0662 dio_send_cur_page 2 0.0662 do_mmap_pgoff 2 0.0662 do_notify_parent 2 0.0662 do_sync_readv_writev 2 0.0662 early_serial_putc 2 0.0662 early_serial_write 2 0.0662 elevator_init 2 0.0662 elv_iosched_allow_merge 2 0.0662 elv_rq_merge_ok 2 0.0662 free_as_io_context 2 0.0662 generic_shutdown_super 2 0.0662 grab_cache_page_nowait 2 0.0662 hwif_request_region 2 0.0662 ide_pci_setup_ports 2 0.0662 ide_setup_pci_device 2 0.0662 ide_taskfile_ioctl 2 0.0662 idle_cpu 2 0.0662 journal_flush 2 0.0662 kmem_cache_create 2 0.0662 lookup_one_len 2 0.0662 mpage_readpages 2 0.0662 notify_arch_cmos_timer 2 0.0662 open_bdev_excl 2 0.0662 pirq_piix_set 2 0.0662 print_bad_pte 2 0.0662 prio_tree_remove 2 0.0662 proc_dodebug 2 0.0662 release_task 2 0.0662 rpc_proc_exit 2 0.0662 rtc_cmos_read 2 0.0662 send_sigio 2 0.0662 set_blocksize 2 0.0662 set_ksettings 2 0.0662 set_using_dma 2 0.0662 sha_transform 2 0.0662 show_schedstat 2 0.0662 sprint_symbol 2 0.0662 sys_faccessat 2 0.0662 sys_fchmodat 2 0.0662 sys_remap_file_pages 2 0.0662 sys_vm86old 2 0.0662 sysctl_head_next 2 0.0662 t_start 2 0.0662 throttle_vm_writeout 2 0.0662 vfs_ioctl 2 0.0662 vfs_mknod 2 0.0662 vfs_unlink 2 0.0662 vgacon_deinit 2 0.0662 vmalloc_sync_all 2 0.0662 wake_up_new_task 2 0.0662 write_boundary_block 2 0.0662 zone_watermark_ok 1 0.0331 __blk_put_request 1 0.0331 __blkdev_get 1 0.0331 __filemap_copy_from_user_iovec_inatomic 1 0.0331 __find_get_block 1 0.0331 __follow_mount 1 0.0331 __free_pages_ok 1 0.0331 __is_prefetch 1 0.0331 __netif_schedule 1 0.0331 __register_chrdev_region 1 0.0331 __remove_hrtimer 1 0.0331 __set_page_dirty_buffers 1 0.0331 __set_page_dirty_nobuffers 1 0.0331 add_timer_randomness 1 0.0331 arch_ptrace 1 0.0331 as_fifo_expired 1 0.0331 as_put_io_context 1 0.0331 as_trim 1 0.0331 badness 1 0.0331 bio_pair_end_2 1 0.0331 bitmap_find_free_region 1 0.0331 blk_free_tags 1 0.0331 blk_init_queue_node 1 0.0331 blk_ordered_cur_seq 1 0.0331 blk_queue_resize_tags 1 0.0331 blk_remove_plug 1 0.0331 blk_sync_queue 1 0.0331 blkdev_get_block 1 0.0331 calculate_totalreserve_pages 1 0.0331 cap_vm_enough_memory 1 0.0331 clocksource_watchdog 1 0.0331 complete 1 0.0331 complete_all 1 0.0331 congestion_wait_interruptible 1 0.0331 copy_process 1 0.0331 cpu_idle 1 0.0331 create_new_namespaces 1 0.0331 current_is_keventd 1 0.0331 dentry_open 1 0.0331 dma_declare_coherent_memory 1 0.0331 do_alignment_check 1 0.0331 do_coredump 1 0.0331 do_getitimer 1 0.0331 do_kern_mount 1 0.0331 do_munmap 1 0.0331 do_sched_setscheduler 1 0.0331 do_sync 1 0.0331 do_sync_read 1 0.0331 do_sys_poll 1 0.0331 do_sysctl 1 0.0331 do_syslog 1 0.0331 do_timer 1 0.0331 do_utimes 1 0.0331 drive_stat_acct 1 0.0331 dump_thread 1 0.0331 dup_fd 1 0.0331 elevator_alloc 1 0.0331 eligible_child 1 0.0331 elv_attr_store 1 0.0331 elv_rb_add 1 0.0331 elv_rb_del 1 0.0331 elv_unregister 1 0.0331 end_buffer_async_write 1 0.0331 expand_stack 1 0.0331 ext3_count_dirs 1 0.0331 ext3_new_blocks 1 0.0331 ext3_orphan_get 1 0.0331 ext3_rename 1 0.0331 ext3_xattr_block_set 1 0.0331 ext3_xattr_get 1 0.0331 ext3_xattr_set 1 0.0331 f_delown 1 0.0331 filemap_fdatawait 1 0.0331 filemap_nopage 1 0.0331 flush_old_exec 1 0.0331 flush_thread 1 0.0331 fn_hash_insert 1 0.0331 follow_mount 1 0.0331 force_sig_info_fault 1 0.0331 force_sigsegv 1 0.0331 frag_start 1 0.0331 free_fdtable_work 1 0.0331 freed_request 1 0.0331 generic_fillattr 1 0.0331 generic_ide_resume 1 0.0331 generic_permission 1 0.0331 generic_unplug_device 1 0.0331 get_request_wait 1 0.0331 get_signal_to_deliver 1 0.0331 get_symbol_offset 1 0.0331 ide_abort 1 0.0331 init_object 1 0.0331 init_tag_map 1 0.0331 inode_add_bytes 1 0.0331 insert_wq_barrier 1 0.0331 install_page 1 0.0331 interruptible_sleep_on_timeout 1 0.0331 ip_fragment 1 0.0331 itimer_get_remtime 1 0.0331 journal_start 1 0.0331 kill_anon_super 1 0.0331 kill_fasync 1 0.0331 kmem_ptr_validate 1 0.0331 kobject_register 1 0.0331 kobject_shadow_add 1 0.0331 kobject_uevent_env 1 0.0331 ktime_get_real 1 0.0331 link_path_walk 1 0.0331 lo_ioctl 1 0.0331 locks_insert_block 1 0.0331 log_do_checkpoint 1 0.0331 madvise_need_mmap_write 1 0.0331 memory_open 1 0.0331 mincore_page 1 0.0331 nobh_prepare_write 1 0.0331 normalize_rt_tasks 1 0.0331 out_of_memory 1 0.0331 page_mkclean 1 0.0331 pcibios_fixup_bus 1 0.0331 posix_cpu_timer_set 1 0.0331 posix_timer_event 1 0.0331 prepare_to_wait_exclusive 1 0.0331 prio_tree_left 1 0.0331 proc_pid_auxv 1 0.0331 proc_task_lookup 1 0.0331 profile_hits 1 0.0331 profile_task_exit 1 0.0331 ptrace_attach 1 0.0331 ptrace_detach 1 0.0331 ptrace_writedata 1 0.0331 put_io_context 1 0.0331 rb_first 1 0.0331 read_port 1 0.0331 read_profile 1 0.0331 red_zone_store 1 0.0331 register_posix_clock 1 0.0331 relay_file_mmap 1 0.0331 relay_file_open 1 0.0331 relay_file_read_consume 1 0.0331 relay_file_release 1 0.0331 release_console_sem 1 0.0331 reparent_thread 1 0.0331 request_irq 1 0.0331 rq_init 1 0.0331 run_local_timers 1 0.0331 run_posix_cpu_timers 1 0.0331 rw_copy_check_uvector 1 0.0331 rw_verify_area 1 0.0331 sched_exit 1 0.0331 sched_setscheduler 1 0.0331 send_group_sigqueue 1 0.0331 set_close_on_exec 1 0.0331 set_load_weight 1 0.0331 skge_set_coalesce 1 0.0331 sock_aio_write 1 0.0331 sock_sendmsg 1 0.0331 strcasecmp 1 0.0331 strncasecmp 1 0.0331 strstr 1 0.0331 subbuf_send_actor 1 0.0331 svc_proc_register 1 0.0331 sys_chroot 1 0.0331 sys_mincore 1 0.0331 sys_munlockall 1 0.0331 sys_openat 1 0.0331 sys_sendfile64 1 0.0331 sys_setfsuid 1 0.0331 sys_sysctl 1 0.0331 sys_tee 1 0.0331 sysfs_follow_link 1 0.0331 task_prio 1 0.0331 tcp_add_reno_sack 1 0.0331 timekeeping_resume 1 0.0331 try_acquire_console_sem 1 0.0331 uevent_helper_store 1 0.0331 unix_release_sock 1 0.0331 unregister_timer_hook 1 0.0331 unuse_table 1 0.0331 update_iter 1 0.0331 user_shm_lock 1 0.0331 vfs_mkdir 1 0.0331 vma_merge 1 0.0331 wait_on_page_writeback_range 1 0.0331 wait_on_retry_sync_kiocb 1 0.0331 wb_kupdate + date Sat Aug 18 00:16:13 EEST 2007