<div>Am I reading this correctly?</div><div>0 calls of proc_lib:init_p_do_apply/3 but taking up sometimes, 30 seconds?</div><div>Calling into gen_server:loop/6 which actually does the work (buy why 0 calls, above, if it's in the trace?)</div>
<div>Which then calls into gen_server:decode_msg/8</div><div><br></div><div><div>Correct? I'm new at this... :)</div><div><br></div></div><div>(and, not shown here, further into gen_server:handle_msg/8 -> gen_server:handle_msg/5 ->gen_server:handle_common_reply/6 calling into gen_server:loop/6...)</div>
<div><br></div><div>If I am correct, what's the recommended way to debug those gen_server messages to find out why they are taking so darn long, besides just being large? (When we start pushing 380kB+ sized messages through the system it starts crying.)</div>
<div><br></div><div>Thanks!</div><div><br></div><div>-mox</div><div><br></div><div><br></div><div><br></div><div><br></div><div><div>{[{{proc_lib,init_p_do_apply,3}, 0,30283.167, 0.007}, </div><div>
{{proc_lib,init_p_do_apply,3}, 0,29494.168, 0.007}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,29484.167, 0.006}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,29479.170, 0.007}, </div>
<div> {{proc_lib,init_p_do_apply,3}, 0,29370.168, 0.005}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,29359.169, 0.006}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,29356.220, 0.006}, </div>
<div> {{proc_lib,init_p_do_apply,3}, 0,24698.223, 0.061}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,24698.040, 0.003}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,17498.139, 0.005}, </div>
<div> {{proc_lib,init_p_do_apply,3}, 0,12391.071, 0.003}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,12389.865, 0.004}, </div><div> {{proc_lib,init_p_do_apply,3}, 0,12389.406, 0.003}, </div>
<div> {{proc_lib,init_p_do_apply,3}, 0,12389.060, 0.006}, </div><div> {undefined, 0,12388.011, 0.003}, </div><div> {{gen_server,handle_common_reply,6}, 3,12357.349, 0.014}, </div>
<div> {{proc_lib,init_p_do_apply,3}, 0, 31.961, 0.004}, </div><div> {{gen_server,handle_msg,5}, 2, 0.000, 0.031}, </div><div> {{gen_server,handle_msg,5}, 1, 0.000, 0.009}, </div>
<div> {{gen_server,handle_msg,5}, 1, 0.000, 0.007}, </div><div> {{gen_server,handle_msg,5}, 1, 0.000, 0.003}, </div><div> {{gen_server,handle_common_reply,6}, 497, 0.000, 5.803}, </div>
<div> {{gen_server,handle_common_reply,6}, 30, 0.000, 0.333}, </div><div> {{gen_server,handle_common_reply,6}, 30, 0.000, 0.307}, </div><div> {{gen_server,handle_common_reply,6}, 30, 0.000, 0.306}, </div>
<div> {{gen_server,handle_common_reply,6}, 30, 0.000, 0.290}, </div><div> {{gen_server,handle_common_reply,6}, 30, 0.000, 0.285}, </div><div> {{gen_server,handle_common_reply,6}, 30, 0.000, 0.277}, </div>
<div> {{gen_server,handle_common_reply,6}, 6, 0.000, 0.039}, </div><div> {{gen_server,handle_common_reply,6}, 4, 0.000, 0.018}, </div><div> {{gen_server,handle_common_reply,6}, 3, 0.000, 0.081}, </div>
<div> {{gen_server,handle_common_reply,6}, 2, 0.000, 0.009}, </div><div> {{gen_server,handle_common_reply,6}, 2, 0.000, 0.008}, </div><div> {{gen_server,handle_common_reply,6}, 1, 0.000, 0.003}], </div>
</div><div><div> { {gen_server,loop,6}, 703,348057.354, 7.959}, %</div><div> [{{gen_server,decode_msg,8}, 497,30283.160, 4.056}, </div><div> {suspend, 497,30151.291, 0.000}, </div>
<div> {{gen_server,decode_msg,8}, 30,29494.161, 0.167}, </div><div> {suspend, 30,29492.573, 0.000}, </div><div> {{gen_server,decode_msg,8}, 30,29484.161, 0.219}, </div>
<div> {suspend, 30,29482.507, 0.000}, </div><div> {{gen_server,decode_msg,8}, 30,29479.163, 0.163}, </div><div> {suspend, 30,29477.584, 0.000}, </div>
<div> {{gen_server,decode_msg,8}, 30,29370.163, 0.166}, </div><div> {suspend, 30,29368.534, 0.000}, </div><div> {{gen_server,decode_msg,8}, 30,29359.163, 0.146}, </div>
<div> {suspend, 30,29357.584, 0.000}, </div><div> {{gen_server,decode_msg,8}, 30,29356.214, 0.202}, </div><div> {suspend, 30,29354.670, 0.000}, </div>
<div> {{gen_server,decode_msg,8}, 3,24698.162, 0.012}, </div><div> {{gen_server,decode_msg,8}, 4,24698.037, 0.014}, </div><div> {suspend, 3,24697.710, 0.000}, </div>
<div> {suspend, 3,24697.567, 0.000}, </div><div> {{gen_server,decode_msg,8}, 7,17498.134, 0.036}, </div><div> {suspend, 7,17497.461, 0.000}, </div>
<div> {{gen_server,decode_msg,8}, 1,12391.068, 0.003}, </div><div> {suspend, 1,12391.030, 0.000}, </div><div> {{gen_server,decode_msg,8}, 2,12389.861, 0.007}, </div>
<div> {suspend, 2,12389.754, 0.000}, </div><div> {{gen_server,decode_msg,8}, 2,12389.403, 0.006}, </div><div> {suspend, 2,12389.338, 0.000}, </div>
<div> {{gen_server,decode_msg,8}, 2,12389.054, 0.007}, </div><div> {suspend, 3,12388.945, 0.000}, </div><div> {suspend, 2,12388.932, 0.000}, </div>
<div> {{gen_server,decode_msg,8}, 2,12388.008, 0.032}, </div><div> {suspend, 2,12387.777, 0.000}, </div><div> {{gen_server,decode_msg,8}, 3, 31.957, 0.037}, </div>
<div> {garbage_collect, 10, 0.100, 0.100}]}. </div></div>