<div dir="ltr">Never mind.  It helps if you bm_start_timer() and bm_stop_timer() with the same name!  <div><br></div><div><br></div><div><br></div><div>- Jeff</div></div><br><div class="gmail_quote"><div dir="ltr">On Tue, Sep 18, 2018 at 5:54 PM Jeff Pyle <<a href="mailto:jeff@ugnd.org">jeff@ugnd.org</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div dir="ltr"><div dir="ltr"><div dir="ltr"><div dir="ltr">Hello,<div><br></div><div>On 2.4.2 I'm running bm_start_timer("db_query_time") right before I call async(avp_db_query()), and bm_log_timer("db_query_time") first thing in the resume route.  The results are strange:</div><div><br></div><div><div>benchmark (timer db_query_time [1]): 1537307242867591 [ msgs/total/min/max/avg - LR: 1/1537307242867591/4294967295/1537307242867591/1537307242867591.000000 | GB: 1/1537307242867591/4294967295/1537307242867591/1537307242867591.000000]</div></div><div><div>benchmark (timer db_query_time [1]): 1537307278764967 [ msgs/total/min/max/avg - LR: 1/1537307278764967/4294967295/1537307278764967/1537307278764967.000000 | GB: 2/3074614521632558/4294967295/1537307278764967/1537307260816279.000000]</div></div><div>benchmark (timer db_query_time [1]): 1537307283581219 [ msgs/total/min/max/avg - LR: 1/1537307283581219/4294967295/1537307283581219/1537307283581219.000000 | GB: 3/4611921805213777/4294967295/1537307283581219/1537307268404592.250000]<br></div><div><br></div><div>This is with granularity=1 for testing where the actual DB delay was probably 40-50ms.  I'm thinking I can't run timers across async functions?  Or...?</div><div><br></div><div><br></div><div>Regards,<br>Jeff</div><div><br></div></div></div></div></div></div>
</blockquote></div>