profile 34 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560
  1. Flat profile:
  2. Each sample counts as 0.01 seconds.
  3. % cumulative self self total
  4. time seconds seconds calls ms/call ms/call name
  5. 21.95 0.09 0.09 274959 0.00 0.00 message_handler_mcast
  6. 19.51 0.17 0.08 274977 0.00 0.00 pending_queues_deliver
  7. 9.76 0.21 0.04 1655 0.02 0.05 user_deliver
  8. 9.76 0.25 0.04 1 40.00 409.61 poll_run
  9. 7.32 0.28 0.03 322067 0.00 0.00 timerlist_expire
  10. 7.32 0.31 0.03 1623 0.02 0.03 deliver_fn
  11. 4.88 0.33 0.02 322068 0.00 0.00 timerlist_timeout_msec
  12. 4.88 0.35 0.02 322046 0.00 0.00 recv_handler
  13. 2.44 0.36 0.01 47021 0.00 0.00 timerlist_add_future
  14. 2.44 0.37 0.01 46913 0.00 0.00 message_handler_orf_token
  15. 2.44 0.38 0.01 46913 0.00 0.00 orf_token_mcast
  16. 2.44 0.39 0.01 46878 0.00 0.00 messages_free
  17. 2.44 0.40 0.01 1632 0.01 0.01 SaNameTisNameT
  18. 2.44 0.41 0.01 1527 0.01 0.01 message_handler_req_exec_ckpt_sectionwrite
  19. 0.00 0.41 0.00 47304 0.00 0.00 poll_timer_delete
  20. 0.00 0.41 0.00 47021 0.00 0.00 poll_timer_add
  21. 0.00 0.41 0.00 47021 0.00 0.00 timerlist_add
  22. 0.00 0.41 0.00 47019 0.00 0.00 timerlist_del
  23. 0.00 0.41 0.00 46913 0.00 0.00 orf_token_evs
  24. 0.00 0.41 0.00 46913 0.00 0.00 orf_token_rtr
  25. 0.00 0.41 0.00 46878 0.00 0.00 sq_items_release
  26. 0.00 0.41 0.00 1655 0.00 0.00 pend_delv_next_delivery_find
  27. 0.00 0.41 0.00 1623 0.00 0.00 queue_reinit
  28. 0.00 0.41 0.00 726 0.00 0.00 internal_log_printf
  29. 0.00 0.41 0.00 125 0.00 0.00 mempool_free
  30. 0.00 0.41 0.00 125 0.00 0.00 mempool_strdup
  31. 0.00 0.41 0.00 125 0.00 0.00 strstr_rs
  32. 0.00 0.41 0.00 71 0.00 0.00 ckptConfChg
  33. 0.00 0.41 0.00 71 0.00 0.00 clmConfChg
  34. 0.00 0.41 0.00 71 0.00 0.00 confchg_fn
  35. 0.00 0.41 0.00 71 0.00 0.00 libraryNotificationLeave
  36. 0.00 0.41 0.00 70 0.00 0.00 message_handler_memb_form_token
  37. 0.00 0.41 0.00 53 0.00 0.00 message_handler_req_exec_clm_nodejoin
  38. 0.00 0.41 0.00 52 0.00 0.00 message_handler_memb_attempt_join
  39. 0.00 0.41 0.00 52 0.00 0.00 message_handler_memb_join
  40. 0.00 0.41 0.00 41 0.00 0.01 message_handler_req_exec_ckpt_checkpointopen
  41. 0.00 0.41 0.00 36 0.00 0.00 SaNameTisEqual
  42. 0.00 0.41 0.00 35 0.00 0.00 memb_form_token_conf_desc_build
  43. 0.00 0.41 0.00 35 0.00 0.00 queues_pend_delv_memb_new
  44. 0.00 0.41 0.00 35 0.00 0.00 sq_reinit
  45. 0.00 0.41 0.00 20 0.00 0.00 queue_init
  46. 0.00 0.41 0.00 19 0.00 0.00 memb_state_commit_enter
  47. 0.00 0.41 0.00 19 0.00 0.00 memb_state_gather_enter
  48. 0.00 0.41 0.00 18 0.00 0.02 findComponent
  49. 0.00 0.41 0.00 18 0.00 0.00 gmi_mcast
  50. 0.00 0.41 0.00 18 0.00 0.00 gmi_pend_trans_item_store
  51. 0.00 0.41 0.00 18 0.00 0.00 libraryNotificationJoin
  52. 0.00 0.41 0.00 18 0.00 0.00 memb_print_commit_set
  53. 0.00 0.41 0.00 18 0.00 0.00 memb_state_commit_consensus
  54. 0.00 0.41 0.00 15 0.00 0.00 orf_timer_function_token_timeout
  55. 0.00 0.41 0.00 12 0.00 0.00 mempool_malloc
  56. 0.00 0.41 0.00 5 0.00 0.00 memb_timer_function_state_gather
  57. 0.00 0.41 0.00 2 0.00 0.01 message_handler_req_exec_ckpt_sectioncreate
  58. 0.00 0.41 0.00 2 0.00 0.00 poll_dispatch_add
  59. 0.00 0.41 0.00 1 0.00 0.00 aisexec_libais_bind
  60. 0.00 0.41 0.00 1 0.00 0.00 amfExecutiveInitialize
  61. 0.00 0.41 0.00 1 0.00 0.39 amfReadGroups
  62. 0.00 0.41 0.00 1 0.00 0.00 clmExecutiveInitialize
  63. 0.00 0.41 0.00 1 0.00 0.00 determine_local_if
  64. 0.00 0.41 0.00 1 0.00 0.00 gmi_init
  65. 0.00 0.41 0.00 1 0.00 0.00 gmi_join
  66. 0.00 0.41 0.00 1 0.00 0.00 grow_connections_table
  67. 0.00 0.41 0.00 1 0.00 0.00 handle_create
  68. 0.00 0.41 0.00 1 0.00 0.00 memb_conf_id_build
  69. 0.00 0.41 0.00 1 0.00 0.00 memb_timer_function_state_commit_timeout
  70. 0.00 0.41 0.00 1 0.00 0.00 mempool_init
  71. 0.00 0.41 0.00 1 0.00 0.00 mempool_realloc
  72. 0.00 0.41 0.00 1 0.00 0.00 poll_create
  73. 0.00 0.41 0.00 1 0.00 0.00 sq_init
  74. 0.00 0.41 0.00 1 0.00 0.00 this_ip_set
  75. 0.00 0.41 0.00 1 0.00 0.00 timerlist_init
  76. % the percentage of the total running time of the
  77. time program used by this function.
  78. cumulative a running sum of the number of seconds accounted
  79. seconds for by this function and those listed above it.
  80. self the number of seconds accounted for by this
  81. seconds function alone. This is the major sort for this
  82. listing.
  83. calls the number of times this function was invoked, if
  84. this function is profiled, else blank.
  85. self the average number of milliseconds spent in this
  86. ms/call function per call, if this function is profiled,
  87. else blank.
  88. total the average number of milliseconds spent in this
  89. ms/call function and its descendents per call, if this
  90. function is profiled, else blank.
  91. name the name of the function. This is the minor sort
  92. for this listing. The index shows the location of
  93. the function in the gprof listing. If the index is
  94. in parenthesis it shows where it would appear in
  95. the gprof listing if it were to be printed.
  96. Call graph (explanation follows)
  97. granularity: each sample hit covers 4 byte(s) for 2.44% of 0.41 seconds
  98. index % time self children called name
  99. <spontaneous>
  100. [1] 100.0 0.00 0.41 main [1]
  101. 0.04 0.37 1/1 poll_run [2]
  102. 0.00 0.00 1/1 amfReadGroups [18]
  103. 0.00 0.00 1/1 gmi_init [27]
  104. 0.00 0.00 2/726 internal_log_printf [36]
  105. 0.00 0.00 1/1 poll_create [69]
  106. 0.00 0.00 1/1 mempool_init [67]
  107. 0.00 0.00 1/1 aisexec_libais_bind [58]
  108. 0.00 0.00 1/1 grow_connections_table [63]
  109. 0.00 0.00 1/2 poll_dispatch_add [57]
  110. 0.00 0.00 1/1 gmi_join [62]
  111. 0.00 0.00 1/1 amfExecutiveInitialize [59]
  112. 0.00 0.00 1/1 clmExecutiveInitialize [60]
  113. -----------------------------------------------
  114. 0.04 0.37 1/1 main [1]
  115. [2] 99.9 0.04 0.37 1 poll_run [2]
  116. 0.02 0.30 322046/322046 recv_handler [3]
  117. 0.03 0.00 322067/322067 timerlist_expire [9]
  118. 0.02 0.00 322068/322068 timerlist_timeout_msec [10]
  119. -----------------------------------------------
  120. 0.02 0.30 322046/322046 poll_run [2]
  121. [3] 78.0 0.02 0.30 322046 recv_handler [3]
  122. 0.09 0.17 274959/274959 message_handler_mcast [4]
  123. 0.01 0.03 46913/46913 message_handler_orf_token [8]
  124. 0.00 0.00 70/70 message_handler_memb_form_token [20]
  125. 0.00 0.00 52/52 message_handler_memb_join [24]
  126. 0.00 0.00 52/52 message_handler_memb_attempt_join [26]
  127. -----------------------------------------------
  128. 0.09 0.17 274959/274959 recv_handler [3]
  129. [4] 63.3 0.09 0.17 274959 message_handler_mcast [4]
  130. 0.08 0.09 274959/274977 pending_queues_deliver [5]
  131. -----------------------------------------------
  132. 0.00 0.00 18/274977 orf_token_mcast [12]
  133. 0.08 0.09 274959/274977 message_handler_mcast [4]
  134. [5] 41.4 0.08 0.09 274977 pending_queues_deliver [5]
  135. 0.04 0.05 1655/1655 user_deliver [6]
  136. -----------------------------------------------
  137. 0.04 0.05 1655/1655 pending_queues_deliver [5]
  138. [6] 21.9 0.04 0.05 1655 user_deliver [6]
  139. 0.03 0.02 1623/1623 deliver_fn [7]
  140. 0.00 0.00 1655/1655 pend_delv_next_delivery_find [34]
  141. 0.00 0.00 1623/1623 queue_reinit [35]
  142. -----------------------------------------------
  143. 0.03 0.02 1623/1623 user_deliver [6]
  144. [7] 12.1 0.03 0.02 1623 deliver_fn [7]
  145. 0.01 0.01 1527/1527 message_handler_req_exec_ckpt_sectionwrite [11]
  146. 0.00 0.00 41/41 message_handler_req_exec_ckpt_checkpointopen [19]
  147. 0.00 0.00 2/2 message_handler_req_exec_ckpt_sectioncreate [21]
  148. 0.00 0.00 53/53 message_handler_req_exec_clm_nodejoin [44]
  149. -----------------------------------------------
  150. 0.01 0.03 46913/46913 recv_handler [3]
  151. [8] 9.8 0.01 0.03 46913 message_handler_orf_token [8]
  152. 0.01 0.00 46913/46913 orf_token_mcast [12]
  153. 0.01 0.00 46878/46878 messages_free [15]
  154. 0.00 0.01 46913/47021 poll_timer_add [13]
  155. 0.00 0.00 46913/46913 orf_token_rtr [32]
  156. 0.00 0.00 46913/46913 orf_token_evs [31]
  157. 0.00 0.00 46913/47304 poll_timer_delete [28]
  158. -----------------------------------------------
  159. 0.03 0.00 322067/322067 poll_run [2]
  160. [9] 7.3 0.03 0.00 322067 timerlist_expire [9]
  161. 0.00 0.00 15/19 memb_state_gather_enter [23]
  162. 0.00 0.00 5/5 memb_timer_function_state_gather [25]
  163. 0.00 0.00 15/15 orf_timer_function_token_timeout [55]
  164. 0.00 0.00 1/1 memb_timer_function_state_commit_timeout [66]
  165. -----------------------------------------------
  166. 0.02 0.00 322068/322068 poll_run [2]
  167. [10] 4.9 0.02 0.00 322068 timerlist_timeout_msec [10]
  168. -----------------------------------------------
  169. 0.01 0.01 1527/1527 deliver_fn [7]
  170. [11] 4.7 0.01 0.01 1527 message_handler_req_exec_ckpt_sectionwrite [11]
  171. 0.01 0.00 1527/1632 SaNameTisNameT [16]
  172. -----------------------------------------------
  173. 0.01 0.00 46913/46913 message_handler_orf_token [8]
  174. [12] 2.4 0.01 0.00 46913 orf_token_mcast [12]
  175. 0.00 0.00 18/274977 pending_queues_deliver [5]
  176. -----------------------------------------------
  177. 0.00 0.00 19/47021 memb_state_commit_enter [22]
  178. 0.00 0.00 19/47021 memb_state_gather_enter [23]
  179. 0.00 0.00 70/47021 message_handler_memb_form_token [20]
  180. 0.00 0.01 46913/47021 message_handler_orf_token [8]
  181. [13] 2.4 0.00 0.01 47021 poll_timer_add [13]
  182. 0.01 0.00 47021/47021 timerlist_add_future [14]
  183. -----------------------------------------------
  184. 0.01 0.00 47021/47021 poll_timer_add [13]
  185. [14] 2.4 0.01 0.00 47021 timerlist_add_future [14]
  186. 0.00 0.00 47021/47021 timerlist_add [29]
  187. -----------------------------------------------
  188. 0.01 0.00 46878/46878 message_handler_orf_token [8]
  189. [15] 2.4 0.01 0.00 46878 messages_free [15]
  190. 0.00 0.00 46878/46878 sq_items_release [33]
  191. -----------------------------------------------
  192. 0.00 0.00 2/1632 message_handler_req_exec_ckpt_sectioncreate [21]
  193. 0.00 0.00 40/1632 message_handler_req_exec_ckpt_checkpointopen [19]
  194. 0.00 0.00 63/1632 findComponent [17]
  195. 0.01 0.00 1527/1632 message_handler_req_exec_ckpt_sectionwrite [11]
  196. [16] 2.4 0.01 0.00 1632 SaNameTisNameT [16]
  197. -----------------------------------------------
  198. 0.00 0.00 18/18 amfReadGroups [18]
  199. [17] 0.1 0.00 0.00 18 findComponent [17]
  200. 0.00 0.00 63/1632 SaNameTisNameT [16]
  201. -----------------------------------------------
  202. 0.00 0.00 1/1 main [1]
  203. [18] 0.1 0.00 0.00 1 amfReadGroups [18]
  204. 0.00 0.00 18/18 findComponent [17]
  205. 0.00 0.00 125/125 strstr_rs [39]
  206. 0.00 0.00 36/36 SaNameTisEqual [45]
  207. 0.00 0.00 12/12 mempool_malloc [56]
  208. -----------------------------------------------
  209. 0.00 0.00 41/41 deliver_fn [7]
  210. [19] 0.1 0.00 0.00 41 message_handler_req_exec_ckpt_checkpointopen [19]
  211. 0.00 0.00 40/1632 SaNameTisNameT [16]
  212. -----------------------------------------------
  213. 0.00 0.00 70/70 recv_handler [3]
  214. [20] 0.0 0.00 0.00 70 message_handler_memb_form_token [20]
  215. 0.00 0.00 70/47021 poll_timer_add [13]
  216. 0.00 0.00 280/47304 poll_timer_delete [28]
  217. 0.00 0.00 35/35 memb_form_token_conf_desc_build [46]
  218. -----------------------------------------------
  219. 0.00 0.00 2/2 deliver_fn [7]
  220. [21] 0.0 0.00 0.00 2 message_handler_req_exec_ckpt_sectioncreate [21]
  221. 0.00 0.00 2/1632 SaNameTisNameT [16]
  222. -----------------------------------------------
  223. 0.00 0.00 5/19 memb_timer_function_state_gather [25]
  224. 0.00 0.00 14/19 message_handler_memb_join [24]
  225. [22] 0.0 0.00 0.00 19 memb_state_commit_enter [22]
  226. 0.00 0.00 19/47021 poll_timer_add [13]
  227. 0.00 0.00 19/47304 poll_timer_delete [28]
  228. -----------------------------------------------
  229. 0.00 0.00 1/19 gmi_init [27]
  230. 0.00 0.00 3/19 message_handler_memb_attempt_join [26]
  231. 0.00 0.00 15/19 timerlist_expire [9]
  232. [23] 0.0 0.00 0.00 19 memb_state_gather_enter [23]
  233. 0.00 0.00 19/47021 poll_timer_add [13]
  234. 0.00 0.00 38/726 internal_log_printf [36]
  235. 0.00 0.00 19/47304 poll_timer_delete [28]
  236. -----------------------------------------------
  237. 0.00 0.00 52/52 recv_handler [3]
  238. [24] 0.0 0.00 0.00 52 message_handler_memb_join [24]
  239. 0.00 0.00 14/19 memb_state_commit_enter [22]
  240. 0.00 0.00 18/18 memb_print_commit_set [53]
  241. 0.00 0.00 18/18 memb_state_commit_consensus [54]
  242. 0.00 0.00 18/726 internal_log_printf [36]
  243. -----------------------------------------------
  244. 0.00 0.00 5/5 timerlist_expire [9]
  245. [25] 0.0 0.00 0.00 5 memb_timer_function_state_gather [25]
  246. 0.00 0.00 5/19 memb_state_commit_enter [22]
  247. -----------------------------------------------
  248. 0.00 0.00 52/52 recv_handler [3]
  249. [26] 0.0 0.00 0.00 52 message_handler_memb_attempt_join [26]
  250. 0.00 0.00 3/19 memb_state_gather_enter [23]
  251. 0.00 0.00 86/726 internal_log_printf [36]
  252. 0.00 0.00 3/47304 poll_timer_delete [28]
  253. -----------------------------------------------
  254. 0.00 0.00 1/1 main [1]
  255. [27] 0.0 0.00 0.00 1 gmi_init [27]
  256. 0.00 0.00 1/19 memb_state_gather_enter [23]
  257. 0.00 0.00 1/20 queue_init [49]
  258. 0.00 0.00 1/1 sq_init [70]
  259. 0.00 0.00 1/1 determine_local_if [61]
  260. 0.00 0.00 1/1 memb_conf_id_build [65]
  261. 0.00 0.00 1/2 poll_dispatch_add [57]
  262. -----------------------------------------------
  263. 0.00 0.00 3/47304 message_handler_memb_attempt_join [26]
  264. 0.00 0.00 19/47304 memb_state_commit_enter [22]
  265. 0.00 0.00 19/47304 memb_state_gather_enter [23]
  266. 0.00 0.00 70/47304 orf_token_evs [31]
  267. 0.00 0.00 280/47304 message_handler_memb_form_token [20]
  268. 0.00 0.00 46913/47304 message_handler_orf_token [8]
  269. [28] 0.0 0.00 0.00 47304 poll_timer_delete [28]
  270. 0.00 0.00 47019/47019 timerlist_del [30]
  271. -----------------------------------------------
  272. 0.00 0.00 47021/47021 timerlist_add_future [14]
  273. [29] 0.0 0.00 0.00 47021 timerlist_add [29]
  274. -----------------------------------------------
  275. 0.00 0.00 47019/47019 poll_timer_delete [28]
  276. [30] 0.0 0.00 0.00 47019 timerlist_del [30]
  277. -----------------------------------------------
  278. 0.00 0.00 46913/46913 message_handler_orf_token [8]
  279. [31] 0.0 0.00 0.00 46913 orf_token_evs [31]
  280. 0.00 0.00 70/47304 poll_timer_delete [28]
  281. 0.00 0.00 70/71 confchg_fn [42]
  282. 0.00 0.00 35/726 internal_log_printf [36]
  283. 0.00 0.00 35/35 sq_reinit [48]
  284. 0.00 0.00 35/35 queues_pend_delv_memb_new [47]
  285. -----------------------------------------------
  286. 0.00 0.00 46913/46913 message_handler_orf_token [8]
  287. [32] 0.0 0.00 0.00 46913 orf_token_rtr [32]
  288. -----------------------------------------------
  289. 0.00 0.00 46878/46878 messages_free [15]
  290. [33] 0.0 0.00 0.00 46878 sq_items_release [33]
  291. -----------------------------------------------
  292. 0.00 0.00 1655/1655 user_deliver [6]
  293. [34] 0.0 0.00 0.00 1655 pend_delv_next_delivery_find [34]
  294. -----------------------------------------------
  295. 0.00 0.00 1623/1623 user_deliver [6]
  296. [35] 0.0 0.00 0.00 1623 queue_reinit [35]
  297. -----------------------------------------------
  298. 0.00 0.00 1/726 sigintr_handler [175]
  299. 0.00 0.00 1/726 memb_timer_function_state_commit_timeout [66]
  300. 0.00 0.00 2/726 main [1]
  301. 0.00 0.00 15/726 orf_timer_function_token_timeout [55]
  302. 0.00 0.00 18/726 message_handler_memb_join [24]
  303. 0.00 0.00 35/726 orf_token_evs [31]
  304. 0.00 0.00 38/726 memb_state_gather_enter [23]
  305. 0.00 0.00 53/726 message_handler_req_exec_clm_nodejoin [44]
  306. 0.00 0.00 86/726 message_handler_memb_attempt_join [26]
  307. 0.00 0.00 477/726 clmConfChg [41]
  308. [36] 0.0 0.00 0.00 726 internal_log_printf [36]
  309. -----------------------------------------------
  310. 0.00 0.00 125/125 strstr_rs [39]
  311. [37] 0.0 0.00 0.00 125 mempool_free [37]
  312. -----------------------------------------------
  313. 0.00 0.00 125/125 strstr_rs [39]
  314. [38] 0.0 0.00 0.00 125 mempool_strdup [38]
  315. -----------------------------------------------
  316. 0.00 0.00 125/125 amfReadGroups [18]
  317. [39] 0.0 0.00 0.00 125 strstr_rs [39]
  318. 0.00 0.00 125/125 mempool_strdup [38]
  319. 0.00 0.00 125/125 mempool_free [37]
  320. -----------------------------------------------
  321. 0.00 0.00 71/71 confchg_fn [42]
  322. [40] 0.0 0.00 0.00 71 ckptConfChg [40]
  323. -----------------------------------------------
  324. 0.00 0.00 71/71 confchg_fn [42]
  325. [41] 0.0 0.00 0.00 71 clmConfChg [41]
  326. 0.00 0.00 477/726 internal_log_printf [36]
  327. 0.00 0.00 71/71 libraryNotificationLeave [43]
  328. 0.00 0.00 18/18 gmi_mcast [50]
  329. -----------------------------------------------
  330. 0.00 0.00 1/71 memb_timer_function_state_commit_timeout [66]
  331. 0.00 0.00 70/71 orf_token_evs [31]
  332. [42] 0.0 0.00 0.00 71 confchg_fn [42]
  333. 0.00 0.00 71/71 ckptConfChg [40]
  334. 0.00 0.00 71/71 clmConfChg [41]
  335. -----------------------------------------------
  336. 0.00 0.00 71/71 clmConfChg [41]
  337. [43] 0.0 0.00 0.00 71 libraryNotificationLeave [43]
  338. -----------------------------------------------
  339. 0.00 0.00 53/53 deliver_fn [7]
  340. [44] 0.0 0.00 0.00 53 message_handler_req_exec_clm_nodejoin [44]
  341. 0.00 0.00 53/726 internal_log_printf [36]
  342. 0.00 0.00 18/18 libraryNotificationJoin [52]
  343. -----------------------------------------------
  344. 0.00 0.00 36/36 amfReadGroups [18]
  345. [45] 0.0 0.00 0.00 36 SaNameTisEqual [45]
  346. -----------------------------------------------
  347. 0.00 0.00 35/35 message_handler_memb_form_token [20]
  348. [46] 0.0 0.00 0.00 35 memb_form_token_conf_desc_build [46]
  349. -----------------------------------------------
  350. 0.00 0.00 35/35 orf_token_evs [31]
  351. [47] 0.0 0.00 0.00 35 queues_pend_delv_memb_new [47]
  352. 0.00 0.00 19/20 queue_init [49]
  353. -----------------------------------------------
  354. 0.00 0.00 35/35 orf_token_evs [31]
  355. [48] 0.0 0.00 0.00 35 sq_reinit [48]
  356. -----------------------------------------------
  357. 0.00 0.00 1/20 gmi_init [27]
  358. 0.00 0.00 19/20 queues_pend_delv_memb_new [47]
  359. [49] 0.0 0.00 0.00 20 queue_init [49]
  360. -----------------------------------------------
  361. 0.00 0.00 18/18 clmConfChg [41]
  362. [50] 0.0 0.00 0.00 18 gmi_mcast [50]
  363. 0.00 0.00 18/18 gmi_pend_trans_item_store [51]
  364. -----------------------------------------------
  365. 0.00 0.00 18/18 gmi_mcast [50]
  366. [51] 0.0 0.00 0.00 18 gmi_pend_trans_item_store [51]
  367. -----------------------------------------------
  368. 0.00 0.00 18/18 message_handler_req_exec_clm_nodejoin [44]
  369. [52] 0.0 0.00 0.00 18 libraryNotificationJoin [52]
  370. -----------------------------------------------
  371. 0.00 0.00 18/18 message_handler_memb_join [24]
  372. [53] 0.0 0.00 0.00 18 memb_print_commit_set [53]
  373. -----------------------------------------------
  374. 0.00 0.00 18/18 message_handler_memb_join [24]
  375. [54] 0.0 0.00 0.00 18 memb_state_commit_consensus [54]
  376. -----------------------------------------------
  377. 0.00 0.00 15/15 timerlist_expire [9]
  378. [55] 0.0 0.00 0.00 15 orf_timer_function_token_timeout [55]
  379. 0.00 0.00 15/726 internal_log_printf [36]
  380. -----------------------------------------------
  381. 0.00 0.00 12/12 amfReadGroups [18]
  382. [56] 0.0 0.00 0.00 12 mempool_malloc [56]
  383. -----------------------------------------------
  384. 0.00 0.00 1/2 main [1]
  385. 0.00 0.00 1/2 gmi_init [27]
  386. [57] 0.0 0.00 0.00 2 poll_dispatch_add [57]
  387. -----------------------------------------------
  388. 0.00 0.00 1/1 main [1]
  389. [58] 0.0 0.00 0.00 1 aisexec_libais_bind [58]
  390. -----------------------------------------------
  391. 0.00 0.00 1/1 main [1]
  392. [59] 0.0 0.00 0.00 1 amfExecutiveInitialize [59]
  393. -----------------------------------------------
  394. 0.00 0.00 1/1 main [1]
  395. [60] 0.0 0.00 0.00 1 clmExecutiveInitialize [60]
  396. 0.00 0.00 1/1 this_ip_set [71]
  397. -----------------------------------------------
  398. 0.00 0.00 1/1 gmi_init [27]
  399. [61] 0.0 0.00 0.00 1 determine_local_if [61]
  400. -----------------------------------------------
  401. 0.00 0.00 1/1 main [1]
  402. [62] 0.0 0.00 0.00 1 gmi_join [62]
  403. -----------------------------------------------
  404. 0.00 0.00 1/1 main [1]
  405. [63] 0.0 0.00 0.00 1 grow_connections_table [63]
  406. 0.00 0.00 1/1 mempool_realloc [68]
  407. -----------------------------------------------
  408. 0.00 0.00 1/1 poll_create [69]
  409. [64] 0.0 0.00 0.00 1 handle_create [64]
  410. -----------------------------------------------
  411. 0.00 0.00 1/1 gmi_init [27]
  412. [65] 0.0 0.00 0.00 1 memb_conf_id_build [65]
  413. -----------------------------------------------
  414. 0.00 0.00 1/1 timerlist_expire [9]
  415. [66] 0.0 0.00 0.00 1 memb_timer_function_state_commit_timeout [66]
  416. 0.00 0.00 1/726 internal_log_printf [36]
  417. 0.00 0.00 1/71 confchg_fn [42]
  418. -----------------------------------------------
  419. 0.00 0.00 1/1 main [1]
  420. [67] 0.0 0.00 0.00 1 mempool_init [67]
  421. -----------------------------------------------
  422. 0.00 0.00 1/1 grow_connections_table [63]
  423. [68] 0.0 0.00 0.00 1 mempool_realloc [68]
  424. -----------------------------------------------
  425. 0.00 0.00 1/1 main [1]
  426. [69] 0.0 0.00 0.00 1 poll_create [69]
  427. 0.00 0.00 1/1 handle_create [64]
  428. 0.00 0.00 1/1 timerlist_init [72]
  429. -----------------------------------------------
  430. 0.00 0.00 1/1 gmi_init [27]
  431. [70] 0.0 0.00 0.00 1 sq_init [70]
  432. -----------------------------------------------
  433. 0.00 0.00 1/1 clmExecutiveInitialize [60]
  434. [71] 0.0 0.00 0.00 1 this_ip_set [71]
  435. -----------------------------------------------
  436. 0.00 0.00 1/1 poll_create [69]
  437. [72] 0.0 0.00 0.00 1 timerlist_init [72]
  438. -----------------------------------------------
  439. This table describes the call tree of the program, and was sorted by
  440. the total amount of time spent in each function and its children.
  441. Each entry in this table consists of several lines. The line with the
  442. index number at the left hand margin lists the current function.
  443. The lines above it list the functions that called this function,
  444. and the lines below it list the functions this one called.
  445. This line lists:
  446. index A unique number given to each element of the table.
  447. Index numbers are sorted numerically.
  448. The index number is printed next to every function name so
  449. it is easier to look up where the function in the table.
  450. % time This is the percentage of the `total' time that was spent
  451. in this function and its children. Note that due to
  452. different viewpoints, functions excluded by options, etc,
  453. these numbers will NOT add up to 100%.
  454. self This is the total amount of time spent in this function.
  455. children This is the total amount of time propagated into this
  456. function by its children.
  457. called This is the number of times the function was called.
  458. If the function called itself recursively, the number
  459. only includes non-recursive calls, and is followed by
  460. a `+' and the number of recursive calls.
  461. name The name of the current function. The index number is
  462. printed after it. If the function is a member of a
  463. cycle, the cycle number is printed between the
  464. function's name and the index number.
  465. For the function's parents, the fields have the following meanings:
  466. self This is the amount of time that was propagated directly
  467. from the function into this parent.
  468. children This is the amount of time that was propagated from
  469. the function's children into this parent.
  470. called This is the number of times this parent called the
  471. function `/' the total number of times the function
  472. was called. Recursive calls to the function are not
  473. included in the number after the `/'.
  474. name This is the name of the parent. The parent's index
  475. number is printed after it. If the parent is a
  476. member of a cycle, the cycle number is printed between
  477. the name and the index number.
  478. If the parents of the function cannot be determined, the word
  479. `<spontaneous>' is printed in the `name' field, and all the other
  480. fields are blank.
  481. For the function's children, the fields have the following meanings:
  482. self This is the amount of time that was propagated directly
  483. from the child into the function.
  484. children This is the amount of time that was propagated from the
  485. child's children to the function.
  486. called This is the number of times the function called
  487. this child `/' the total number of times the child
  488. was called. Recursive calls by the child are not
  489. listed in the number after the `/'.
  490. name This is the name of the child. The child's index
  491. number is printed after it. If the child is a
  492. member of a cycle, the cycle number is printed
  493. between the name and the index number.
  494. If there are any cycles (circles) in the call graph, there is an
  495. entry for the cycle-as-a-whole. This entry shows who called the
  496. cycle (as parents) and the members of the cycle (as children.)
  497. The `+' recursive calls entry shows the number of function calls that
  498. were internal to the cycle, and the calls entry for each member shows,
  499. for that member, how many times it was called from other members of
  500. the cycle.
  501. Index by function name
  502. [45] SaNameTisEqual [54] memb_state_commit_consensus [34] pend_delv_next_delivery_find
  503. [16] SaNameTisNameT [22] memb_state_commit_enter [5] pending_queues_deliver
  504. [58] aisexec_libais_bind [23] memb_state_gather_enter [69] poll_create
  505. [59] amfExecutiveInitialize [66] memb_timer_function_state_commit_timeout [57] poll_dispatch_add
  506. [18] amfReadGroups [25] memb_timer_function_state_gather [2] poll_run
  507. [40] ckptConfChg [37] mempool_free [13] poll_timer_add
  508. [41] clmConfChg [67] mempool_init [28] poll_timer_delete
  509. [60] clmExecutiveInitialize [56] mempool_malloc [49] queue_init
  510. [42] confchg_fn [68] mempool_realloc [35] queue_reinit
  511. [7] deliver_fn [38] mempool_strdup [47] queues_pend_delv_memb_new
  512. [61] determine_local_if [4] message_handler_mcast [3] recv_handler
  513. [17] findComponent [26] message_handler_memb_attempt_join [70] sq_init
  514. [27] gmi_init [20] message_handler_memb_form_token [33] sq_items_release
  515. [62] gmi_join [24] message_handler_memb_join [48] sq_reinit
  516. [50] gmi_mcast [8] message_handler_orf_token [39] strstr_rs
  517. [51] gmi_pend_trans_item_store [19] message_handler_req_exec_ckpt_checkpointopen [71] this_ip_set
  518. [63] grow_connections_table [21] message_handler_req_exec_ckpt_sectioncreate [29] timerlist_add
  519. [64] handle_create [11] message_handler_req_exec_ckpt_sectionwrite [14] timerlist_add_future
  520. [36] internal_log_printf [44] message_handler_req_exec_clm_nodejoin [30] timerlist_del
  521. [52] libraryNotificationJoin [15] messages_free [9] timerlist_expire
  522. [43] libraryNotificationLeave [55] orf_timer_function_token_timeout [72] timerlist_init
  523. [65] memb_conf_id_build [31] orf_token_evs [10] timerlist_timeout_msec
  524. [46] memb_form_token_conf_desc_build [12] orf_token_mcast [6] user_deliver
  525. [53] memb_print_commit_set [32] orf_token_rtr