[parisc-linux] kernel profile

Paul Bame bame@fc.hp.com
Wed, 29 May 2002 23:52:28 -0600 (MDT)


I made the crude kernel profiler work and here's a trace from a b180
compiling the kernel sorted by #tics.  'man readprofile' to figure out
column 3.  I'll commit this stuff soon.  Enjoy!

 22214 total                                      0.0125
  4816 flush_user_icache_range_asm              133.7778
  4641 flush_user_dcache_range_asm              128.9167
  2672 clear_user_page_asm                       23.8571
   712 $lctu_done                                44.5000
   616 copy_user_page_asm                         4.0526
   542 handle_interruption                        0.4208
   486 $lctu_loop                                40.5000
   454 memset                                    14.1875
   436 flush_kernel_icache_page                   4.3600
   431 rmqueue                                    0.4705
   430 flush_kernel_dcache_page                   4.3000
   366 purge_kernel_dcache_page                   3.6600
   273 do_page_fault                              0.3500
   258 d_lookup                                   0.6719
   194 fimanyloop                                 9.7000
   193 find_vma_prev                              2.2976
   191 fdsync                                     9.5500
   185 link_path_walk                             0.0731
   173 zap_page_range                             0.1975
   131 __free_pages_ok                            0.1227
   129 do_anonymous_page                          0.3665
   111 $lcfu_done                                 9.2500
   109 intr_restore                               0.2412
   109 handle_mm_fault                            0.4007
   109 __down_read                                0.6646
   104 do_no_page                                 0.2281
   104 copy_page_range                            0.1884
   102 lru_cache_add                              0.5100
    94 memcpy                                     2.6111
    87 clear_page_tables                          0.4439
    86 syscall_restore                            0.3583
    85 __find_get_page                            1.2500
    85 $lcfu_loop                                 7.0833
    79 mark_page_accessed                         0.8229
    78 memcmp                                     0.9750
    77 filemap_nopage                             0.1119
    74 kmem_cache_alloc                           0.2342
    72 __free_pte                                 0.5143
    70 pte_alloc                                  0.3889
    69 kmem_cache_free                            0.3450
    58 __free_pages                               0.7632
    55 reschedule_idle                            0.0640
    54 fput                                       0.1378
    53 do_generic_file_read                       0.0418
    52 do_wp_page                                 0.0553
    51 __lru_cache_del                            0.3643
    50 __alloc_pages                              0.0926
    50 $lslen_loop                                4.1667
    49 update_mmu_cache                           0.3500
    43 do_brk                                     0.0741
    42 dput                                       0.0972
    39 set_page_dirty                             0.2786
    38 vm_enough_memory                           0.2375
    37 vfs_permission                             0.1063
    36 nr_free_pages                              0.6000
    36 fioneloop                                  4.5000
    35 path_init                                  0.0678
    34 exit_notify                                0.0431
    34 $lsfu_loop                                 2.1250
    33 __up_read                                  0.3750
    31 parisc_acctyp                              0.1490
    29 sys_brk                                    0.0993
    29 permission                                 0.3625
    29 _alloc_pages                               0.6591
    29 __down_write                               0.1859
    29 $lslen_done                                3.6250
    28 free_page_and_swap_cache                   0.2800
    26 syscall_exit                               0.9286
    25 memmove                                    0.3125
    25 get_empty_filp                             0.0753
    24 sys_read                                   0.0682
    23 open_namei                                 0.0148
    23 getname                                    0.0943
    22 find_vma                                   0.2500
    22 cached_lookup                              0.1774
    20 __mark_inode_dirty                         0.1220
    20 $lclu_done                                 1.2500
    19 get_unshared_area                          0.1357
    17 schedule                                   0.0179
    16 memscan                                    0.3636
    16 generic_file_read                          0.0476
    16 find_vma_prepare                           0.1333
    16 do_mmap_pgoff                              0.0125
    16 dentry_open                                0.0303
    15 intr_return                                0.7500
    15 get_unused_fd                              0.0341
    15 find_trylock_page                          0.1136
    15 fcntl_dirnotify                            0.0379
    14 unlock_page                                0.1000
    13 sys_open                                   0.0464
    12 sys_close                                  0.0732
    11 locks_remove_posix                         0.0289
    11 ext2_readdir                               0.0174
    11 cp_new_stat64                              0.0246
    11 copy_strings                               0.0519
    10 filp_close                                 0.0676
    10 do_munmap                                  0.0135
    10 do_fork                                    0.0048
    10 $lsfu_done                                 2.5000
     9 vma_merge                                  0.0288
     9 syscall_check_bh                           0.5625
     9 filldir64                                  0.0262
     9 fget                                       0.1406
     9 ext2_release_file                          0.2250
     8 update_atime                               0.0645
     8 kfree                                      0.0392
     8 Letext                                     0.2857
     7 locks_remove_flock                         0.0565
     7 load_elf_binary                            0.0027
     7 intr_check_resched                         0.5833
     7 generic_file_write                         0.0036
     7 generic_file_readahead                     0.0167
     7 exit_mmap                                  0.0148
     7 cp_new_stat                                0.0192
     7 copy_mm                                    0.0082
     7 activate_page                              0.0282
     7 __user_walk                                0.0530
     6 recycle_sids                               0.0517
     6 put_filp                                   0.0577
     6 intr_check_sig                             0.5000
     6 in_group_p                                 0.1154
     6 create_elf_tables                          0.0058
     6 count                                      0.0600
     6 __wake_up                                  0.0214
     5 vsnprintf                                  0.0023
     5 syscall_check_sig                          0.4167
     5 sys_newfstat                               0.0338
     5 supplemental_group_member                  0.0833
     5 rb_insert_color                            0.0167
     5 path_walk                                  0.1562
     5 net_rx_action                              0.0062
     5 lstrncpy_from_user                         0.1389
     5 load_elf_interp                            0.0069
     5 get_unmapped_area                          0.0312
     5 filp_open                                  0.0463
     5 file_read_actor                            0.0391
     5 do_mmap2                                   0.0236
     5 __block_prepare_write                      0.0056
     5 $$remU                                     0.0169
     4 sys_write                                  0.0114
     4 sys_rt_sigprocmask                         0.0108
     4 sys_mmap                                   0.0667
     4 scsi_dispatch_cmd                          0.0041
     4 proc_lookup                                0.0192
     4 number                                     0.0036
     4 n_tty_receive_buf                          0.0014
     4 meminfo_read_proc                          0.0052
     4 lru_cache_del                              0.1667
     4 kmalloc                                    0.0110
     4 ip_route_input                             0.0085
     4 ext2_new_block                             0.0014
     4 copy_string                                0.0233
     4 copy_files                                 0.0068
     4 can_share_swap_page                        0.0238
     4 $lclu_loop                                 0.5000
     3 syscall_restore_rfi                        0.1071
     3 syscall_check_resched                      0.3750
     3 sys_rt_sigaction                           0.0142
     3 sys_getdents64                             0.0160
     3 setup_rt_frame                             0.0029
     3 release_task                               0.0073
     3 put_files_struct                           0.0097
     3 path_release                               0.0357
     3 opost_block                                0.0059
     3 flush_old_exec                             0.0039
     3 flush_kernel_dcache_range_asm              0.0750
     3 find_extend_vma                            0.0117
     3 ext2_follow_link                           0.0750
     3 do_signal                                  0.0027
     3 do_exit                                    0.0046
     3 change_protection                          0.0043
     3 block_prepare_write                        0.0326
     3 arch_get_unmapped_area                     0.0250
     3 $lsfu_exit                                 0.1875
     2 vfs_follow_link                            0.0037
     2 tcp_poll                                   0.0052
     2 tcp_clean_rtx_queue                        0.0024
     2 sys_stat64                                 0.0125
     2 sys_select                                 0.0021
     2 sys_munmap                                 0.0192
     2 sys_mprotect                               0.0035
     2 sys_llseek                                 0.0079
     2 sys_clone                                  0.0714
     2 sock_poll                                  0.0357
     2 read_chan                                  0.0011
     2 read_cache_page                            0.0040
     2 pty_write                                  0.0047
     2 pipe_read                                  0.0031
     2 nfs3_xdr_sattrargs                         0.0007
     2 mod_timer                                  0.0083
     2 mark_buffer_dirty                          0.0185
     2 lock_vma_mappings                          0.5000
     2 get_kmapped_page                           0.0217
     2 generic_file_open                          0.0357
     2 free_sid                                   0.0156
     2 free_pgtables                              0.0122
     2 file_send_actor                            0.0082
     2 file_move                                  0.0455
     2 ext2_update_inode                          0.0016
     2 ext2_new_inode                             0.0017
     2 ext2_get_group_desc                        0.0111
     2 ext2_get_block                             0.0016
     2 ext2_free_blocks                           0.0019
     2 ext2_find_entry                            0.0055
     2 do_sigaction                               0.0068
     2 do_select                                  0.0034
     2 do_execve                                  0.0045
     2 discard_bh_page                            0.0098
     2 dev_queue_xmit_nit                         0.0083
     2 build_mmap_rb                              0.0161
     2 block_read_full_page                       0.0026
     2 alloc_sid                                  0.0055
     2 __rb_rotate_right                          0.0250
     2 __rb_erase_color                           0.0044
     2 __pollwait                                 0.0089
     2 $$remI                                     0.0061
     2 $$divU                                     0.0069
     2 $$divI                                     0.0061
     1 write_chan                                 0.0016
     1 wrapper_exit                               0.0109
     1 vfs_unlink                                 0.0021
     1 vfs_readdir                                0.0032
     1 unmap_fixup                                0.0021
     1 unlock_vma_mappings                        0.2500
     1 udp_rcv                                    0.0013
     1 tty_write                                  0.0015
     1 tty_poll                                   0.0049
     1 tcp_sendmsg                                0.0002
     1 tcp_ack                                    0.0012
     1 syscall_do_signal                          0.0064
     1 syscall_do_resched                         0.0625
     1 sys_wait4                                  0.0010
     1 sys_ioctl                                  0.0017
     1 sys_fstat64                                0.0068
     1 sys_dup2                                   0.0032
     1 strncpy                                    0.0312
     1 skb_clone                                  0.0022
     1 sk_run_filter                              0.0004
     1 si_meminfo                                 0.0132
     1 setup_sigcontext                           0.0022
     1 setup_arg_pages                            0.0018
     1 set_binfmt                                 0.0081
     1 set_bh_page                                0.0100
     1 select_bits_free                           0.0417
     1 search_binary_handler                      0.0025
     1 scsi_request_fn                            0.0012
     1 scsi_malloc                                0.0046
     1 refill_inactive                            0.0021
     1 real_lookup                                0.0028
     1 rb_erase                                   0.0033
     1 pty_write_room                             0.0156
     1 pipe_wait                                  0.0040
     1 pipe_release                               0.0042
     1 packet_rcv                                 0.0013
     1 nr_free_buffer_pages                       0.0139
     1 normal_poll                                0.0027
     1 mmput                                      0.0061
     1 mm_release                                 0.0192
     1 lstrnlen_user                              0.0278
     1 lookup_create                              0.0054
     1 locate_fd                                  0.0030
     1 lcopy_from_user                            0.0357
     1 kstat_read_proc                            0.0014
     1 iput                                       0.0016
     1 ip_rcv                                     0.0009
     1 ip_local_deliver                           0.0020
     1 insert_vm_struct                           0.0050
     1 inode_has_buffers                          0.0227
     1 inode_change_ok                            0.0021
     1 inet_recvmsg                               0.0100
     1 in_egroup_p                                0.0192
     1 getrusage                                  0.0017
     1 get_unused_buffer_head                     0.0056
     1 get_tty_driver                             0.0104
     1 get_pid                                    0.0028
     1 get_new_inode                              0.0019
     1 get_hash_table                             0.0057
     1 generic_make_request                       0.0023
     1 generic_file_mmap                          0.0083
     1 generic_file_llseek                        0.0052
     1 generic_file_direct_IO                     0.0016
     1 generic_commit_write                       0.0069
     1 free_pages                                 0.0147
     1 flush_signal_handlers                      0.0192
     1 flush_kernel_icache_range_asm              0.0147
     1 flush_data_cache_local                     0.0250
     1 flush_cache_all_local                      0.0357
     1 find_inode                                 0.0058
     1 fdtdone                                    0.0156
     1 ext2_prepare_write                         0.0208
     1 ext2_lookup                                0.0081
     1 ext2_get_page                              0.0074
     1 ext2_free_branches                         0.0023
     1 ext2_create                                0.0050
     1 ext2_alloc_block                           0.0060
     1 do_sigaltstack                             0.0028
     1 do_settimeofday                            0.0035
     1 do_pipe                                    0.0012
     1 discard_buffer                             0.0034
     1 del_timer                                  0.0208
     1 de_put                                     0.0078
     1 d_invalidate                               0.0071
     1 d_delete                                   0.0063
     1 d_alloc                                    0.0023
     1 create_empty_buffers                       0.0063
     1 compute_creds                              0.0024
     1 clean_inode                                0.0061
     1 bcopy                                      0.0278
     1 balance_dirty_state                        0.0069
     1 add_wait_queue                             0.0208
     1 add_timer                                  0.0038
     1 add_page_to_hash_queue                     0.0081
     1 _switch_to_ret                             0.0013
     1 _switch_to                                 0.0093
     1 __vma_link                                 0.0046
     1 __remove_from_lru_list                     0.0078
     1 __insert_vm_struct                         0.0066
     1 __insert_into_lru_list                     0.0044
     1 __get_free_pages                           0.0179
     1 __block_commit_write                       0.0027
     1 $lslen_nzero                               0.0625
     1 $$mulU                                     0.0019