+ date Fri Aug 17 20:17:46 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| %| ------------------ 10455 90.6922 vmlinux 410 3.5566 libc-2.6.1.so 239 2.0732 bash CPU_CLK_UNHALT...| samples| %| ------------------ 237 99.1632 bash 2 0.8368 [vdso] (tgid:12553 range:0xb7f81000-0xb7f82000) 190 1.6482 ld-2.6.1.so 121 1.0496 oprofiled 57 0.4944 ISO8859-1.so 10 0.0867 gawk 10 0.0867 badblocks CPU_CLK_UNHALT...| samples| %| ------------------ 6 60.0000 badblocks 4 40.0000 [vdso] (tgid:10904 range:0xb7eed000-0xb7eee000) 5 0.0434 grep 5 0.0434 locale-archive 4 0.0347 libext2fs.so.2.4 3 0.0260 libcrypto.so.0.9.8 3 0.0260 imap-login CPU_CLK_UNHALT...| samples| %| ------------------ 2 66.6667 imap-login 1 33.3333 [vdso] (tgid:1946 range:0xb7f17000-0xb7f18000) 2 0.0173 libncurses.so.5.6 2 0.0173 screen-4.0.3 2 0.0173 dovecot 2 0.0173 sshd 1 0.0087 cat 1 0.0087 mkdir 1 0.0087 rm 1 0.0087 libreadline.so.5.2 1 0.0087 libnetsnmp.so.15.0.0 1 0.0087 libnetsnmphelpers.so.15.0.0 1 0.0087 libnetsnmpmibs.so.15.0.0 1 0.0087 syslog-ng + 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 6879 65.7963 irq_handler 881 8.4266 kmem_cache_shrink 672 6.4275 create_kmalloc_cache 188 1.7982 congestion_wait 140 1.3391 pre_reset 104 0.9947 vsscanf 84 0.8034 do_syscall_trace 78 0.7461 ide_do_request 51 0.4878 assign_all_busses 46 0.4400 interruptible_sleep_on 35 0.3348 sys_fadvise64_64 33 0.3156 sys_madvise 30 0.2869 rtc_cmos_read 29 0.2774 do_wp_page 28 0.2678 kobject_rename 25 0.2391 check_object 25 0.2391 print_hex_dump 19 0.1817 __relay_reset 17 0.1626 setup_sigcontext 16 0.1530 vsnprintf 15 0.1435 ide_setup_pci_devices 15 0.1435 zoneinfo_show 14 0.1339 block_truncate_page 14 0.1339 test_set_page_writeback 13 0.1243 __blkdev_put 13 0.1243 cont_prepare_write 13 0.1243 load_elf_binary 12 0.1148 access_process_vm 12 0.1148 pcibios_setup 11 0.1052 __handle_mm_fault 11 0.1052 __switch_to 11 0.1052 do_ide_setup_pci_device 11 0.1052 dump_task_extended_fpu 11 0.1052 rpc_print_iostats 11 0.1052 zap_pte 10 0.0956 bdget 10 0.0956 blk_release_queue 10 0.0956 do_page_fault 10 0.0956 elv_next_request 10 0.0956 sys_mq_open 9 0.0861 bdev_clear_inode 9 0.0861 generic_file_buffered_write 9 0.0861 locks_mandatory_area 9 0.0861 scsi_cmd_ioctl 8 0.0765 bd_claim_by_disk 8 0.0765 bd_release_from_disk 8 0.0765 dump_task_regs 8 0.0765 fcntl_setlk 7 0.0670 blk_cleanup_queue 7 0.0670 do_mpage_readpage 7 0.0670 loop_alloc 7 0.0670 unmap_vmas 7 0.0670 vfs_rename 6 0.0574 __end_that_request_first 6 0.0574 bio_split 6 0.0574 blkdev_close 6 0.0574 do_generic_mapping_read 6 0.0574 do_mremap 6 0.0574 generic_ide_ioctl 6 0.0574 pirq_piix_set 6 0.0574 prio_tree_insert 6 0.0574 test_clear_page_writeback 5 0.0478 __break_lease 5 0.0478 __switch_to_xtra 5 0.0478 daemonize 5 0.0478 dio_complete 5 0.0478 flush_old_exec 5 0.0478 handle_vm86_fault 5 0.0478 kmem_cache_create 5 0.0478 pipe_write 5 0.0478 pirq_enable_irq 5 0.0478 process_slab 5 0.0478 read_cache_page_async 5 0.0478 vfs_mkdir 4 0.0383 __blk_put_request 4 0.0383 add_to_page_cache 4 0.0383 cap_inode_removexattr 4 0.0383 cap_task_post_setuid 4 0.0383 dio_cleanup 4 0.0383 dio_get_page 4 0.0383 do_kern_mount 4 0.0383 do_mmap_pgoff 4 0.0383 do_notify_parent 4 0.0383 do_sendfile 4 0.0383 do_sysctl 4 0.0383 do_sysctl_strategy 4 0.0383 ext3_xattr_set_handle 4 0.0383 generic_write_checks 4 0.0383 idedisk_check_hpa 4 0.0383 interruptible_sleep_on_timeout 4 0.0383 log_do_checkpoint 4 0.0383 pcibios_scan_root 4 0.0383 print_bad_pte 4 0.0383 ptrace_request 4 0.0383 send_group_sigqueue 4 0.0383 set_using_dma 4 0.0383 sys_remap_file_pages 4 0.0383 vmtruncate 3 0.0287 __pte_alloc 3 0.0287 alloc_node_mem_map 3 0.0287 as_read_expire_store 3 0.0287 bitmap_find_free_region 3 0.0287 check_disk_change 3 0.0287 do_open 3 0.0287 do_sync 3 0.0287 do_sync_readv_writev 3 0.0287 early_serial_putc 3 0.0287 generic_ide_resume 3 0.0287 generic_shutdown_super 3 0.0287 ide_intr 3 0.0287 ide_register_hw_with_fixup 3 0.0287 install_file_pte 3 0.0287 install_page 3 0.0287 kobject_get_path 3 0.0287 ll_back_merge_fn 3 0.0287 lock_timer 3 0.0287 mpage_readpages 3 0.0287 on_freelist 3 0.0287 posix_cpu_nsleep 3 0.0287 rb_erase 3 0.0287 release_task 3 0.0287 rt_mutex_setprio 3 0.0287 sched_exit 3 0.0287 sg_scsi_ioctl 3 0.0287 show_partition 3 0.0287 show_schedstat 3 0.0287 sprint_symbol 3 0.0287 svc_seq_show 3 0.0287 sync_page_range 3 0.0287 sys_chown 3 0.0287 sys_mincore 3 0.0287 sys_mprotect 3 0.0287 sys_statfs 3 0.0287 sys_tee 3 0.0287 wake_up_new_task 2 0.0191 __elv_add_request 2 0.0191 __is_prefetch 2 0.0191 __register_chrdev_region 2 0.0191 account_steal_time 2 0.0191 apply_microcode 2 0.0191 as_antic_expire_store 2 0.0191 bd_forget 2 0.0191 bdput 2 0.0191 blk_init_queue_node 2 0.0191 blk_recount_segments 2 0.0191 blkdev_open 2 0.0191 calibrate_delay 2 0.0191 clear_page_dirty_for_io 2 0.0191 copy_page_range 2 0.0191 copy_process 2 0.0191 d_invalidate 2 0.0191 dentry_open 2 0.0191 dio_new_bio 2 0.0191 dio_send_cur_page 2 0.0191 do_brk 2 0.0191 do_msgrcv 2 0.0191 do_sync_read 2 0.0191 do_utimes 2 0.0191 dx_probe 2 0.0191 elv_insert 2 0.0191 elv_iosched_allow_merge 2 0.0191 elv_rb_del 2 0.0191 est_time_show 2 0.0191 exit_itimers 2 0.0191 ext3_free_inode 2 0.0191 ext3_mknod 2 0.0191 ext3_new_inode 2 0.0191 ext3_orphan_get 2 0.0191 filemap_nopage 2 0.0191 find_mergeable_anon_vma 2 0.0191 free_as_io_context 2 0.0191 freed_request 2 0.0191 get_signal_to_deliver 2 0.0191 get_timestamp 2 0.0191 grab_cache_page_nowait 2 0.0191 handle_stop_signal 2 0.0191 hrtimer_run_queues 2 0.0191 init_once 2 0.0191 inode_wait 2 0.0191 kobject_move 2 0.0191 kobject_shadow_rename 2 0.0191 load_elf_library 2 0.0191 lookup_address 2 0.0191 number 2 0.0191 open_namei 2 0.0191 pcibios_enable_device 2 0.0191 pcibios_fixup_bus 2 0.0191 poison_store 2 0.0191 prio_tree_next 2 0.0191 prio_tree_remove 2 0.0191 put_io_context 2 0.0191 read_port 2 0.0191 register_blkdev 2 0.0191 register_chrdev 2 0.0191 reiserfs_delete_xattrs 2 0.0191 reiserfs_xattr_init 2 0.0191 relay_destroy_buf 2 0.0191 request_irq 2 0.0191 rpc_proc_exit 2 0.0191 rpc_proc_init 2 0.0191 rpc_proc_open 2 0.0191 rw_copy_check_uvector 2 0.0191 sb_set_blocksize 2 0.0191 sched_setscheduler 2 0.0191 simple_strtoul 2 0.0191 skge_probe 2 0.0191 slab_pad_check 2 0.0191 strcspn 2 0.0191 sys_chdir 2 0.0191 sys_faccessat 2 0.0191 sys_fchdir 2 0.0191 sys_mq_timedreceive 2 0.0191 sys_mq_timedsend 2 0.0191 sys_openat 2 0.0191 sys_sync_file_range 2 0.0191 t_start 2 0.0191 tick_notify 2 0.0191 timer_list_show 2 0.0191 try_to_wake_up 2 0.0191 vfs_mknod 2 0.0191 vgacon_startup 2 0.0191 vm_normal_page 2 0.0191 vmtruncate_range 2 0.0191 zone_watermark_ok 1 0.0096 __activate_task 1 0.0096 __blockdev_direct_IO 1 0.0096 __call_usermodehelper 1 0.0096 __cleanup_sighand 1 0.0096 __d_lookup 1 0.0096 __ext3_get_inode_loc 1 0.0096 __free_pages_ok 1 0.0096 __free_slab 1 0.0096 __generic_file_aio_write_nolock 1 0.0096 __journal_drop_transaction 1 0.0096 __link_path_walk 1 0.0096 __posix_lock_file 1 0.0096 __ptrace_link 1 0.0096 __remove_shared_vm_struct 1 0.0096 __round_jiffies_relative 1 0.0096 __sigqueue_alloc 1 0.0096 __vma_link_rb 1 0.0096 __vmalloc_area_node 1 0.0096 __wait_on_freeing_inode 1 0.0096 __wake_up_common 1 0.0096 arch_ptrace 1 0.0096 arp_ioctl 1 0.0096 as_can_break_anticipation 1 0.0096 as_choose_req 1 0.0096 athlon_setup_ctrs 1 0.0096 background_writeout 1 0.0096 badness 1 0.0096 bd_get_sb 1 0.0096 bio_alloc_bioset 1 0.0096 blk_execute_rq_nowait 1 0.0096 blk_hw_contig_segment 1 0.0096 blk_ordered_complete_seq 1 0.0096 blk_ordered_cur_seq 1 0.0096 blk_ordered_req_seq 1 0.0096 blk_queue_find_tag 1 0.0096 blk_queue_make_request 1 0.0096 blk_queue_resize_tags 1 0.0096 blk_sync_queue 1 0.0096 blkdev_get_block 1 0.0096 blkdev_get_blocks 1 0.0096 block_fsync 1 0.0096 block_invalidatepage 1 0.0096 block_uevent_filter 1 0.0096 call_filldir 1 0.0096 call_usermodehelper_pipe 1 0.0096 cap_bprm_apply_creds 1 0.0096 cap_bprm_secureexec 1 0.0096 cap_capset_check 1 0.0096 cap_inode_setxattr 1 0.0096 cap_settime 1 0.0096 cap_vm_enough_memory 1 0.0096 cdev_get 1 0.0096 chrdev_show 1 0.0096 clockevents_set_mode 1 0.0096 common_timer_get 1 0.0096 complete_all 1 0.0096 congestion_wait_interruptible 1 0.0096 cp_old_stat 1 0.0096 cpu_idle 1 0.0096 current_io_context 1 0.0096 d_rehash 1 0.0096 deactivate_slab 1 0.0096 dev_kfree_skb_any 1 0.0096 discard_slab 1 0.0096 dma_declare_coherent_memory 1 0.0096 do_fcntl 1 0.0096 do_getitimer 1 0.0096 do_lookup 1 0.0096 do_munmap 1 0.0096 do_sched_setscheduler 1 0.0096 do_tkill 1 0.0096 do_wait 1 0.0096 drive_stat_acct 1 0.0096 dupfd 1 0.0096 elevator_alloc 1 0.0096 elevator_init 1 0.0096 elf_core_dump 1 0.0096 elv_attr_store 1 0.0096 elv_completed_request 1 0.0096 elv_unregister 1 0.0096 end_buffer_async_write 1 0.0096 expand_files 1 0.0096 expand_stack 1 0.0096 ext3_count_dirs 1 0.0096 ext3_find_entry 1 0.0096 ext3_free_blocks_sb 1 0.0096 ext3_getblk 1 0.0096 ext3_group_add 1 0.0096 ext3_new_blocks 1 0.0096 ext3_read_inode 1 0.0096 ext3_reserve_inode_write 1 0.0096 ext3_setattr 1 0.0096 ext3_symlink 1 0.0096 ext3_try_to_allocate 1 0.0096 ext3_xattr_block_set 1 0.0096 ext3_xattr_get 1 0.0096 ext3_xattr_trusted_list 1 0.0096 ext3_xattr_user_set 1 0.0096 file_send_actor 1 0.0096 filemap_fdatawait 1 0.0096 find_extend_vma 1 0.0096 follow_up 1 0.0096 force_sig_info_fault 1 0.0096 frag_show 1 0.0096 free_compound_page 1 0.0096 free_pgd_range 1 0.0096 generic_drop_inode 1 0.0096 generic_permission 1 0.0096 generic_unplug_device 1 0.0096 get_request_wait 1 0.0096 get_symbol_pos 1 0.0096 getname 1 0.0096 ide_hwif_request_regions 1 0.0096 ide_mm_inb 1 0.0096 ide_pci_setup_ports 1 0.0096 ide_setup_ports 1 0.0096 init_idedisk_capacity 1 0.0096 init_object 1 0.0096 init_request_from_bio 1 0.0096 init_tag_map 1 0.0096 inode_sub_bytes 1 0.0096 invalidate_bh_lrus 1 0.0096 ioctl_by_bdev 1 0.0096 ip_fragment 1 0.0096 iput 1 0.0096 it_real_fn 1 0.0096 itimer_get_remtime 1 0.0096 journal_commit_transaction 1 0.0096 journal_recover 1 0.0096 journal_refile_buffer 1 0.0096 kill_anon_super 1 0.0096 kill_litter_super 1 0.0096 kmem_cache_destroy 1 0.0096 kobject_shadow_add 1 0.0096 ll_rw_block 1 0.0096 lo_ioctl 1 0.0096 lookup_bdev 1 0.0096 may_attach 1 0.0096 may_open 1 0.0096 mb_cache_create 1 0.0096 mb_cache_entry_insert 1 0.0096 microcode_write 1 0.0096 modify_acceptable_latency 1 0.0096 mounts_release 1 0.0096 mpage_end_io_write 1 0.0096 neigh_resolve_output 1 0.0096 netif_rx 1 0.0096 nobh_prepare_write 1 0.0096 notify_arch_cmos_timer 1 0.0096 open_by_devnum 1 0.0096 p4_fill_in_addresses 1 0.0096 page_address_in_vma 1 0.0096 pci_write 1 0.0096 pcibios_assign_all_busses 1 0.0096 pcibios_disable_device 1 0.0096 pdflush 1 0.0096 pipe_read_open 1 0.0096 pirq_serverworks_set 1 0.0096 poll_idle 1 0.0096 posix_cpu_timer_del 1 0.0096 posix_timer_event 1 0.0096 prepare_to_wait_exclusive 1 0.0096 proc_dodebug 1 0.0096 proc_pid_auxv 1 0.0096 proc_task_lookup 1 0.0096 ptrace_attach 1 0.0096 ptrace_writedata 1 0.0096 raw_bind 1 0.0096 read_chan 1 0.0096 register_chrdev_region 1 0.0096 reiserfs_listxattr 1 0.0096 relay_file_mmap 1 0.0096 relay_file_open 1 0.0096 rpc_mkdir 1 0.0096 rpc_proc_show 1 0.0096 rt_check_expire 1 0.0096 run_local_timers 1 0.0096 run_posix_cpu_timers 1 0.0096 sb_min_blocksize 1 0.0096 schedule_tail 1 0.0096 set_blocksize 1 0.0096 set_ksettings 1 0.0096 set_load_weight 1 0.0096 setup_arg_pages 1 0.0096 setup_irq 1 0.0096 sg_io 1 0.0096 show_regs 1 0.0096 show_stat 1 0.0096 show_state_filter 1 0.0096 shrink_zone 1 0.0096 skb_checksum 1 0.0096 skge_get_coalesce 1 0.0096 skge_poll 1 0.0096 skge_set_coalesce 1 0.0096 sock_aio_write 1 0.0096 strnicmp 1 0.0096 svc_proc_register 1 0.0096 sys_chroot 1 0.0096 sys_fchmodat 1 0.0096 sys_get_thread_area 1 0.0096 sys_getcwd 1 0.0096 sys_mknodat 1 0.0096 sys_mlockall 1 0.0096 sys_nice 1 0.0096 sys_renameat 1 0.0096 sys_sched_get_priority_min 1 0.0096 sys_sendfile 1 0.0096 sys_sendfile64 1 0.0096 sys_timer_settime 1 0.0096 sys_uselib 1 0.0096 sysctl_head_next 1 0.0096 sysfs_follow_link 1 0.0096 sysfs_slab_add 1 0.0096 tcp_enter_quickack_mode 1 0.0096 tcp_v4_connect 1 0.0096 tcp_v4_hash 1 0.0096 throttle_vm_writeout 1 0.0096 trace 1 0.0096 udp_lib_unhash 1 0.0096 udp_proc_unregister 1 0.0096 uevent_helper_store 1 0.0096 unmap_mapping_range_vma 1 0.0096 unuse_table 1 0.0096 user_shm_unlock 1 0.0096 validate_store 1 0.0096 vfs_ioctl 1 0.0096 vgacon_scrolldelta 1 0.0096 vm_stat_account 1 0.0096 vma_adjust 1 0.0096 vprintk 1 0.0096 wait_for_helper 1 0.0096 wait_for_partner 1 0.0096 wait_on_retry_sync_kiocb 1 0.0096 wq_sleep 1 0.0096 write_boundary_block 1 0.0096 write_cache_pages + date Fri Aug 17 20:17:53 EEST 2007