profile2.txt 14 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203
  1. Flat profile:
  2. Each sample counts as 0.01 seconds.
  3. % cumulative self self total
  4. time seconds seconds calls Ts/call Ts/call name
  5. 100.00 0.01 0.01 __gnu_cxx::__atomic_add(int volatile*, int)
  6. 0.00 0.01 0.00 51 0.00 0.00 boost::archive::iterators::transform_width<char*, 6, 8, char>::fill()
  7. 0.00 0.01 0.00 36 0.00 0.00 boost::archive::iterators::transform_width<__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, 8, 6, char>::fill()
  8. 0.00 0.01 0.00 30 0.00 0.00 std::vector<char, std::allocator<char> >::_M_insert_aux(__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, char const&)
  9. 0.00 0.01 0.00 11 0.00 0.00 boost::archive::iterators::xml_escape<char const*>::fill(char const*&, char const*&)
  10. 0.00 0.01 0.00 9 0.00 0.00 void test_transform_width<6, 8>(unsigned int)
  11. 0.00 0.01 0.00 9 0.00 0.00 boost::archive::iterators::xml_unescape<char const*>::drain()
  12. 0.00 0.01 0.00 5 0.00 0.00 boost::archive::iterators::xml_unescape<char const*>::drain_residue(char const*)
  13. 0.00 0.01 0.00 2 0.00 0.00 __static_initialization_and_destruction_0(int, int)
  14. 0.00 0.01 0.00 1 0.00 0.00 void test_xml_escape<char const>(char const*, char const*, unsigned int)
  15. 0.00 0.01 0.00 1 0.00 0.00 void test_xml_unescape<char const>(char const*, char const*, unsigned int)
  16. 0.00 0.01 0.00 1 0.00 0.00 void test_stream_iterators<char>(char const*, unsigned int)
  17. 0.00 0.01 0.00 1 0.00 0.00 test_main(int, char**)
  18. 0.00 0.01 0.00 1 0.00 0.00 char* std::string::_S_construct<char*>(char*, char*, std::allocator<char> const&, std::forward_iterator_tag)
  19. 0.00 0.01 0.00 1 0.00 0.00 std::basic_string<char, std::char_traits<char>, std::allocator<char> >::basic_string<char*>(char*, char*, std::allocator<char> const&)
  20. % the percentage of the total running time of the
  21. time program used by this function.
  22. cumulative a running sum of the number of seconds accounted
  23. seconds for by this function and those listed above it.
  24. self the number of seconds accounted for by this
  25. seconds function alone. This is the major sort for this
  26. listing.
  27. calls the number of times this function was invoked, if
  28. this function is profiled, else blank.
  29. self the average number of milliseconds spent in this
  30. ms/call function per call, if this function is profiled,
  31. else blank.
  32. total the average number of milliseconds spent in this
  33. ms/call function and its descendents per call, if this
  34. function is profiled, else blank.
  35. name the name of the function. This is the minor sort
  36. for this listing. The index shows the location of
  37. the function in the gprof listing. If the index is
  38. in parenthesis it shows where it would appear in
  39. the gprof listing if it were to be printed.
  40. Call graph (explanation follows)
  41. granularity: each sample hit covers 4 byte(s) for 100.00% of 0.01 seconds
  42. index % time self children called name
  43. <spontaneous>
  44. [1] 100.0 0.01 0.00 __gnu_cxx::__atomic_add(int volatile*, int) [1]
  45. -----------------------------------------------
  46. 0.00 0.00 51/51 void test_transform_width<6, 8>(unsigned int) [9]
  47. [5] 0.0 0.00 0.00 51 boost::archive::iterators::transform_width<char*, 6, 8, char>::fill() [5]
  48. -----------------------------------------------
  49. 0.00 0.00 36/36 void test_transform_width<6, 8>(unsigned int) [9]
  50. [6] 0.0 0.00 0.00 36 boost::archive::iterators::transform_width<__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, 8, 6, char>::fill() [6]
  51. -----------------------------------------------
  52. 0.00 0.00 30/30 void test_transform_width<6, 8>(unsigned int) [9]
  53. [7] 0.0 0.00 0.00 30 std::vector<char, std::allocator<char> >::_M_insert_aux(__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, char const&) [7]
  54. -----------------------------------------------
  55. 0.00 0.00 11/11 void test_xml_escape<char const>(char const*, char const*, unsigned int) [13]
  56. [8] 0.0 0.00 0.00 11 boost::archive::iterators::xml_escape<char const*>::fill(char const*&, char const*&) [8]
  57. -----------------------------------------------
  58. 0.00 0.00 9/9 test_main(int, char**) [16]
  59. [9] 0.0 0.00 0.00 9 void test_transform_width<6, 8>(unsigned int) [9]
  60. 0.00 0.00 51/51 boost::archive::iterators::transform_width<char*, 6, 8, char>::fill() [5]
  61. 0.00 0.00 36/36 boost::archive::iterators::transform_width<__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, 8, 6, char>::fill() [6]
  62. 0.00 0.00 30/30 std::vector<char, std::allocator<char> >::_M_insert_aux(__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, char const&) [7]
  63. -----------------------------------------------
  64. 0.00 0.00 9/9 void test_xml_unescape<char const>(char const*, char const*, unsigned int) [14]
  65. [10] 0.0 0.00 0.00 9 boost::archive::iterators::xml_unescape<char const*>::drain() [10]
  66. 0.00 0.00 5/5 boost::archive::iterators::xml_unescape<char const*>::drain_residue(char const*) [11]
  67. -----------------------------------------------
  68. 0.00 0.00 5/5 boost::archive::iterators::xml_unescape<char const*>::drain() [10]
  69. [11] 0.0 0.00 0.00 5 boost::archive::iterators::xml_unescape<char const*>::drain_residue(char const*) [11]
  70. -----------------------------------------------
  71. 0.00 0.00 1/2 global constructors keyed to main [38]
  72. 0.00 0.00 1/2 global destructors keyed to main [35]
  73. [12] 0.0 0.00 0.00 2 __static_initialization_and_destruction_0(int, int) [12]
  74. -----------------------------------------------
  75. 0.00 0.00 1/1 test_main(int, char**) [16]
  76. [13] 0.0 0.00 0.00 1 void test_xml_escape<char const>(char const*, char const*, unsigned int) [13]
  77. 0.00 0.00 11/11 boost::archive::iterators::xml_escape<char const*>::fill(char const*&, char const*&) [8]
  78. -----------------------------------------------
  79. 0.00 0.00 1/1 test_main(int, char**) [16]
  80. [14] 0.0 0.00 0.00 1 void test_xml_unescape<char const>(char const*, char const*, unsigned int) [14]
  81. 0.00 0.00 9/9 boost::archive::iterators::xml_unescape<char const*>::drain() [10]
  82. -----------------------------------------------
  83. 0.00 0.00 1/1 test_main(int, char**) [16]
  84. [15] 0.0 0.00 0.00 1 void test_stream_iterators<char>(char const*, unsigned int) [15]
  85. 0.00 0.00 1/1 std::basic_string<char, std::char_traits<char>, std::allocator<char> >::basic_string<char*>(char*, char*, std::allocator<char> const&) [18]
  86. -----------------------------------------------
  87. 0.00 0.00 1/1 main [1211]
  88. [16] 0.0 0.00 0.00 1 test_main(int, char**) [16]
  89. 0.00 0.00 9/9 void test_transform_width<6, 8>(unsigned int) [9]
  90. 0.00 0.00 1/1 void test_xml_escape<char const>(char const*, char const*, unsigned int) [13]
  91. 0.00 0.00 1/1 void test_xml_unescape<char const>(char const*, char const*, unsigned int) [14]
  92. 0.00 0.00 1/1 void test_stream_iterators<char>(char const*, unsigned int) [15]
  93. -----------------------------------------------
  94. 0.00 0.00 1/1 std::basic_string<char, std::char_traits<char>, std::allocator<char> >::basic_string<char*>(char*, char*, std::allocator<char> const&) [18]
  95. [17] 0.0 0.00 0.00 1 char* std::string::_S_construct<char*>(char*, char*, std::allocator<char> const&, std::forward_iterator_tag) [17]
  96. -----------------------------------------------
  97. 0.00 0.00 1/1 void test_stream_iterators<char>(char const*, unsigned int) [15]
  98. [18] 0.0 0.00 0.00 1 std::basic_string<char, std::char_traits<char>, std::allocator<char> >::basic_string<char*>(char*, char*, std::allocator<char> const&) [18]
  99. 0.00 0.00 1/1 char* std::string::_S_construct<char*>(char*, char*, std::allocator<char> const&, std::forward_iterator_tag) [17]
  100. -----------------------------------------------
  101. This table describes the call tree of the program, and was sorted by
  102. the total amount of time spent in each function and its children.
  103. Each entry in this table consists of several lines. The line with the
  104. index number at the left hand margin lists the current function.
  105. The lines above it list the functions that called this function,
  106. and the lines below it list the functions this one called.
  107. This line lists:
  108. index A unique number given to each element of the table.
  109. Index numbers are sorted numerically.
  110. The index number is printed next to every function name so
  111. it is easier to look up where the function in the table.
  112. % time This is the percentage of the `total' time that was spent
  113. in this function and its children. Note that due to
  114. different viewpoints, functions excluded by options, etc,
  115. these numbers will NOT add up to 100%.
  116. self This is the total amount of time spent in this function.
  117. children This is the total amount of time propagated into this
  118. function by its children.
  119. called This is the number of times the function was called.
  120. If the function called itself recursively, the number
  121. only includes non-recursive calls, and is followed by
  122. a `+' and the number of recursive calls.
  123. name The name of the current function. The index number is
  124. printed after it. If the function is a member of a
  125. cycle, the cycle number is printed between the
  126. function's name and the index number.
  127. For the function's parents, the fields have the following meanings:
  128. self This is the amount of time that was propagated directly
  129. from the function into this parent.
  130. children This is the amount of time that was propagated from
  131. the function's children into this parent.
  132. called This is the number of times this parent called the
  133. function `/' the total number of times the function
  134. was called. Recursive calls to the function are not
  135. included in the number after the `/'.
  136. name This is the name of the parent. The parent's index
  137. number is printed after it. If the parent is a
  138. member of a cycle, the cycle number is printed between
  139. the name and the index number.
  140. If the parents of the function cannot be determined, the word
  141. `<spontaneous>' is printed in the `name' field, and all the other
  142. fields are blank.
  143. For the function's children, the fields have the following meanings:
  144. self This is the amount of time that was propagated directly
  145. from the child into the function.
  146. children This is the amount of time that was propagated from the
  147. child's children to the function.
  148. called This is the number of times the function called
  149. this child `/' the total number of times the child
  150. was called. Recursive calls by the child are not
  151. listed in the number after the `/'.
  152. name This is the name of the child. The child's index
  153. number is printed after it. If the child is a
  154. member of a cycle, the cycle number is printed
  155. between the name and the index number.
  156. If there are any cycles (circles) in the call graph, there is an
  157. entry for the cycle-as-a-whole. This entry shows who called the
  158. cycle (as parents) and the members of the cycle (as children.)
  159. The `+' recursive calls entry shows the number of function calls that
  160. were internal to the cycle, and the calls entry for each member shows,
  161. for that member, how many times it was called from other members of
  162. the cycle.
  163. Index by function name
  164. [13] void test_xml_escape<char const>(char const*, char const*, unsigned int) [16] test_main(int, char**) [5] boost::archive::iterators::transform_width<char*, 6, 8, char>::fill()
  165. [14] void test_xml_unescape<char const>(char const*, char const*, unsigned int) [8] boost::archive::iterators::xml_escape<char const*>::fill(char const*&, char const*&) [1] __gnu_cxx::__atomic_add(int volatile*, int)
  166. [9] void test_transform_width<6, 8>(unsigned int) [11] boost::archive::iterators::xml_unescape<char const*>::drain_residue(char const*) [17] char* std::string::_S_construct<char*>(char*, char*, std::allocator<char> const&, std::forward_iterator_tag)
  167. [15] void test_stream_iterators<char>(char const*, unsigned int) [10] boost::archive::iterators::xml_unescape<char const*>::drain() [18] std::basic_string<char, std::char_traits<char>, std::allocator<char> >::basic_string<char*>(char*, char*, std::allocator<char> const&)
  168. [12] __static_initialization_and_destruction_0(int, int) (performance_iterators.cpp) [6] boost::archive::iterators::transform_width<__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, 8, 6, char>::fill() [7] std::vector<char, std::allocator<char> >::_M_insert_aux(__gnu_cxx::__normal_iterator<char*, std::vector<char, std::allocator<char> > >, char const&)