Julian Seward 415490d305 Improvements to the built-in profiling infrastructure:
--profile-flags=00000000 now prints summary statistics, one line per
profiled block, but with no translation details.  Previously it had
no effect.

--profile-interval=<number> is a new flag that causes the profile data
to be dumped and zeroed every <number> event checks.  This makes it
possible to get profile data without waiting for runs to end, and to
get profile data which depends on the current workload etc.  If
--profile-interval=0 or is unset, the profile is printed only once, at
the end of the run, as before.

--profile-flags=XXXXXXXX (for at least one nonzero X) prints the
summary lines both at the start and end of the profile, so you don't
have to scroll back up to the top to see the summary.



git-svn-id: svn://svn.valgrind.org/valgrind/trunk@13213
2012-12-28 09:12:14 +00:00

200 lines
7.2 KiB
C

/*--------------------------------------------------------------------*/
/*--- For printing superblock profiles m_sbprofile.c ---*/
/*--------------------------------------------------------------------*/
/*
This file is part of Valgrind, a dynamic binary instrumentation
framework.
Copyright (C) 2012-2012 Mozilla Foundation
This program is free software; you can redistribute it and/or
modify it under the terms of the GNU General Public License as
published by the Free Software Foundation; either version 2 of the
License, or (at your option) any later version.
This program is distributed in the hope that it will be useful, but
WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
General Public License for more details.
You should have received a copy of the GNU General Public License
along with this program; if not, write to the Free Software
Foundation, Inc., 59 Temple Place, Suite 330, Boston, MA
02111-1307, USA.
The GNU General Public License is contained in the file COPYING.
*/
/* Contributed by Julian Seward <jseward@acm.org> */
#include "pub_core_basics.h"
#include "pub_core_transtab.h"
#include "pub_core_libcbase.h"
#include "pub_core_libcprint.h"
#include "pub_core_libcassert.h"
#include "pub_core_debuginfo.h"
#include "pub_core_translate.h"
#include "pub_core_options.h"
#include "pub_core_sbprofile.h" // self
/*====================================================================*/
/*=== SB profiling ===*/
/*====================================================================*/
static UInt n_profiles = 0;
static
void show_SB_profile ( SBProfEntry tops[], UInt n_tops,
ULong score_total, ULong ecs_done )
{
ULong score_cumul, score_cumul_saved, score_here;
HChar buf_cumul[10], buf_here[10];
HChar name[64];
Int r; /* must be signed */
HChar ecs_txt[50];
if (ecs_done > 0) {
VG_(sprintf)(ecs_txt, "%'llu ecs done", ecs_done);
} else {
VG_(strcpy)(ecs_txt, "for the entire run");
}
vg_assert(VG_(clo_profyle_sbs));
VG_(printf)("\n");
VG_(printf)("<<<---<<<---<<<---<<<---<<<---<<<---<<<---"
"<<<---<<<---<<<---<<<---<<<---<<<\n");
VG_(printf)("<<<---<<<---<<<---<<<---<<<---<<<---<<<---"
"<<<---<<<---<<<---<<<---<<<---<<<\n");
VG_(printf)("\n");
VG_(printf)("<<< BEGIN SB Profile #%u (%s)\n",
++n_profiles, ecs_txt);
VG_(printf)("<<<\n");
VG_(printf)("\n");
VG_(printf)("Total score = %'lld\n\n", score_total);
/* Print an initial per-block summary. */
VG_(printf)("rank ---cumulative--- -----self-----\n");
score_cumul = 0;
for (r = 0; r < n_tops; r++) {
if (tops[r].addr == 0)
continue;
if (tops[r].score == 0)
continue;
name[0] = 0;
VG_(get_fnname_w_offset)(tops[r].addr, name, 64);
name[63] = 0;
score_here = tops[r].score;
score_cumul += score_here;
VG_(percentify)(score_cumul, score_total, 2, 6, buf_cumul);
VG_(percentify)(score_here, score_total, 2, 6, buf_here);
VG_(printf)("%3d: (%9lld %s) %9lld %s 0x%llx %s\n",
r,
score_cumul, buf_cumul,
score_here, buf_here, tops[r].addr, name );
}
score_cumul_saved = score_cumul;
if (VG_(clo_profyle_flags) > 0) {
/* Show the details, if requested. */
VG_(printf)("\n");
VG_(printf)("-----------------------------"
"------------------------------\n");
VG_(printf)("--- SB Profile (SB details) "
" ---\n");
VG_(printf)("-----------------------------"
"------------------------------\n");
VG_(printf)("\n");
score_cumul = 0;
for (r = 0; r < n_tops; r++) {
if (tops[r].addr == 0)
continue;
if (tops[r].score == 0)
continue;
name[0] = 0;
VG_(get_fnname_w_offset)(tops[r].addr, name, 64);
name[63] = 0;
score_here = tops[r].score;
score_cumul += score_here;
VG_(percentify)(score_cumul, score_total, 2, 6, buf_cumul);
VG_(percentify)(score_here, score_total, 2, 6, buf_here);
VG_(printf)("\n");
VG_(printf)("=-=-=-=-=-=-=-=-=-=-=-=-=-= begin SB rank %d "
"=-=-=-=-=-=-=-=-=-=-=-=-=-=\n\n", r);
VG_(printf)("%3d: (%9lld %s) %9lld %s 0x%llx %s\n",
r,
score_cumul, buf_cumul,
score_here, buf_here, tops[r].addr, name );
VG_(printf)("\n");
VG_(discard_translations)(tops[r].addr, 1, "bb profile");
VG_(translate)(0, tops[r].addr, True, VG_(clo_profyle_flags), 0, True);
VG_(printf)("=-=-=-=-=-=-=-=-=-=-=-=-=-= end SB rank %d "
"=-=-=-=-=-=-=-=-=-=-=-=-=-=\n\n", r);
}
/* Print a final per-block summary, in reverse order, for the
convenience of people reading up from the end. */
score_cumul = score_cumul_saved;
for (r = n_tops-1; r >= 0; r--) {
if (tops[r].addr == 0)
continue;
if (tops[r].score == 0)
continue;
name[0] = 0;
VG_(get_fnname_w_offset)(tops[r].addr, name, 64);
name[63] = 0;
score_here = tops[r].score;
VG_(percentify)(score_cumul, score_total, 2, 6, buf_cumul);
VG_(percentify)(score_here, score_total, 2, 6, buf_here);
VG_(printf)("%3d: (%9lld %s) %9lld %s 0x%llx %s\n",
r,
score_cumul, buf_cumul,
score_here, buf_here, tops[r].addr, name );
score_cumul -= score_here;
}
VG_(printf)("rank ---cumulative--- -----self-----\n");
}
VG_(printf)("\n");
VG_(printf)(">>>\n");
VG_(printf)(">>> END SB Profile #%u (%s)\n",
n_profiles, ecs_txt);
VG_(printf)(">>>\n");
VG_(printf)(">>>--->>>--->>>--->>>--->>>--->>>--->>>---"
">>>--->>>--->>>--->>>--->>>--->>>\n");
VG_(printf)(">>>--->>>--->>>--->>>--->>>--->>>--->>>---"
">>>--->>>--->>>--->>>--->>>--->>>\n");
VG_(printf)("\n");
}
/* Get and print a profile. Also, zero out the counters so that if we
call it again later, the second call will only show new work done
since the first call. ecs_done == 0 is taken to mean this is a
run-end profile. */
void VG_(get_and_show_SB_profile) ( ULong ecs_done )
{
/* The number of blocks to show for a end-of-run profile */
# define N_MAX_END 200
/* The number of blocks to show for a mid-run profile. */
# define N_MAX_INTERVAL 20
vg_assert(N_MAX_INTERVAL <= N_MAX_END);
SBProfEntry tops[N_MAX_END];
Int nToShow = ecs_done == 0 ? N_MAX_END : N_MAX_INTERVAL;
ULong score_total = VG_(get_SB_profile)(tops, nToShow);
show_SB_profile(tops, nToShow, score_total, ecs_done);
# undef N_MAX_END
# undef N_MAX_INTERVAL
}
/*--------------------------------------------------------------------*/
/*--- end m_sbprofile.c ---*/
/*--------------------------------------------------------------------*/