onak.gprof

   1 Flat profile:
   2
   3 Each sample counts as 0.01 seconds.
   4   %   cumulative   self              self     total
   5  time   seconds   seconds    calls  us/call  us/call  name
   6  44.57      0.41     0.41    44208     9.27    14.02  transform
   7  22.83      0.62     0.21  9902592     0.02     0.02  rol
   8   9.78      0.71     0.09    31610     2.85    19.66  sha1_write
   9   7.61      0.78     0.07    33623     2.08     2.08  stdin_getchar
  10   2.17      0.80     0.02     6937     2.88   106.67  get_keyid
  11   2.17      0.82     0.02     1690    11.83    17.75  write_openpgp_stream
  12   2.17      0.84     0.02                             Letext
  13   1.09      0.85     0.01    30179     0.33     0.33  keydb_putchar
  14   1.09      0.86     0.01    17848     0.56     0.56  packet_dup
  15   1.09      0.87     0.01     6322     1.58    35.26  sha1_final
  16   1.09      0.88     0.01     1690     5.92     8.88  flatten_publickey
  17   1.09      0.89     0.01     1690     5.92     5.92  keyuids
  18   1.09      0.90     0.01     1690     5.92   262.98  store_key
  19   1.09      0.91     0.01        1 10000.00 10000.00  free_publickey
  20   1.09      0.92     0.01        1 10000.00 815000.00  update_keys
  21   0.00      0.92     0.00    17848     0.00     0.00  free_packet
  22   0.00      0.92     0.00     6322     0.00     0.00  sha1_init
  23   0.00      0.92     0.00     6322     0.00     0.00  sha1_read
  24   0.00      0.92     0.00     3438     0.00     0.00  free_packet_list
  25   0.00      0.92     0.00     3227     0.00     0.00  free_signedpacket_list
  26   0.00      0.92     0.00     1690     0.00     0.00  endtrans
  27   0.00      0.92     0.00     1690     0.00     0.00  fetch_key
  28   0.00      0.92     0.00     1690     0.00     0.00  spsize
  29   0.00      0.92     0.00     1690     0.00     0.00  starttrans
  30   0.00      0.92     0.00        1     0.00     0.00  cleanupdb
  31   0.00      0.92     0.00        1     0.00     0.00  initdb
  32   0.00      0.92     0.00        1     0.00  5000.00  parse_keys
  33   0.00      0.92     0.00        1     0.00 70000.00  read_openpgp_stream
  34
  35  %         the percentage of the total running time of the
  36 time       program used by this function.
  37
  38 cumulative a running sum of the number of seconds accounted
  39  seconds   for by this function and those listed above it.
  40
  41  self      the number of seconds accounted for by this
  42 seconds    function alone.  This is the major sort for this
  43            listing.
  44
  45 calls      the number of times this function was invoked, if
  46            this function is profiled, else blank.
  47
  48  self      the average number of milliseconds spent in this
  49 ms/call    function per call, if this function is profiled,
  50            else blank.
  51
  52  total     the average number of milliseconds spent in this
  53 ms/call    function and its descendents per call, if this
  54            function is profiled, else blank.
  55
  56 name       the name of the function.  This is the minor sort
  57            for this listing. The index shows the location of
  58            the function in the gprof listing. If the index is
  59            in parenthesis it shows where it would appear in
  60            the gprof listing if it were to be printed.
  61 \f
  62                      Call graph (explanation follows)
  63
  64
  65 granularity: each sample hit covers 4 byte(s) for 1.09% of 0.92 seconds
  66
  67 index % time    self  children    called     name
  68                                                  <spontaneous>
  69 [1]     97.8    0.00    0.90                 main [1]
  70                 0.01    0.81       1/1           update_keys [2]
  71                 0.00    0.07       1/1           read_openpgp_stream [10]
  72                 0.01    0.00       1/1           free_publickey [17]
  73                 0.00    0.01       1/1           parse_keys [18]
  74                 0.00    0.00       1/3438        free_packet_list [22]
  75                 0.00    0.00       1/1           initdb [29]
  76                 0.00    0.00       1/1           cleanupdb [28]
  77 -----------------------------------------------
  78                 0.01    0.81       1/1           main [1]
  79 [2]     88.6    0.01    0.81       1         update_keys [2]
  80                 0.01    0.43    1690/1690        store_key [6]
  81                 0.01    0.35    3380/6937        get_keyid [3]
  82                 0.00    0.00    1690/1690        starttrans [27]
  83                 0.00    0.00    1690/1690        fetch_key [25]
  84                 0.00    0.00    1690/1690        endtrans [24]
  85 -----------------------------------------------
  86                 0.01    0.35    3380/6937        update_keys [2]
  87                 0.01    0.37    3557/6937        store_key [6]
  88 [3]     80.4    0.02    0.72    6937         get_keyid [3]
  89                 0.07    0.43   25288/31610       sha1_write [4]
  90                 0.01    0.21    6322/6322        sha1_final [7]
  91                 0.00    0.00    6322/6322        sha1_init [20]
  92                 0.00    0.00    6322/6322        sha1_read [21]
  93 -----------------------------------------------
  94                                18966             sha1_write [4]
  95                 0.02    0.11    6322/31610       sha1_final [7]
  96                 0.07    0.43   25288/31610       get_keyid [3]
  97 [4]     67.5    0.09    0.53   31610+18966   sha1_write [4]
  98                 0.35    0.18   37886/44208       transform [5]
  99                                18966             sha1_write [4]
 100 -----------------------------------------------
 101                 0.06    0.03    6322/44208       sha1_final [7]
 102                 0.35    0.18   37886/44208       sha1_write [4]
 103 [5]     67.4    0.41    0.21   44208         transform [5]
 104                 0.21    0.00 9902592/9902592     rol [8]
 105 -----------------------------------------------
 106                 0.01    0.43    1690/1690        update_keys [2]
 107 [6]     48.3    0.01    0.43    1690         store_key [6]
 108                 0.01    0.37    3557/6937        get_keyid [3]
 109                 0.02    0.01    1690/1690        write_openpgp_stream [11]
 110                 0.01    0.01    1690/1690        flatten_publickey [13]
 111                 0.01    0.00    1690/1690        keyuids [16]
 112 -----------------------------------------------
 113                 0.01    0.21    6322/6322        get_keyid [3]
 114 [7]     24.2    0.01    0.21    6322         sha1_final [7]
 115                 0.02    0.11    6322/31610       sha1_write [4]
 116                 0.06    0.03    6322/44208       transform [5]
 117 -----------------------------------------------
 118                 0.21    0.00 9902592/9902592     transform [5]
 119 [8]     22.8    0.21    0.00 9902592         rol [8]
 120 -----------------------------------------------
 121                 0.07    0.00   33623/33623       read_openpgp_stream [10]
 122 [9]      7.6    0.07    0.00   33623         stdin_getchar [9]
 123 -----------------------------------------------
 124                 0.00    0.07       1/1           main [1]
 125 [10]     7.6    0.00    0.07       1         read_openpgp_stream [10]
 126                 0.07    0.00   33623/33623       stdin_getchar [9]
 127 -----------------------------------------------
 128                 0.02    0.01    1690/1690        store_key [6]
 129 [11]     3.3    0.02    0.01    1690         write_openpgp_stream [11]
 130                 0.01    0.00   30179/30179       keydb_putchar [14]
 131 -----------------------------------------------
 132                                                  <spontaneous>
 133 [12]     2.2    0.02    0.00                 Letext [12]
 134 -----------------------------------------------
 135                 0.01    0.01    1690/1690        store_key [6]
 136 [13]     1.6    0.01    0.01    1690         flatten_publickey [13]
 137                 0.01    0.00    8924/17848       packet_dup [15]
 138 -----------------------------------------------
 139                 0.01    0.00   30179/30179       write_openpgp_stream [11]
 140 [14]     1.1    0.01    0.00   30179         keydb_putchar [14]
 141 -----------------------------------------------
 142                 0.01    0.00    8924/17848       parse_keys [18]
 143                 0.01    0.00    8924/17848       flatten_publickey [13]
 144 [15]     1.1    0.01    0.00   17848         packet_dup [15]
 145 -----------------------------------------------
 146                 0.01    0.00    1690/1690        store_key [6]
 147 [16]     1.1    0.01    0.00    1690         keyuids [16]
 148                 0.00    0.00    1690/1690        spsize [26]
 149 -----------------------------------------------
 150                 0.01    0.00       1/1           main [1]
 151 [17]     1.1    0.01    0.00       1         free_publickey [17]
 152                 0.00    0.00    3227/3227        free_signedpacket_list [23]
 153                 0.00    0.00    1690/17848       free_packet [19]
 154                 0.00    0.00      41/3438        free_packet_list [22]
 155 -----------------------------------------------
 156                 0.00    0.01       1/1           main [1]
 157 [18]     0.5    0.00    0.01       1         parse_keys [18]
 158                 0.01    0.00    8924/17848       packet_dup [15]
 159 -----------------------------------------------
 160                 0.00    0.00    1690/17848       free_publickey [17]
 161                 0.00    0.00    3410/17848       free_signedpacket_list [23]
 162                 0.00    0.00   12748/17848       free_packet_list [22]
 163 [19]     0.0    0.00    0.00   17848         free_packet [19]
 164 -----------------------------------------------
 165                 0.00    0.00    6322/6322        get_keyid [3]
 166 [20]     0.0    0.00    0.00    6322         sha1_init [20]
 167 -----------------------------------------------
 168                 0.00    0.00    6322/6322        get_keyid [3]
 169 [21]     0.0    0.00    0.00    6322         sha1_read [21]
 170 -----------------------------------------------
 171                 0.00    0.00       1/3438        main [1]
 172                 0.00    0.00      41/3438        free_publickey [17]
 173                 0.00    0.00    3396/3438        free_signedpacket_list [23]
 174 [22]     0.0    0.00    0.00    3438         free_packet_list [22]
 175                 0.00    0.00   12748/17848       free_packet [19]
 176 -----------------------------------------------
 177                 0.00    0.00    3227/3227        free_publickey [17]
 178 [23]     0.0    0.00    0.00    3227         free_signedpacket_list [23]
 179                 0.00    0.00    3410/17848       free_packet [19]
 180                 0.00    0.00    3396/3438        free_packet_list [22]
 181 -----------------------------------------------
 182                 0.00    0.00    1690/1690        update_keys [2]
 183 [24]     0.0    0.00    0.00    1690         endtrans [24]
 184 -----------------------------------------------
 185                 0.00    0.00    1690/1690        update_keys [2]
 186 [25]     0.0    0.00    0.00    1690         fetch_key [25]
 187 -----------------------------------------------
 188                 0.00    0.00    1690/1690        keyuids [16]
 189 [26]     0.0    0.00    0.00    1690         spsize [26]
 190 -----------------------------------------------
 191                 0.00    0.00    1690/1690        update_keys [2]
 192 [27]     0.0    0.00    0.00    1690         starttrans [27]
 193 -----------------------------------------------
 194                 0.00    0.00       1/1           main [1]
 195 [28]     0.0    0.00    0.00       1         cleanupdb [28]
 196 -----------------------------------------------
 197                 0.00    0.00       1/1           main [1]
 198 [29]     0.0    0.00    0.00       1         initdb [29]
 199 -----------------------------------------------
 200
 201  This table describes the call tree of the program, and was sorted by
 202  the total amount of time spent in each function and its children.
 203
 204  Each entry in this table consists of several lines.  The line with the
 205  index number at the left hand margin lists the current function.
 206  The lines above it list the functions that called this function,
 207  and the lines below it list the functions this one called.
 208  This line lists:
 209      index      A unique number given to each element of the table.
 210                 Index numbers are sorted numerically.
 211                 The index number is printed next to every function name so
 212                 it is easier to look up where the function in the table.
 213
 214      % time     This is the percentage of the `total' time that was spent
 215                 in this function and its children.  Note that due to
 216                 different viewpoints, functions excluded by options, etc,
 217                 these numbers will NOT add up to 100%.
 218
 219      self       This is the total amount of time spent in this function.
 220
 221      children   This is the total amount of time propagated into this
 222                 function by its children.
 223
 224      called     This is the number of times the function was called.
 225                 If the function called itself recursively, the number
 226                 only includes non-recursive calls, and is followed by
 227                 a `+' and the number of recursive calls.
 228
 229      name       The name of the current function.  The index number is
 230                 printed after it.  If the function is a member of a
 231                 cycle, the cycle number is printed between the
 232                 function's name and the index number.
 233
 234
 235  For the function's parents, the fields have the following meanings:
 236
 237      self       This is the amount of time that was propagated directly
 238                 from the function into this parent.
 239
 240      children   This is the amount of time that was propagated from
 241                 the function's children into this parent.
 242
 243      called     This is the number of times this parent called the
 244                 function `/' the total number of times the function
 245                 was called.  Recursive calls to the function are not
 246                 included in the number after the `/'.
 247
 248      name       This is the name of the parent.  The parent's index
 249                 number is printed after it.  If the parent is a
 250                 member of a cycle, the cycle number is printed between
 251                 the name and the index number.
 252
 253  If the parents of the function cannot be determined, the word
 254  `<spontaneous>' is printed in the `name' field, and all the other
 255  fields are blank.
 256
 257  For the function's children, the fields have the following meanings:
 258
 259      self       This is the amount of time that was propagated directly
 260                 from the child into the function.
 261
 262      children   This is the amount of time that was propagated from the
 263                 child's children to the function.
 264
 265      called     This is the number of times the function called
 266                 this child `/' the total number of times the child
 267                 was called.  Recursive calls by the child are not
 268                 listed in the number after the `/'.
 269
 270      name       This is the name of the child.  The child's index
 271                 number is printed after it.  If the child is a
 272                 member of a cycle, the cycle number is printed
 273                 between the name and the index number.
 274
 275  If there are any cycles (circles) in the call graph, there is an
 276  entry for the cycle-as-a-whole.  This entry shows who called the
 277  cycle (as parents) and the members of the cycle (as children.)
 278  The `+' recursive calls entry shows the number of function calls that
 279  were internal to the cycle, and the calls entry for each member shows,
 280  for that member, how many times it was called from other members of
 281  the cycle.
 282
 283 \f
 284 Index by function name
 285
 286   [12] Letext (bithelp.h)     [29] initdb                  [4] sha1_write
 287   [28] cleanupdb              [14] keydb_putchar (keydb_pg.c) [26] spsize
 288   [24] endtrans               [16] keyuids                [27] starttrans
 289   [25] fetch_key              [15] packet_dup              [9] stdin_getchar
 290   [13] flatten_publickey      [18] parse_keys              [6] store_key
 291   [19] free_packet            [10] read_openpgp_stream     [5] transform (sha.c)
 292   [22] free_packet_list        [8] rol (bithelp.h)         [2] update_keys
 293   [17] free_publickey          [7] sha1_final             [11] write_openpgp_stream
 294   [23] free_signedpacket_list [20] sha1_init
 295    [3] get_keyid              [21] sha1_read