summaryrefslogtreecommitdiff
path: root/ext
diff options
context:
space:
mode:
authorFlorian Ragwitz <rafl@debian.org>2011-02-19 00:03:56 +0100
committerFlorian Ragwitz <rafl@debian.org>2011-02-19 00:03:56 +0100
commitade33ac2954f9478493dc04eb7bfa173aebcc1bc (patch)
tree7a7f24b426448bd6e14da4b3210489dd7c2f3140 /ext
parenta7d069054a598a6162aff1dda3f68e9d5b9a5a5f (diff)
downloadperl-ade33ac2954f9478493dc04eb7bfa173aebcc1bc.tar.gz
Dual-life Devel::DProf
It's not actually on CPAN yet, but it will be in the next couple of days.
Diffstat (limited to 'ext')
-rw-r--r--ext/Devel-DProf/Changes204
-rw-r--r--ext/Devel-DProf/DProf.pm250
-rw-r--r--ext/Devel-DProf/DProf.xs785
-rw-r--r--ext/Devel-DProf/Makefile.PL18
-rw-r--r--ext/Devel-DProf/Todo13
-rw-r--r--ext/Devel-DProf/bin/dprofpp942
-rw-r--r--ext/Devel-DProf/dprof/V.pm63
-rw-r--r--ext/Devel-DProf/dprof/test1_t18
-rw-r--r--ext/Devel-DProf/dprof/test1_v24
-rw-r--r--ext/Devel-DProf/dprof/test2_t21
-rw-r--r--ext/Devel-DProf/dprof/test2_v36
-rw-r--r--ext/Devel-DProf/dprof/test3_t19
-rw-r--r--ext/Devel-DProf/dprof/test3_v29
-rw-r--r--ext/Devel-DProf/dprof/test4_t24
-rw-r--r--ext/Devel-DProf/dprof/test4_v36
-rw-r--r--ext/Devel-DProf/dprof/test5_t25
-rw-r--r--ext/Devel-DProf/dprof/test5_v15
-rw-r--r--ext/Devel-DProf/dprof/test6_t29
-rw-r--r--ext/Devel-DProf/dprof/test6_v16
-rw-r--r--ext/Devel-DProf/dprof/test7_t9
-rw-r--r--ext/Devel-DProf/dprof/test7_v10
-rw-r--r--ext/Devel-DProf/dprof/test8_t15
-rw-r--r--ext/Devel-DProf/dprof/test8_v11
-rw-r--r--ext/Devel-DProf/t/DProf.t93
24 files changed, 0 insertions, 2705 deletions
diff --git a/ext/Devel-DProf/Changes b/ext/Devel-DProf/Changes
deleted file mode 100644
index 1d1ba0e7b9..0000000000
--- a/ext/Devel-DProf/Changes
+++ /dev/null
@@ -1,204 +0,0 @@
-2003 Aug 1
- Radu Greab:
- DProf.xs:
- - do not assume that $^P stays unchanged inside the profiled subroutine
- DProf.pm:
- - increase VERSION
-
-2003 Jul 6
-
- Radu Greab:
- DProf.xs:
- - improved the mapping between subroutines and identifiers
- - do not assume that $^P stays unchanged during the lifetime of the script
- - panic when the profiled subroutine is leaved with goto/last/next
- DProf.pm:
- - document the problem with the subroutines exited with goto/last/next
- t/test{7,8}*
- - added
-
-2003 Jan 8
-
- Blair Zajac:
- DProf.xs:
- - To avoid core dumps, increase stack size by 10 instead of 5.
- - Assert that g_profstack is large enough when DEBUGGING is defined
- DProf.pm:
- - Bump VERSION.
-
-1999 Jan 8
-
- Ilya Zakharevich:
- Newer perls: Add PERL_POLLUTE and dTHR.
-
-1998 Nov 10
-This version of DProf should work with older Perls too, but to get
-full benefits some patches to 5.004_55 are needed. Patches take effect
-after new version of Perl is installed, and DProf recompiled.
-
-Without these patches the overhead of DProf is too big, thus the statistic
-may be very skewed.
-
-Oct 98:
- Ilya Zakharevich:
- DProf.xs
- - correct defstash to PL_defstash
- - nonlocal exits work
- dprofpp
- - nonlocal exits work
- DProf.pm
- - documentation updated
- t/test6.*
- - added
-
-Nov-Dec 97:
- Jason E. Holt and Ilya Zakharevich:
- DProf.xs
- - will not wait until completion to write the output, size of buffer
- regulated by PERL_DPROF_BUFFER, default 2**14 words;
-
- Ilya Zakharevich:
- dprofpp
- - smarter in fixing garbled profiles;
- - subtracts DProf output overhead, and suggested profiler overhead;
- - new options -A, -R, -g subroutine, -S;
- - handles 'goto' too;
- DProf.xs
- - 7x denser output (time separated from name, ids for subs);
- - outputs report-write overhead;
- - optional higher-resolution (currently OS/2 only, cannot grok VMS code);
- - outputs suggested profiler overhead;
- - handles 'goto' too;
- - handles PERL_DPROF_TICKS (on OS/2, VMS may be easily modified too)
-
-Jun 14, 97 andreas koenig adds the compatibility notes to the README
-and lets the Makefile.PL die on $] < 5.004.
-
-Jun 06, 97 andreas koenig applies a patch by gurusamy sarathy because
-Dean is not available for comments at that time. The patch is available
-from CPAN in the authors/id/GSAR directory for inspection.
-
-Sep 30, 96 dmr
- DProf.xs
- - added Ilya's patches to fix "&bar as &bar(@_)" bug. This also fixes
- the coredumps people have seen when using this with 5.003+.
- DProf.pm
- - updated manpage
- t/bug.t
- - moved to test5
- Makefile.PL
- - remove special case for bug.t
-
-Jun 26, 96 dmr
- dprofpp.PL
- - smarter r.e. to find VERSION in Makefile (for MM5.27).
- DProf.pm
- - updated manpage
- DProf.xs
- - keep pid of profiled process, if process forks then only the
- parent is profiled. Added test4 for this.
-
-Mar 2, 96 dmr
- README
- - updated
- dprofpp
- - updated manpage, point to DProf for raw profile description.
- DProf.pm
- - update manpage, update raw profile description with XS_VERSION.
- - update manpage for AUTOLOAD changes.
- DProf.xs
- - smart handling of &AUTOLOAD--looks in $AUTOLOAD for the sub name.
- this fixes one problem with corrupt profiles.
-
-Feb 5, 96 dmr
- dprofpp
- - updated manpage
- - added -E/-I for exclusive/inclusive times
- - added DPROFPP_OPTS -- lazily
- - added -p/-Q for profile-then-analyze
- - added version check
- dprofpp.PL
- - pull dprofpp's version id from the makefile
- DProf.pm
- - added version to bootstrap
- - updated doc
- - updated doc, DProf and -w are now friendly to each other
- DProf.xs
- - using savepv
- - added Tim's patch to check for DBsub, avoids -MDevel::DProf coredump
- - turn off warnings during newXS("DB::sub")
- tests
- - added Tim's patch to ignore Loader::import in results
- - added Tim's patch to aid readability of test?.v output
-
-
--- from those days when I kept a unique changelog for each module --
-
-# Devel::DProf - a Perl code profiler
-# 31oct95
-#
-# changes/bugs fixed since 5apr95 version -dmr:
-# -added VMS patches from CharlesB.
-# -now open ./tmon.out in BOOT.
-# changes/bugs fixed since 2apr95 version -dmr:
-# -now mallocing an extra byte for the \0 :)
-# changes/bugs fixed since 01mar95 version -dmr:
-# -stringified code ref is used for name of anonymous sub.
-# -include stash name with stringified code ref.
-# -use perl.c's DBsingle and DBsub.
-# -now using croak() and warn().
-# -print "timer is on" before turning timer on.
-# -use safefree() instead of free().
-# -rely on PM to provide full path name to tmon.out.
-# -print errno if unable to write tmon.out.
-# changes/bugs fixed since 03feb95 version -dmr:
-# -comments
-# changes/bugs fixed since 31dec94 version -dmr:
-# -added patches from AndyD.
-#
-
-# Devel::DProf - a Perl code profiler
-# 31oct95
-#
-# changes/bugs fixed since 05apr95 version -dmr:
-# - VMS-related prob; now let tmon.out name be handled in XS.
-# changes/bugs fixed since 01mar95 version -dmr:
-# - record $pwd and build pathname for tmon.out
-# changes/bugs fixed since 03feb95 version -dmr:
-# - fixed some doc bugs
-# - added require 5.000
-# - added -w note to bugs section of pod
-# changes/bugs fixed since 31dec94 version -dmr:
-# - podified
-#
-
-
-# dprofpp - display perl profile data
-# 31oct95
-#
-# changes/bugs fixed since 7oct95 version -dmr:
-# - PL'd
-# changes/bugs fixed since 5apr95 version -dmr:
-# - touch up handling of exit timestamps.
-# - suggests -F when exit timestamps are missing.
-# - added compressed execution tree patches from AchimB, put under -t.
-# now -z is the default action; user+system time.
-# - doc changes.
-# changes/bugs fixed since 10feb95 version -dmr:
-# - summary info is printed by default, opt_c is gone.
-# - fixed some doc bugs
-# - changed name to dprofpp
-# changes/bugs fixed since 03feb95 version -dmr:
-# - fixed division by zero.
-# - replace many local()s with my().
-# - now prints user+system times by default
-# now -u prints user time, -U prints unsorted.
-# - fixed documentation
-# - fixed output, to clarify that times are given in seconds.
-# - can now fake exit timestamps if the profile is garbled.
-# changes/bugs fixed since 17jun94 version -dmr:
-# - podified.
-# - correct old documentation flaws.
-# - added AndyD's patches.
-#
-
diff --git a/ext/Devel-DProf/DProf.pm b/ext/Devel-DProf/DProf.pm
deleted file mode 100644
index 8b4788aad7..0000000000
--- a/ext/Devel-DProf/DProf.pm
+++ /dev/null
@@ -1,250 +0,0 @@
-use 5.006_001;
-
-=head1 NAME
-
-Devel::DProf - a B<DEPRECATED> Perl code profiler
-
-=head1 SYNOPSIS
-
- perl -d:DProf test.pl
-
-=head1 ACHTUNG!
-
-C<Devel::DProf> is B<DEPRECATED> and will be removed from a future version of
-Perl. We strongly recommend that you install and use L<Devel::NYTProf> instead,
-as it offers significantly improved profiling and reporting.
-
-=head1 DESCRIPTION
-
-The Devel::DProf package is a Perl code profiler. This will collect
-information on the execution time of a Perl script and of the subs in that
-script. This information can be used to determine which subroutines are
-using the most time and which subroutines are being called most often. This
-information can also be used to create an execution graph of the script,
-showing subroutine relationships.
-
-To profile a Perl script run the perl interpreter with the B<-d> debugging
-switch. The profiler uses the debugging hooks. So to profile script
-F<test.pl> the following command should be used:
-
- perl -d:DProf test.pl
-
-When the script terminates (or when the output buffer is filled) the
-profiler will dump the profile information to a file called
-F<tmon.out>. A tool like I<dprofpp> can be used to interpret the
-information which is in that profile. The following command will
-print the top 15 subroutines which used the most time:
-
- dprofpp
-
-To print an execution graph of the subroutines in the script use the
-following command:
-
- dprofpp -T
-
-Consult L<dprofpp> for other options.
-
-=head1 PROFILE FORMAT
-
-The old profile is a text file which looks like this:
-
- #fOrTyTwO
- $hz=100;
- $XS_VERSION='DProf 19970606';
- # All values are given in HZ
- $rrun_utime=2; $rrun_stime=0; $rrun_rtime=7
- PART2
- + 26 28 566822884 DynaLoader::import
- - 26 28 566822884 DynaLoader::import
- + 27 28 566822885 main::bar
- - 27 28 566822886 main::bar
- + 27 28 566822886 main::baz
- + 27 28 566822887 main::bar
- - 27 28 566822888 main::bar
- [....]
-
-The first line is the magic number. The second line is the hertz value, or
-clock ticks, of the machine where the profile was collected. The third line
-is the name and version identifier of the tool which created the profile.
-The fourth line is a comment. The fifth line contains three variables
-holding the user time, system time, and realtime of the process while it was
-being profiled. The sixth line indicates the beginning of the sub
-entry/exit profile section.
-
-The columns in B<PART2> are:
-
- sub entry(+)/exit(-) mark
- app's user time at sub entry/exit mark, in ticks
- app's system time at sub entry/exit mark, in ticks
- app's realtime at sub entry/exit mark, in ticks
- fully-qualified sub name, when possible
-
-With newer perls another format is used, which may look like this:
-
- #fOrTyTwO
- $hz=10000;
- $XS_VERSION='DProf 19971213';
- # All values are given in HZ
- $over_utime=5917; $over_stime=0; $over_rtime=5917;
- $over_tests=10000;
- $rrun_utime=1284; $rrun_stime=0; $rrun_rtime=1284;
- $total_marks=6;
-
- PART2
- @ 406 0 406
- & 2 main bar
- + 2
- @ 456 0 456
- - 2
- @ 1 0 1
- & 3 main baz
- + 3
- @ 141 0 141
- + 2
- @ 141 0 141
- - 2
- @ 1 0 1
- & 4 main foo
- + 4
- @ 142 0 142
- + & Devel::DProf::write
- @ 5 0 5
- - & Devel::DProf::write
-
-(with high value of $ENV{PERL_DPROF_TICKS}).
-
-New C<$over_*> values show the measured overhead of making $over_tests
-calls to the profiler These values are used by the profiler to
-subtract the overhead from the runtimes.
-
-Lines starting with C<@> mark the amount of time passed since the
-previous C<@> line. The numbers following the C<@> are integer tick
-counts representing user, system, and real time. Divide these numbers
-by the $hz value in the header to get seconds.
-
-Lines starting with C<&> map subroutine identifiers (an integer) to
-subroutine packages and names. These should only occur once per
-subroutine.
-
-Lines starting with C<+> or C<-> mark normal entering and exit of
-subroutines. The number following is a reference to a subroutine
-identifier.
-
-Lines starting with C<*> mark where subroutines are entered by C<goto
-&subr>, but note that the return will still be marked as coming from
-the original sub. The sequence might look like this:
-
- + 5
- * 6
- - 5
-
-Lines starting with C</> is like C<-> but mark where subroutines are
-exited by dying. Example:
-
- + 5
- + 6
- / 6
- / 5
-
-Finally you might find C<@> time stamp marks surrounded by C<+ &
-Devel::DProf::write> and C<- & Devel::DProf::write> lines. These 3
-lines are outputted when printing of the mark above actually consumed
-measurable time.
-
-=head1 AUTOLOAD
-
-When Devel::DProf finds a call to an C<&AUTOLOAD> subroutine it looks at the
-C<$AUTOLOAD> variable to find the real name of the sub being called. See
-L<perlsub/"Autoloading">.
-
-=head1 ENVIRONMENT
-
-C<PERL_DPROF_BUFFER> sets size of output buffer in words. Defaults to 2**14.
-
-C<PERL_DPROF_TICKS> sets number of ticks per second on some systems where
-a replacement for times() is used. Defaults to the value of C<HZ> macro.
-
-C<PERL_DPROF_OUT_FILE_NAME> sets the name of the output file. If not set,
-defaults to tmon.out.
-
-=head1 BUGS
-
-Builtin functions cannot be measured by Devel::DProf.
-
-With a newer Perl DProf relies on the fact that the numeric slot of
-$DB::sub contains an address of a subroutine. Excessive manipulation
-of this variable may overwrite this slot, as in
-
- $DB::sub = 'current_sub';
- ...
- $addr = $DB::sub + 0;
-
-will set this numeric slot to numeric value of the string
-C<current_sub>, i.e., to C<0>. This will cause a segfault on the exit
-from this subroutine. Note that the first assignment above does not
-change the numeric slot (it will I<mark> it as invalid, but will not
-write over it).
-
-Another problem is that if a subroutine exits using goto(LABEL),
-last(LABEL) or next(LABEL) then perl may crash or Devel::DProf will die
-with the error:
-
- panic: Devel::DProf inconsistent subroutine return
-
-For example, this code will break under Devel::DProf:
-
- sub foo {
- last FOO;
- }
- FOO: {
- foo();
- }
-
-A pattern like this is used by Test::More's skip() function, for
-example. See L<perldiag> for more details.
-
-Mail bug reports and feature requests to the perl5-porters mailing list at
-F<E<lt>perl5-porters@perl.orgE<gt>>.
-
-=head1 SEE ALSO
-
-L<perl>, L<dprofpp>, times(2)
-
-=cut
-
-# This sub is needed for calibration.
-package Devel::DProf;
-
-sub NONESUCH_noxs {
- return $Devel::DProf::VERSION;
-}
-
-{
- package DB;
-
- #
- # As of perl5.003_20, &DB::sub stub is not needed (some versions
- # even had problems if stub was redefined with XS version).
- #
-
- # disable DB single-stepping
- BEGIN { $single = 0; }
-
- # This sub is needed during startup.
- sub DB {
- # print "nonXS DBDB\n";
- }
-}
-
-use XSLoader ();
-
-$Devel::DProf::VERSION = '20110217.00'; # this version not authorized by
- # Dean Roehrich. See "Changes" file.
-
-use if $] >= 5.013, 'deprecate';
-
-sub import {
- XSLoader::load 'Devel::DProf', $Devel::DProf::VERSION;
-}
-
-1;
diff --git a/ext/Devel-DProf/DProf.xs b/ext/Devel-DProf/DProf.xs
deleted file mode 100644
index 4eef0bc289..0000000000
--- a/ext/Devel-DProf/DProf.xs
+++ /dev/null
@@ -1,785 +0,0 @@
-#define PERL_NO_GET_CONTEXT
-#include "EXTERN.h"
-#include "perl.h"
-#include "XSUB.h"
-
-/* define DBG_SUB to cause a warning on each subroutine entry. */
-/*#define DBG_SUB 1 */
-
-/* define DBG_TIMER to cause a warning when the timer is turned on and off. */
-/*#define DBG_TIMER 1 */
-
-#ifdef DEBUGGING
-#define ASSERT(x) assert(x)
-#else
-#define ASSERT(x)
-#endif
-
-static CV *
-db_get_cv(pTHX_ SV *sv)
-{
- CV *cv;
-
- if (SvIOK(sv)) { /* if (PERLDB_SUB_NN) { */
- cv = INT2PTR(CV*,SvIVX(sv));
- } else {
- if (SvPOK(sv)) {
- STRLEN len;
- const char *const name = SvPV(sv, len);
- cv = get_cvn_flags(name, len, GV_ADD | SvUTF8(sv));
- } else if (SvROK(sv)) {
- cv = (CV*)SvRV(sv);
- } else {
- croak("DProf: don't know what subroutine to profile");
- }
- }
- return cv;
-}
-
-#ifdef DBG_SUB
-# define DBG_SUB_NOTIFY(A) dprof_dbg_sub_notify(aTHX_ A)
-void
-dprof_dbg_sub_notify(pTHX_ SV *Sub) {
- CV * const cv = db_get_cv(aTHX_ Sub);
- GV * const gv = cv ? CvGV(cv) : NULL;
- if (cv && gv) {
- warn("XS DBsub(%s::%s)\n",
- ((GvSTASH(gv) && HvNAME_get(GvSTASH(gv))) ?
- HvNAME_get(GvSTASH(gv)) : "(null)"),
- GvNAME(gv));
- } else {
- warn("XS DBsub(unknown) at %x", Sub);
- }
-}
-#else
-# define DBG_SUB_NOTIFY(A) /* nothing */
-#endif
-
-
-#ifdef DBG_TIMER
-# define DBG_TIMER_NOTIFY(A) warn(A)
-#else
-# define DBG_TIMER_NOTIFY(A) /* nothing */
-#endif
-
-/* HZ == clock ticks per second */
-#ifdef VMS
-# define HZ ((I32)CLK_TCK)
-# define DPROF_HZ HZ
-# include <starlet.h> /* prototype for sys$gettim() */
-# include <lib$routines.h>
-# define Times(ptr) (dprof_times(aTHX_ ptr))
-# define NEEDS_DPROF_TIMES
-#else
-# ifdef BSDish
-# define Times(ptr) (dprof_times(aTHX_ ptr))
-# define NEEDS_DPROF_TIMES
-# define HZ 1000000
-# define DPROF_HZ HZ
-# else
-# ifndef HZ
-# ifdef CLK_TCK
-# define HZ ((I32)CLK_TCK)
-# else
-# define HZ 60
-# endif
-# endif
-# ifdef OS2 /* times() has significant overhead */
-# define Times(ptr) (dprof_times(aTHX_ ptr))
-# define NEEDS_DPROF_TIMES
-# define INCL_DOSPROFILE
-# define INCL_DOSERRORS
-# include <os2.h>
-# define toLongLong(arg) (*(long long*)&(arg))
-# define DPROF_HZ g_dprof_ticks
-# else
-# define Times(ptr) (times(ptr))
-# define DPROF_HZ HZ
-# endif
-# endif
-#endif
-
-XS(XS_Devel__DProf_END); /* used by prof_mark() */
-
-/* Everything is built on times(2). See its manpage for a description
- * of the timings.
- */
-
-union prof_any {
- clock_t tms_utime; /* cpu time spent in user space */
- clock_t tms_stime; /* cpu time spent in system */
- clock_t realtime; /* elapsed real time, in ticks */
- const char *name;
- U32 id;
- opcode ptype;
-};
-
-typedef union prof_any PROFANY;
-
-typedef struct {
- U32 dprof_ticks;
- const char* out_file_name; /* output file (defaults to tmon.out) */
- PerlIO* fp; /* pointer to tmon.out file */
- Off_t TIMES_LOCATION; /* Where in the file to store the time totals */
- int SAVE_STACK; /* How much data to buffer until end of run */
- int prof_pid; /* pid of profiled process */
- struct tms prof_start;
- struct tms prof_end;
- clock_t rprof_start; /* elapsed real time ticks */
- clock_t rprof_end;
- clock_t wprof_u;
- clock_t wprof_s;
- clock_t wprof_r;
- clock_t otms_utime;
- clock_t otms_stime;
- clock_t orealtime;
- PROFANY* profstack;
- int profstack_max;
- int profstack_ix;
- HV* cv_hash; /* cache of CV to identifier mappings */
- SV* key_hash; /* key for cv_hash */
- U32 total;
- U32 lastid;
- U32 default_perldb;
- UV depth;
-#ifdef OS2
- ULONG frequ;
- long long start_cnt;
-#endif
-#ifdef PERL_IMPLICIT_CONTEXT
- PerlInterpreter *my_perl;
-#endif
-} prof_state_t;
-
-prof_state_t g_prof_state;
-
-#define g_dprof_ticks g_prof_state.dprof_ticks
-#define g_out_file_name g_prof_state.out_file_name
-#define g_fp g_prof_state.fp
-#define g_TIMES_LOCATION g_prof_state.TIMES_LOCATION
-#define g_SAVE_STACK g_prof_state.SAVE_STACK
-#define g_prof_pid g_prof_state.prof_pid
-#define g_prof_start g_prof_state.prof_start
-#define g_prof_end g_prof_state.prof_end
-#define g_rprof_start g_prof_state.rprof_start
-#define g_rprof_end g_prof_state.rprof_end
-#define g_wprof_u g_prof_state.wprof_u
-#define g_wprof_s g_prof_state.wprof_s
-#define g_wprof_r g_prof_state.wprof_r
-#define g_otms_utime g_prof_state.otms_utime
-#define g_otms_stime g_prof_state.otms_stime
-#define g_orealtime g_prof_state.orealtime
-#define g_profstack g_prof_state.profstack
-#define g_profstack_max g_prof_state.profstack_max
-#define g_profstack_ix g_prof_state.profstack_ix
-#define g_cv_hash g_prof_state.cv_hash
-#define g_key_hash g_prof_state.key_hash
-#define g_total g_prof_state.total
-#define g_lastid g_prof_state.lastid
-#define g_default_perldb g_prof_state.default_perldb
-#define g_depth g_prof_state.depth
-#ifdef PERL_IMPLICIT_CONTEXT
-# define g_THX g_prof_state.my_perl
-#endif
-#ifdef OS2
-# define g_frequ g_prof_state.frequ
-# define g_start_cnt g_prof_state.start_cnt
-#endif
-
-#ifdef NEEDS_DPROF_TIMES
-static clock_t
-dprof_times(pTHX_ struct tms *t)
-{
-#ifdef OS2
- ULONG rc;
- QWORD cnt;
-
- if (!g_frequ) {
- if (CheckOSError(DosTmrQueryFreq(&g_frequ)))
- croak("DosTmrQueryFreq: %s", SvPV_nolen(perl_get_sv("!",GV_ADD)));
- else
- g_frequ = g_frequ/DPROF_HZ; /* count per tick */
- if (CheckOSError(DosTmrQueryTime(&cnt)))
- croak("DosTmrQueryTime: %s",
- SvPV_nolen_const(perl_get_sv("!",GV_ADD)));
- g_start_cnt = toLongLong(cnt);
- }
-
- if (CheckOSError(DosTmrQueryTime(&cnt)))
- croak("DosTmrQueryTime: %s", SvPV_nolen(perl_get_sv("!",GV_ADD)));
- t->tms_stime = 0;
- return (t->tms_utime = (toLongLong(cnt) - g_start_cnt)/g_frequ);
-#else /* !OS2 */
-# ifdef VMS
- clock_t retval;
- /* Get wall time and convert to 10 ms intervals to
- * produce the return value dprof expects */
-# if defined(__DECC) && defined (__ALPHA)
-# include <ints.h>
- uint64 vmstime;
- _ckvmssts(sys$gettim(&vmstime));
- vmstime /= 100000;
- retval = vmstime & 0x7fffffff;
-# else
- /* (Older hw or ccs don't have an atomic 64-bit type, so we
- * juggle 32-bit ints (and a float) to produce a time_t result
- * with minimal loss of information.) */
- long int vmstime[2],remainder,divisor = 100000;
- _ckvmssts(sys$gettim((unsigned long int *)vmstime));
- vmstime[1] &= 0x7fff; /* prevent overflow in EDIV */
- _ckvmssts(lib$ediv(&divisor,vmstime,(long int *)&retval,&remainder));
-# endif
- /* Fill in the struct tms using the CRTL routine . . .*/
- times((tbuffer_t *)t);
- return (clock_t) retval;
-# else /* !VMS && !OS2 */
-# ifdef BSDish
-# include <sys/resource.h>
- struct rusage ru;
- struct timeval tv;
- /* Measure offset from start time to avoid overflow */
- static struct timeval tv0 = { 0, 0 };
-
- if (!tv0.tv_sec)
- if (gettimeofday(&tv0, NULL) < 0)
- croak("gettimeofday: %s", SvPV_nolen_const(perl_get_sv("!",GV_ADD)));
-
- if (getrusage(0, &ru) < 0)
- croak("getrusage: %s", SvPV_nolen_const(perl_get_sv("!",GV_ADD)));
-
- if (gettimeofday(&tv, NULL) < 0)
- croak("gettimeofday: %s", SvPV_nolen_const(perl_get_sv("!",GV_ADD)));
-
- t->tms_stime = DPROF_HZ * ru.ru_stime.tv_sec + ru.ru_stime.tv_usec;
- t->tms_utime = DPROF_HZ * ru.ru_utime.tv_sec + ru.ru_utime.tv_usec;
-
- if (tv.tv_usec < tv0.tv_usec)
- tv.tv_sec--, tv.tv_usec += DPROF_HZ;
-
- return DPROF_HZ * (tv.tv_sec - tv0.tv_sec) + tv.tv_usec - tv0.tv_usec;
-# else /* !VMS && !OS2 && !BSD! */
- return times(t);
-# endif
-# endif
-#endif
-}
-#endif
-
-static void
-prof_dumpa(pTHX_ opcode ptype, U32 id)
-{
- if (ptype == OP_LEAVESUB) {
- PerlIO_printf(g_fp,"- %"UVxf"\n", (UV)id);
- }
- else if(ptype == OP_ENTERSUB) {
- PerlIO_printf(g_fp,"+ %"UVxf"\n", (UV)id);
- }
- else if(ptype == OP_GOTO) {
- PerlIO_printf(g_fp,"* %"UVxf"\n", (UV)id);
- }
- else if(ptype == OP_DIE) {
- PerlIO_printf(g_fp,"/ %"UVxf"\n", (UV)id);
- }
- else {
- PerlIO_printf(g_fp,"Profiler unknown prof code %d\n", ptype);
- }
-}
-
-static void
-prof_dumps(pTHX_ U32 id, const char *pname, const char *gname)
-{
- PerlIO_printf(g_fp,"& %"UVxf" %s %s\n", (UV)id, pname, gname);
-}
-
-static void
-prof_dumpt(pTHX_ long tms_utime, long tms_stime, long realtime)
-{
- PerlIO_printf(g_fp,"@ %ld %ld %ld\n", tms_utime, tms_stime, realtime);
-}
-
-static void
-prof_dump_until(pTHX_ long ix)
-{
- long base = 0;
- struct tms t1, t2;
- clock_t realtime2;
-
- const clock_t realtime1 = Times(&t1);
-
- while (base < ix) {
- const opcode ptype = g_profstack[base++].ptype;
- if (ptype == OP_TIME) {
- const long tms_utime = g_profstack[base++].tms_utime;
- const long tms_stime = g_profstack[base++].tms_stime;
- const long realtime = g_profstack[base++].realtime;
-
- prof_dumpt(aTHX_ tms_utime, tms_stime, realtime);
- }
- else if (ptype == OP_GV) {
- const U32 id = g_profstack[base++].id;
- const char * const pname = g_profstack[base++].name;
- const char * const gname = g_profstack[base++].name;
-
- prof_dumps(aTHX_ id, pname, gname);
- }
- else {
- const U32 id = g_profstack[base++].id;
- prof_dumpa(aTHX_ ptype, id);
- }
- }
- PerlIO_flush(g_fp);
- realtime2 = Times(&t2);
- if (realtime2 != realtime1 || t1.tms_utime != t2.tms_utime
- || t1.tms_stime != t2.tms_stime) {
- g_wprof_r += realtime2 - realtime1;
- g_wprof_u += t2.tms_utime - t1.tms_utime;
- g_wprof_s += t2.tms_stime - t1.tms_stime;
-
- PerlIO_printf(g_fp,"+ & Devel::DProf::write\n");
- PerlIO_printf(g_fp,"@ %"IVdf" %"IVdf" %"IVdf"\n",
- /* The (IV) casts are one possibility:
- * the Painfully Correct Way would be to
- * have Clock_t_f. */
- (IV)(t2.tms_utime - t1.tms_utime),
- (IV)(t2.tms_stime - t1.tms_stime),
- (IV)(realtime2 - realtime1));
- PerlIO_printf(g_fp,"- & Devel::DProf::write\n");
- g_otms_utime = t2.tms_utime;
- g_otms_stime = t2.tms_stime;
- g_orealtime = realtime2;
- PerlIO_flush(g_fp);
- }
-}
-
-static void
-set_cv_key(pTHX_ CV *cv, const char *pname, const char *gname)
-{
- SvGROW(g_key_hash, sizeof(CV**) + strlen(pname) + strlen(gname) + 3);
- sv_setpvn(g_key_hash, (char*)&cv, sizeof(CV**));
- sv_catpv(g_key_hash, pname);
- sv_catpv(g_key_hash, "::");
- sv_catpv(g_key_hash, gname);
-}
-
-static void
-prof_mark(pTHX_ opcode ptype)
-{
- struct tms t;
- clock_t realtime, rdelta, udelta, sdelta;
- U32 id;
- SV * const Sub = GvSV(PL_DBsub); /* name of current sub */
-
- if (g_SAVE_STACK) {
- if (g_profstack_ix + 10 > g_profstack_max) {
- g_profstack_max = g_profstack_max * 3 / 2;
- Renew(g_profstack, g_profstack_max, PROFANY);
- }
- }
-
- realtime = Times(&t);
- rdelta = realtime - g_orealtime;
- udelta = t.tms_utime - g_otms_utime;
- sdelta = t.tms_stime - g_otms_stime;
- if (rdelta || udelta || sdelta) {
- if (g_SAVE_STACK) {
- ASSERT(g_profstack_ix + 4 <= g_profstack_max);
- g_profstack[g_profstack_ix++].ptype = OP_TIME;
- g_profstack[g_profstack_ix++].tms_utime = udelta;
- g_profstack[g_profstack_ix++].tms_stime = sdelta;
- g_profstack[g_profstack_ix++].realtime = rdelta;
- }
- else { /* Write it to disk now so's not to eat up core */
- if (g_prof_pid == (int)getpid()) {
- prof_dumpt(aTHX_ udelta, sdelta, rdelta);
- PerlIO_flush(g_fp);
- }
- }
- g_orealtime = realtime;
- g_otms_stime = t.tms_stime;
- g_otms_utime = t.tms_utime;
- }
-
- {
- SV **svp;
- char *gname, *pname;
-
- CV * const cv = db_get_cv(aTHX_ Sub);
- GV * const gv = CvGV(cv);
- if (isGV_with_GP(gv)) {
- pname = GvSTASH(gv) ? HvNAME_get(GvSTASH(gv)) : NULL;
- pname = pname ? pname : (char *) "(null)";
- gname = GvNAME(gv);
- } else {
- gname = pname = (char *) "(null)";
- }
-
- set_cv_key(aTHX_ cv, pname, gname);
- svp = hv_fetch(g_cv_hash, SvPVX_const(g_key_hash), SvCUR(g_key_hash), TRUE);
- if (!SvOK(*svp)) {
- sv_setiv(*svp, id = ++g_lastid);
- if (CvXSUB(cv) == XS_Devel__DProf_END)
- return;
- if (g_SAVE_STACK) { /* Store it for later recording -JH */
- ASSERT(g_profstack_ix + 4 <= g_profstack_max);
- g_profstack[g_profstack_ix++].ptype = OP_GV;
- g_profstack[g_profstack_ix++].id = id;
- g_profstack[g_profstack_ix++].name = pname;
- g_profstack[g_profstack_ix++].name = gname;
- }
- else { /* Write it to disk now so's not to eat up core */
- /* Only record the parent's info */
- if (g_prof_pid == (int)getpid()) {
- prof_dumps(aTHX_ id, pname, gname);
- PerlIO_flush(g_fp);
- }
- else
- PL_perldb = 0; /* Do not debug the kid. */
- }
- }
- else {
- id = SvIV(*svp);
- }
- }
-
- g_total++;
- if (g_SAVE_STACK) { /* Store it for later recording -JH */
- ASSERT(g_profstack_ix + 2 <= g_profstack_max);
- g_profstack[g_profstack_ix++].ptype = ptype;
- g_profstack[g_profstack_ix++].id = id;
-
- /* Only record the parent's info */
- if (g_SAVE_STACK < g_profstack_ix) {
- if (g_prof_pid == (int)getpid())
- prof_dump_until(aTHX_ g_profstack_ix);
- else
- PL_perldb = 0; /* Do not debug the kid. */
- g_profstack_ix = 0;
- }
- }
- else { /* Write it to disk now so's not to eat up core */
-
- /* Only record the parent's info */
- if (g_prof_pid == (int)getpid()) {
- prof_dumpa(aTHX_ ptype, id);
- PerlIO_flush(g_fp);
- }
- else
- PL_perldb = 0; /* Do not debug the kid. */
- }
-}
-
-/* Counts overhead of prof_mark and extra XS call. */
-static void
-test_time(pTHX_ clock_t *r, clock_t *u, clock_t *s)
-{
- CV * const cv = get_cvs("Devel::DProf::NONESUCH_noxs", 0);
- HV * const oldstash = PL_curstash;
- struct tms t1, t2;
- const U32 ototal = g_total;
- const U32 ostack = g_SAVE_STACK;
- const U32 operldb = PL_perldb;
- int k = 0;
-
- clock_t realtime1 = Times(&t1);
- clock_t realtime2 = 0;
-
- g_SAVE_STACK = 1000000;
-
- while (k < 2) {
- int i = 0;
- /* Disable debugging of perl_call_sv on second pass: */
- PL_curstash = (k == 0 ? PL_defstash : PL_debstash);
- PL_perldb = g_default_perldb;
- while (++i <= 100) {
- int j = 0;
- g_profstack_ix = 0; /* Do not let the stack grow */
- while (++j <= 100) {
-/* prof_mark(aTHX_ OP_ENTERSUB); */
-
- PUSHMARK(PL_stack_sp);
- perl_call_sv((SV*)cv, G_SCALAR);
- PL_stack_sp--;
-/* prof_mark(aTHX_ OP_LEAVESUB); */
- }
- }
- PL_curstash = oldstash;
- if (k == 0) { /* Put time with debugging */
- realtime2 = Times(&t2);
- *r = realtime2 - realtime1;
- *u = t2.tms_utime - t1.tms_utime;
- *s = t2.tms_stime - t1.tms_stime;
- }
- else { /* Subtract time without debug */
- realtime1 = Times(&t1);
- *r -= realtime1 - realtime2;
- *u -= t1.tms_utime - t2.tms_utime;
- *s -= t1.tms_stime - t2.tms_stime;
- }
- k++;
- }
- g_total = ototal;
- g_SAVE_STACK = ostack;
- PL_perldb = operldb;
-}
-
-static void
-prof_recordheader(pTHX)
-{
- clock_t r, u, s;
-
- /* g_fp is opened in the BOOT section */
- PerlIO_printf(g_fp, "#fOrTyTwO\n");
- PerlIO_printf(g_fp, "$hz=%"IVdf";\n", (IV)DPROF_HZ);
- PerlIO_printf(g_fp, "$XS_VERSION='DProf %s';\n", XS_VERSION);
- PerlIO_printf(g_fp, "# All values are given in HZ\n");
- test_time(aTHX_ &r, &u, &s);
- PerlIO_printf(g_fp,
- "$over_utime=%"IVdf"; $over_stime=%"IVdf"; $over_rtime=%"IVdf";\n",
- /* The (IV) casts are one possibility:
- * the Painfully Correct Way would be to
- * have Clock_t_f. */
- (IV)u, (IV)s, (IV)r);
- PerlIO_printf(g_fp, "$over_tests=10000;\n");
-
- g_TIMES_LOCATION = PerlIO_tell(g_fp);
-
- /* Pad with whitespace. */
- /* This should be enough even for very large numbers. */
- PerlIO_printf(g_fp, "%*s\n", 240 , "");
-
- PerlIO_printf(g_fp, "\n");
- PerlIO_printf(g_fp, "PART2\n");
-
- PerlIO_flush(g_fp);
-}
-
-static void
-prof_record(pTHX)
-{
- /* g_fp is opened in the BOOT section */
-
- /* Now that we know the runtimes, fill them in at the recorded
- location -JH */
-
- if (g_SAVE_STACK) {
- prof_dump_until(aTHX_ g_profstack_ix);
- }
- PerlIO_seek(g_fp, g_TIMES_LOCATION, SEEK_SET);
- /* Write into reserved 240 bytes: */
- PerlIO_printf(g_fp,
- "$rrun_utime=%"IVdf"; $rrun_stime=%"IVdf"; $rrun_rtime=%"IVdf";",
- /* The (IV) casts are one possibility:
- * the Painfully Correct Way would be to
- * have Clock_t_f. */
- (IV)(g_prof_end.tms_utime-g_prof_start.tms_utime-g_wprof_u),
- (IV)(g_prof_end.tms_stime-g_prof_start.tms_stime-g_wprof_s),
- (IV)(g_rprof_end-g_rprof_start-g_wprof_r));
- PerlIO_printf(g_fp, "\n$total_marks=%"IVdf, (IV)g_total);
-
- PerlIO_close(g_fp);
-}
-
-#define NONESUCH()
-
-static void
-check_depth(pTHX_ void *foo)
-{
- const U32 need_depth = PTR2UV(foo);
- if (need_depth != g_depth) {
- if (need_depth > g_depth) {
- warn("garbled call depth when profiling");
- }
- else {
- IV marks = g_depth - need_depth;
-
-/* warn("Check_depth: got %d, expected %d\n", g_depth, need_depth); */
- while (marks--) {
- prof_mark(aTHX_ OP_DIE);
- }
- g_depth = need_depth;
- }
- }
-}
-
-#define for_real
-#ifdef for_real
-
-XS(XS_DB_sub);
-XS(XS_DB_sub)
-{
- dMARK;
- dORIGMARK;
- SV * const Sub = GvSV(PL_DBsub); /* name of current sub */
-
-#ifdef PERL_IMPLICIT_CONTEXT
- /* profile only the interpreter that loaded us */
- if (g_THX != aTHX) {
- PUSHMARK(ORIGMARK);
- perl_call_sv((SV*)db_get_cv(aTHX_ Sub), GIMME_V | G_NODEBUG);
- }
- else
-#endif
- {
- HV * const oldstash = PL_curstash;
- const I32 old_scopestack_ix = PL_scopestack_ix;
- const I32 old_cxstack_ix = cxstack_ix;
-
- DBG_SUB_NOTIFY(Sub);
-
- SAVEDESTRUCTOR_X(check_depth, INT2PTR(void*,g_depth));
- g_depth++;
-
- prof_mark(aTHX_ OP_ENTERSUB);
- PUSHMARK(ORIGMARK);
- perl_call_sv((SV*)db_get_cv(aTHX_ Sub), GIMME_V | G_NODEBUG);
- PL_curstash = oldstash;
-
- /* Make sure we are on the same context and scope as before the call
- * to the sub. If the called sub was exited via a goto, next or
- * last then this will try to croak(), however perl may still crash
- * with a segfault. */
- if (PL_scopestack_ix != old_scopestack_ix || cxstack_ix != old_cxstack_ix)
- croak("panic: Devel::DProf inconsistent subroutine return");
-
- prof_mark(aTHX_ OP_LEAVESUB);
- g_depth--;
- }
- return;
-}
-
-XS(XS_DB_goto);
-XS(XS_DB_goto)
-{
-#ifdef PERL_IMPLICIT_CONTEXT
- if (g_THX == aTHX)
-#endif
- {
- prof_mark(aTHX_ OP_GOTO);
- return;
- }
-}
-
-#endif /* for_real */
-
-#ifdef testing
-
- MODULE = Devel::DProf PACKAGE = DB
-
- void
- sub(...)
- PPCODE:
- {
- dORIGMARK;
- HV * const oldstash = PL_curstash;
- SV * const Sub = GvSV(PL_DBsub); /* name of current sub */
- /* SP -= items; added by xsubpp */
- DBG_SUB_NOTIFY(Sub);
-
- sv_setiv(PL_DBsingle, 0); /* disable DB single-stepping */
-
- prof_mark(aTHX_ OP_ENTERSUB);
- PUSHMARK(ORIGMARK);
-
- PL_curstash = PL_debstash; /* To disable debugging of perl_call_sv */
- perl_call_sv(Sub, GIMME_V);
- PL_curstash = oldstash;
-
- prof_mark(aTHX_ OP_LEAVESUB);
- SPAGAIN;
- /* PUTBACK; added by xsubpp */
- }
-
-#endif /* testing */
-
-MODULE = Devel::DProf PACKAGE = Devel::DProf
-
-void
-END()
-PPCODE:
- {
- if (PL_DBsub) {
- /* maybe the process forked--we want only
- * the parent's profile.
- */
- if (
-#ifdef PERL_IMPLICIT_CONTEXT
- g_THX == aTHX &&
-#endif
- g_prof_pid == (int)getpid())
- {
- g_rprof_end = Times(&g_prof_end);
- DBG_TIMER_NOTIFY("Profiler timer is off.\n");
- prof_record(aTHX);
- }
- }
- }
-
-void
-NONESUCH()
-
-BOOT:
- {
- g_TIMES_LOCATION = 42;
- g_SAVE_STACK = 1<<14;
- g_profstack_max = 128;
-#ifdef PERL_IMPLICIT_CONTEXT
- g_THX = aTHX;
-#endif
-
- /* Before we go anywhere make sure we were invoked
- * properly, else we'll dump core.
- */
- if (!PL_DBsub)
- croak("DProf: run perl with -d to use DProf.\n");
-
- /* When we hook up the XS DB::sub we'll be redefining
- * the DB::sub from the PM file. Turn off warnings
- * while we do this.
- */
- {
- const bool warn_tmp = PL_dowarn;
- PL_dowarn = 0;
- newXS("DB::sub", XS_DB_sub, file);
- newXS("DB::goto", XS_DB_goto, file);
- PL_dowarn = warn_tmp;
- }
-
- sv_setiv(PL_DBsingle, 0); /* disable DB single-stepping */
-
- {
- const char *buffer = getenv("PERL_DPROF_BUFFER");
-
- if (buffer) {
- g_SAVE_STACK = atoi(buffer);
- }
-
- buffer = getenv("PERL_DPROF_TICKS");
-
- if (buffer) {
- g_dprof_ticks = atoi(buffer); /* Used under OS/2 only */
- }
- else {
- g_dprof_ticks = HZ;
- }
-
- buffer = getenv("PERL_DPROF_OUT_FILE_NAME");
- g_out_file_name = savepv(buffer ? buffer : "tmon.out");
- }
-
- if ((g_fp = PerlIO_open(g_out_file_name, "w")) == NULL)
- croak("DProf: unable to write '%s', errno = %d\n",
- g_out_file_name, errno);
-
- g_default_perldb = PERLDBf_NONAME | PERLDBf_SUB | PERLDBf_GOTO;
- g_cv_hash = newHV();
- g_key_hash = newSV(256);
- g_prof_pid = (int)getpid();
-
- Newx(g_profstack, g_profstack_max, PROFANY);
- prof_recordheader(aTHX);
- DBG_TIMER_NOTIFY("Profiler timer is on.\n");
- g_orealtime = g_rprof_start = Times(&g_prof_start);
- g_otms_utime = g_prof_start.tms_utime;
- g_otms_stime = g_prof_start.tms_stime;
- PL_perldb = g_default_perldb;
- }
diff --git a/ext/Devel-DProf/Makefile.PL b/ext/Devel-DProf/Makefile.PL
deleted file mode 100644
index 5bb00128d0..0000000000
--- a/ext/Devel-DProf/Makefile.PL
+++ /dev/null
@@ -1,18 +0,0 @@
-BEGIN {
- require 5.006;
-}
-
-use ExtUtils::MakeMaker;
-
-WriteMakefile(
- NAME => 'Devel::DProf',
- DISTNAME => 'DProf',
- VERSION_FROM => 'DProf.pm',
- clean => { 'FILES' => 'tmon.out t/tmon.out t/err'},
- XSPROTOARG => '-noprototypes',
- dist => {
- COMPRESS => 'gzip -9f',
- SUFFIX => 'gz',
- DIST_DEFAULT => 'all tardist',
- },
-);
diff --git a/ext/Devel-DProf/Todo b/ext/Devel-DProf/Todo
deleted file mode 100644
index 0e003471d6..0000000000
--- a/ext/Devel-DProf/Todo
+++ /dev/null
@@ -1,13 +0,0 @@
-- work on test suite.
-- localize the depth to guard against non-local exits.
-Current overhead (with PERLDBf_NONAME) wrt non-debugging run (estimates):
- 8% extra call frame on DB::sub
- 7% output of subroutine data
- 70% output of timing data (on OS/2, 35% with custom dprof_times())
-(Additional 17% are spent to write the output, but they are counted
- and subtracted.)
-
-With compensation for DProf overhead all but some odd 12% are subtracted ?!
-
-- Calculate overhead/count for XS calls and Perl calls separately.
-- goto &XSUB in pp_ctl.c;
diff --git a/ext/Devel-DProf/bin/dprofpp b/ext/Devel-DProf/bin/dprofpp
deleted file mode 100644
index 51d0ed9d9b..0000000000
--- a/ext/Devel-DProf/bin/dprofpp
+++ /dev/null
@@ -1,942 +0,0 @@
-#!/usr/bin/perl
-
-require 5.003;
-
-my $stty;
-BEGIN {
- foreach my $s (qw(/bin/stty /usr/bin/stty)) {
- if (-x $s) {
- $stty = $s;
- last;
- }
- }
-}
-
-require Devel::DProf;
-require deprecate;
-
-if($] > 5.013009 && deprecate::__loaded_from_core('Devel::DProf', $INC{'Devel/DProf.pm'},
- 'Devel/DProf.pm')) {
- warn "dprofpp will be removed from the Perl core distribution in the next major release. Please install Devel::DProf from CPAN.\n";
-}
-
-=head1 NAME
-
-dprofpp - display perl profile data
-
-=head1 SYNOPSIS
-
-dprofpp [B<-a>|B<-z>|B<-l>|B<-v>|B<-U>] [B<-d>] [B<-s>|B<-r>|B<-u>] [B<-q>] [B<-F>] [B<-I|-E>] [B<-O cnt>] [B<-A>] [B<-R>] [B<-S>] [B<-g subroutine>] [B<-G> <regexp> [B<-P>]] [B<-f> <regexp>] [profile]
-
-dprofpp B<-T> [B<-F>] [B<-g subroutine>] [profile]
-
-dprofpp B<-t> [B<-F>] [B<-g subroutine>] [profile]
-
-dprofpp B<-G> <regexp> [B<-P>] [profile]
-
-dprofpp B<-p script> [B<-Q>] [other opts]
-
-dprofpp B<-V> [profile]
-
-=head1 DESCRIPTION
-
-The I<dprofpp> command interprets profile data produced by a profiler, such
-as the Devel::DProf profiler. Dprofpp will read the file F<tmon.out> and
-display the 15 subroutines which are using the most time. By default
-the times for each subroutine are given exclusive of the times of their
-child subroutines.
-
-To profile a Perl script run the perl interpreter with the B<-d> switch. So
-to profile script F<test.pl> with Devel::DProf use the following:
-
- $ perl5 -d:DProf test.pl
-
-Then run dprofpp to analyze the profile. The output of dprofpp depends
-on the flags to the program and the version of Perl you're using.
-
- $ dprofpp -u
- Total Elapsed Time = 1.67 Seconds
- User Time = 0.61 Seconds
- Exclusive Times
- %Time Seconds #Calls sec/call Name
- 52.4 0.320 2 0.1600 main::foo
- 45.9 0.280 200 0.0014 main::bar
- 0.00 0.000 1 0.0000 DynaLoader::import
- 0.00 0.000 1 0.0000 main::baz
-
-The dprofpp tool can also run the profiler before analyzing the profile
-data. The above two commands can be executed with one dprofpp command.
-
- $ dprofpp -u -p test.pl
-
-Consult L<Devel::DProf/"PROFILE FORMAT"> for a description of the raw profile.
-
-=head1 OUTPUT
-
-Columns are:
-
-=over 4
-
-=item %Time
-
-Percentage of time spent in this routine.
-
-=item #Calls
-
-Number of calls to this routine.
-
-=item sec/call
-
-Average number of seconds per call to this routine.
-
-=item Name
-
-Name of routine.
-
-=item CumulS
-
-Time (in seconds) spent in this routine and routines called from it.
-
-=item ExclSec
-
-Time (in seconds) spent in this routine (not including those called
-from it).
-
-=item Csec/c
-
-Average time (in seconds) spent in each call of this routine
-(including those called from it).
-
-=back
-
-=head1 OPTIONS
-
-=over 5
-
-=item B<-a>
-
-Sort alphabetically by subroutine names.
-
-=item B<-d>
-
-Reverse whatever sort is used
-
-=item B<-A>
-
-Count timing for autoloaded subroutine as timing for C<*::AUTOLOAD>.
-Otherwise the time to autoload it is counted as time of the subroutine
-itself (there is no way to separate autoload time from run time).
-
-This is going to be irrelevant with newer Perls. They will inform
-C<Devel::DProf> I<when> the C<AUTOLOAD> switches to actual subroutine,
-so a separate statistics for C<AUTOLOAD> will be collected no matter
-whether this option is set.
-
-=item B<-R>
-
-Count anonymous subroutines defined in the same package separately.
-
-=item B<-E>
-
-(default) Display all subroutine times exclusive of child subroutine times.
-
-=item B<-F>
-
-Force the generation of fake exit timestamps if dprofpp reports that the
-profile is garbled. This is only useful if dprofpp determines that the
-profile is garbled due to missing exit timestamps. You're on your own if
-you do this. Consult the BUGS section.
-
-=item B<-I>
-
-Display all subroutine times inclusive of child subroutine times.
-
-=item B<-l>
-
-Sort by number of calls to the subroutines. This may help identify
-candidates for inlining.
-
-=item B<-O cnt>
-
-Show only I<cnt> subroutines. The default is 15.
-
-=item B<-p script>
-
-Tells dprofpp that it should profile the given script and then interpret its
-profile data. See B<-Q>.
-
-=item B<-Q>
-
-Used with B<-p> to tell dprofpp to quit after profiling the script, without
-interpreting the data.
-
-=item B<-q>
-
-Do not display column headers.
-
-=item B<-r>
-
-Display elapsed real times rather than user+system times.
-
-=item B<-s>
-
-Display system times rather than user+system times.
-
-=item B<-T>
-
-Display subroutine call tree to stdout. Subroutine statistics are
-not displayed.
-
-=item B<-t>
-
-Display subroutine call tree to stdout. Subroutine statistics are not
-displayed. When a function is called multiple consecutive times at the same
-calling level then it is displayed once with a repeat count.
-
-=item B<-S>
-
-Display I<merged> subroutine call tree to stdout. Statistics are
-displayed for each branch of the tree.
-
-When a function is called multiple (I<not necessarily consecutive>)
-times in the same branch then all these calls go into one branch of
-the next level. A repeat count is output together with combined
-inclusive, exclusive and kids time.
-
-Branches are sorted with regard to inclusive time.
-
-=item B<-U>
-
-Do not sort. Display in the order found in the raw profile.
-
-=item B<-u>
-
-Display user times rather than user+system times.
-
-=item B<-V>
-
-Print dprofpp's version number and exit. If a raw profile is found then its
-XS_VERSION variable will be displayed, too.
-
-=item B<-v>
-
-Sort by average time spent in subroutines during each call. This may help
-identify candidates for inlining.
-
-=item B<-z>
-
-(default) Sort by amount of user+system time used. The first few lines
-should show you which subroutines are using the most time.
-
-=item B<-g> C<subroutine>
-
-Ignore subroutines except C<subroutine> and whatever is called from it.
-
-=item B<-G> <regexp>
-
-Aggregate "Group" all calls matching the pattern together.
-For example this can be used to group all calls of a set of packages
-
- -G "(package1::)|(package2::)|(package3::)"
-
-or to group subroutines by name:
-
- -G "getNum"
-
-=item B<-P>
-
-Used with -G to aggregate "Pull" together all calls that did not match -G.
-
-=item B<-f> <regexp>
-
-Filter all calls matching the pattern.
-
-=item B<-h>
-
-Display brief help and exit.
-
-=item B<-H>
-
-Display long help and exit.
-
-=back
-
-=head1 ENVIRONMENT
-
-The environment variable B<DPROFPP_OPTS> can be set to a string containing
-options for dprofpp. You might use this if you prefer B<-I> over B<-E> or
-if you want B<-F> on all the time.
-
-This was added fairly lazily, so there are some undesirable side effects.
-Options on the commandline should override options in DPROFPP_OPTS--but
-don't count on that in this version.
-
-=head1 BUGS
-
-Applications which call _exit() or exec() from within a subroutine
-will leave an incomplete profile. See the B<-F> option.
-
-Any bugs in Devel::DProf, or any profiler generating the profile data, could
-be visible here. See L<Devel::DProf/BUGS>.
-
-Mail bug reports and feature requests to the perl5-porters mailing list at
-F<E<lt>perl5-porters@perl.orgE<gt>>. Bug reports should include the
-output of the B<-V> option.
-
-=head1 FILES
-
- dprofpp - profile processor
- tmon.out - raw profile
-
-=head1 SEE ALSO
-
-L<perl>, L<Devel::DProf>, times(2)
-
-=cut
-
-sub shortusage {
- print <<'EOF';
-dprofpp [options] [profile]
-
- -A Count autoloaded to *AUTOLOAD.
- -a Sort by alphabetic name of subroutines.
- -d Reverse sort.
- -E Sub times are reported exclusive of child times. (default)
- -f Filter all calls matching the pattern.
- -G Group all calls matching the pattern together.
- -g subr Count only subs who are SUBR or called from SUBR.
- -H Display long manual page.
- -h Display this short usage message.
- -I Sub times are reported inclusive of child times.
- -l Sort by number of calls to subroutines.
- -O cnt Specifies maximum number of subroutines to display.
- -P Used with -G to pull all other calls together.
- -p script Specifies name of script to be profiled.
- -Q Used with -p to indicate that dprofpp should quit
- after profiling the script, without interpreting the data.
- -q Do not print column headers.
- -R Count anonymous subs separately even if from the same package.
- -r Use real elapsed time rather than user+system time.
- -S Create statistics for all the depths.
- -s Use system time rather than user+system time.
- -T Show call tree.
- -t Show call tree, compressed.
- -U Do not sort subroutines.
- -u Use user time rather than user+system time.
- -V Print dprofpp's version.
- -v Sort by average amount of time spent in subroutines.
- -z Sort by user+system time spent in subroutines. (default)
-EOF
-}
-
-use Getopt::Std 'getopts';
-use Config '%Config';
-
-Setup: {
- my $options = 'O:g:G:Pf:dlzaAvuTtqrRsUFEIp:QVShH';
-
- $Monfile = 'tmon.out';
- if( exists $ENV{DPROFPP_OPTS} ){
- my @tmpargv = @ARGV;
- @ARGV = split( ' ', $ENV{DPROFPP_OPTS} );
- getopts( $options );
- if( @ARGV ){
- # there was a filename.
- $Monfile = shift;
- }
- @ARGV = @tmpargv;
- }
-
- getopts( $options ) or die "Try 'dprofpp -h' for help.\n";
- if( @ARGV ){
- # there was a filename, it overrides any earlier name.
- $Monfile = shift;
- }
-
- if ( defined $opt_h ) {
- shortusage();
- exit;
- }
- if ( defined $opt_H ) {
- require Pod::Usage;
- Pod::Usage::pod2usage( {-verbose => 2, -input => $0 } );
- exit;
- }
-
- if( defined $opt_V ){
- my $fh = 'main::fh';
- print "$0 version: $Devel::DProf::VERSION\n";
- open( $fh, "<$Monfile" ) && do {
- local $XS_VERSION = 'early';
- header($fh);
- close( $fh );
- print "XS_VERSION: $XS_VERSION\n";
- };
- exit(0);
- }
- $cnt = $opt_O || 15;
- $sort = 'by_time';
- $sort = 'by_ctime' if defined $opt_I;
- $sort = 'by_calls' if defined $opt_l;
- $sort = 'by_alpha' if defined $opt_a;
- $sort = 'by_avgcpu' if defined $opt_v;
-
- if(defined $opt_d){
- $sort = "r".$sort;
- }
- $incl_excl = 'Exclusive';
- $incl_excl = 'Inclusive' if defined $opt_I;
- $whichtime = 'User+System';
- $whichtime = 'System' if defined $opt_s;
- $whichtime = 'Real' if defined $opt_r;
- $whichtime = 'User' if defined $opt_u;
-
- if( defined $opt_p ){
- my $prof = 'DProf';
- my $startperl = $Config{'startperl'};
-
- $startperl =~ s/^#!//; # remove shebang
- run_profiler( $opt_p, $prof, $startperl );
- $Monfile = 'tmon.out'; # because that's where it is
- exit(0) if defined $opt_Q;
- }
- elsif( defined $opt_Q ){
- die "-Q is meaningful only when used with -p\n";
- }
-}
-
-Main: {
- my $monout = $Monfile;
- my $fh = 'main::fh';
- local $names = {};
- local $times = {}; # times in hz
- local $ctimes = {}; # Cumulative times in hz
- local $calls = {};
- local $persecs = {}; # times in seconds
- local $idkeys = [];
- local $runtime; # runtime in seconds
- my @a = ();
- my $a;
- local $rrun_utime = 0; # user time in hz
- local $rrun_stime = 0; # system time in hz
- local $rrun_rtime = 0; # elapsed run time in hz
- local $rrun_ustime = 0; # user+system time in hz
- local $hz = 0;
- local $deep_times = {count => 0 , kids => {}, incl_time => 0};
- local $time_precision = 2;
- local $overhead = 0;
-
- open( $fh, "<$monout" ) || die "Unable to open $monout\n";
-
- header($fh);
-
- $rrun_ustime = $rrun_utime + $rrun_stime;
-
- $~ = 'STAT';
- if( ! $opt_q ){
- $^ = 'CSTAT_top';
- }
-
- parsestack( $fh, $names, $calls, $times, $ctimes, $idkeys );
-
- #filter calls
- if( $opt_f ){
- for(my $i = 0;$i < @$idkeys - 2;){
- $key = $$idkeys[$i];
- if($key =~ /$opt_f/){
- splice(@$idkeys, $i, 1);
- $runtime -= $$times{$key};
- next;
- }
- $i++;
- }
- }
-
- if( $opt_G ){
- group($names, $calls, $times, $ctimes, $idkeys );
- }
-
- settime( \$runtime, $hz ) unless $opt_g;
-
- exit(0) if $opt_T || $opt_t;
-
- if( $opt_v ){
- percalc( $calls, ($opt_I ? $ctimes : $times), $persecs, $idkeys );
- }
- if( ! $opt_U ){
- @a = sort $sort @$idkeys;
- $a = \@a;
- }
- else {
- $a = $idkeys;
- }
- display( $runtime, $hz, $names, $calls, $times, $ctimes, $cnt, $a,
- $deep_times);
-}
-
-sub group{
- my ($names, $calls, $times, $ctimes, $idkeys ) = @_;
- print "Option G Grouping: [$opt_G]\n";
- # create entries to store grouping
- $$names{$opt_G} = $opt_G;
- $$calls{$opt_G} = 0;
- $$times{$opt_G} = 0;
- $$ctimes{$opt_G} = 0;
- $$idkeys[@$idkeys] = $opt_G;
- # Sum calls for the grouping
-
- my $other = "other";
- if($opt_P){
- $$names{$other} = $other;
- $$calls{$other} = 0;
- $$times{$other} = 0;
- $$ctimes{$other} = 0;
- $$idkeys[@$idkeys] = $other;
- }
-
- for(my $i = 0;$i < @$idkeys - 2;){
- $key = $$idkeys[$i];
- if($key =~ /$opt_G/){
- $$calls{$opt_G} += $$calls{$key};
- $$times{$opt_G} += $$times{$key};
- $$ctimes{$opt_G} += $$ctimes{$key};
- splice(@$idkeys, $i, 1);
- next;
- }else{
- if($opt_P){
- $$calls{$other} += $$calls{$key};
- $$times{$other} += $$times{$key};
- $$ctimes{$other} += $$ctimes{$key};
- splice(@$idkeys, $i, 1);
- next;
- }
- }
- $i++;
- }
- print "Grouping [$opt_G] Calls: [$$calls{$opt_G}]\n".
- "Grouping [$opt_G] Times: [$$times{$opt_G}]\n".
- "Grouping [$opt_G] IncTimes: [$$ctimes{$opt_G}]\n";
-}
-
-# Sets $runtime to user, system, real, or user+system time. The
-# result is given in seconds.
-#
-sub settime {
- my( $runtime, $hz ) = @_;
-
- $hz ||= 1;
-
- if( $opt_r ){
- $$runtime = ($rrun_rtime - $overhead)/$hz;
- }
- elsif( $opt_s ){
- $$runtime = ($rrun_stime - $overhead)/$hz;
- }
- elsif( $opt_u ){
- $$runtime = ($rrun_utime - $overhead)/$hz;
- }
- else{
- $$runtime = ($rrun_ustime - $overhead)/$hz;
- }
- $$runtime = 0 unless $$runtime > 0;
-}
-
-sub exclusives_in_tree {
- my( $deep_times ) = @_;
- my $kids_time = 0;
- my $kid;
- # When summing, take into account non-rounded-up kids time.
- for $kid (keys %{$deep_times->{kids}}) {
- $kids_time += $deep_times->{kids}{$kid}{incl_time};
- }
- $kids_time = 0 unless $kids_time >= 0;
- $deep_times->{excl_time} = $deep_times->{incl_time} - $kids_time;
- $deep_times->{excl_time} = 0 unless $deep_times->{excl_time} >= 0;
- for $kid (keys %{$deep_times->{kids}}) {
- exclusives_in_tree($deep_times->{kids}{$kid});
- }
- $deep_times->{incl_time} = 0 unless $deep_times->{incl_time} >= 0;
- $deep_times->{kids_time} = $kids_time;
-}
-
-sub kids_by_incl { $kids{$b}{incl_time} <=> $kids{$a}{excl_time}
- or $a cmp $b }
-
-sub display_tree {
- my( $deep_times, $name, $level ) = @_;
- exclusives_in_tree($deep_times);
-
- my $kid;
-
- my $time;
- if (%{$deep_times->{kids}}) {
- $time = sprintf '%.*fs = (%.*f + %.*f)',
- $time_precision, $deep_times->{incl_time}/$hz,
- $time_precision, $deep_times->{excl_time}/$hz,
- $time_precision, $deep_times->{kids_time}/$hz;
- } else {
- $time = sprintf '%.*f', $time_precision, $deep_times->{incl_time}/$hz;
- }
- print ' ' x (2*$level), "$name x $deep_times->{count} \t${time}s\n"
- if $deep_times->{count};
-
- for $kid (sort kids_by_incl %{$deep_times->{kids}}) {
- display_tree( $deep_times->{kids}{$kid}, $kid, $level + 1 );
- }
-}
-
-# Report the times in seconds.
-sub display {
- my( $runtime, $hz, $names, $calls, $times, $ctimes, $cnt,
- $idkeys, $deep_times ) = @_;
- my( $x, $key, $s, $cs );
- #format: $ncalls, $name, $secs, $percall, $pcnt
-
- if ($opt_S) {
- display_tree( $deep_times, 'toplevel', -1 )
- } else {
- for( $x = 0; $x < @$idkeys; ++$x ){
- $key = $idkeys->[$x];
- $ncalls = $calls->{$key};
- $name = $names->{$key};
- $s = $times->{$key}/$hz;
- $secs = sprintf("%.3f", $s );
- $cs = $ctimes->{$key}/$hz;
- $csecs = sprintf("%.3f", $cs );
- $percall = sprintf("%.4f", $s/$ncalls );
- $cpercall = sprintf("%.4f", $cs/$ncalls );
- $pcnt = sprintf("%.2f",
- $runtime? ((($opt_I ? $csecs : $secs) / $runtime) * 100.0): 0 );
- write;
- $pcnt = $secs = $ncalls = $percall = "";
- write while( length $name );
- last unless --$cnt;
- }
- }
-}
-
-sub move_keys {
- my ($source, $dest) = @_;
-
- for my $kid_name (keys %$source) {
- my $source_kid = delete $source->{$kid_name};
-
- if (my $dest_kid = $dest->{$kid_name}) {
- $dest_kid->{count} += $source_kid->{count};
- $dest_kid->{incl_time} += $source_kid->{incl_time};
- move_keys($source_kid->{kids},$dest_kid->{kids});
- } else {
- $dest->{$kid_name} = $source_kid;
- }
- }
-}
-
-sub add_to_tree {
- my ($curdeep_times, $name, $t) = @_;
- if ($name ne $curdeep_times->[-1]{name} and $opt_A) {
- $name = $curdeep_times->[-1]{name};
- }
- die "Shorted?!" unless @$curdeep_times >= 2;
- my $entry = $curdeep_times->[-2]{kids}{$name} ||= {
- count => 0,
- kids => {},
- incl_time => 0,
- };
- # Now transfer to the new node (could not do earlier, since name can change)
- $entry->{count}++;
- $entry->{incl_time} += $t - $curdeep_times->[-1]{enter_stamp};
- # Merge the kids?
- move_keys($curdeep_times->[-1]->{kids},$entry->{kids});
- pop @$curdeep_times;
-}
-
-
-sub parsestack {
- my( $fh, $names, $calls, $times, $ctimes, $idkeys ) = @_;
- my( $dir, $name );
- my( $t, $syst, $realt, $usert );
- my( $x, $z, $c, $id, $pack );
- my @stack = ();
- my @tstack = ();
- my %outer;
- my $tab = 3;
- my $in = 0;
-
- # remember last call depth and function name
- my $l_in = $in;
- my $l_name = '';
- my $repcnt = 0;
- my $repstr = '';
- my $dprof_stamp;
- my %cv_hash;
- my $in_level = not defined $opt_g; # Level deep in report grouping
- my $curdeep_times = [$deep_times];
-
- my $over_per_call;
- if ( $opt_u ) { $over_per_call = $over_utime }
- elsif( $opt_s ) { $over_per_call = $over_stime }
- elsif( $opt_r ) { $over_per_call = $over_rtime }
- else { $over_per_call = $over_utime + $over_stime }
- $over_per_call /= 2*$over_tests; # distribute over entry and exit
-
- while(<$fh>){
- next if /^#/;
- last if /^PART/;
-
- chop;
- if (/^&/) {
- ($dir, $id, $pack, $name) = split;
- if ($opt_R and ($name =~ /(?:::)?(__ANON__|END)$/)) {
- $name .= "($id)";
- }
- $cv_hash{$id} = "$pack\::$name";
- next;
- }
- ($dir, $usert, $syst, $realt, $name) = split;
-
- my $ot = $t;
- if ( $dir eq '/' ) {
- $syst = $stack[-1][0] if scalar @stack;
- $usert = '&';
- $dir = '-';
- #warn("Inserted exit for $stack[-1][0].\n")
- }
- if (defined $realt) { # '+ times nam' '- times nam' or '@ incr'
- if ( $opt_u ) { $t = $usert }
- elsif( $opt_s ) { $t = $syst }
- elsif( $opt_r ) { $t = $realt }
- else { $t = $usert + $syst }
- $t += $ot, next if $dir eq '@'; # Increments there
- } else {
- # "- id" or "- & name"
- $name = defined $syst ? $syst : $cv_hash{$usert};
- }
-
- next unless $in_level or $name eq $opt_g;
- if ( $dir eq '-' or $dir eq '*' ) {
- my $ename = $dir eq '*' ? $stack[-1][0] : $name;
- $overhead += $over_per_call;
- if ($name eq "Devel::DProf::write") {
- $overhead += $t - $dprof_stamp;
- next;
- } elsif (defined $opt_g and $ename eq $opt_g) {
- $in_level--;
- }
- add_to_tree($curdeep_times, $ename,
- $t - $overhead) if $opt_S;
- exitstamp( \@stack, \@tstack,
- $t - $overhead,
- $times, $ctimes, $name, \$in, $tab,
- $curdeep_times, \%outer );
- }
- next unless $in_level or $name eq $opt_g;
- if( $dir eq '+' or $dir eq '*' ){
- if ($name eq "Devel::DProf::write") {
- $dprof_stamp = $t;
- next;
- } elsif (defined $opt_g and $name eq $opt_g) {
- $in_level++;
- }
- $overhead += $over_per_call;
- if( $opt_T ){
- print ' ' x $in, "$name\n";
- $in += $tab;
- }
- elsif( $opt_t ){
- # suppress output on same function if the
- # same calling level is called.
- if ($l_in == $in and $l_name eq $name) {
- $repcnt++;
- } else {
- $repstr = ' ('.++$repcnt.'x)'
- if $repcnt;
- print ' ' x $l_in, "$l_name$repstr\n"
- if $l_name ne '';
- $repstr = '';
- $repcnt = 0;
- $l_in = $in;
- $l_name = $name;
- }
- $in += $tab;
- }
- if( ! defined $names->{$name} ){
- $names->{$name} = $name;
- $times->{$name} = 0;
- $ctimes->{$name} = 0;
- push( @$idkeys, $name );
- }
- $calls->{$name}++;
- $outer{$name}++;
- push @$curdeep_times, { kids => {},
- name => $name,
- enter_stamp => $t - $overhead,
- } if $opt_S;
- $x = [ $name, $t - $overhead ];
- push( @stack, $x );
-
- # my children will put their time here
- push( @tstack, 0 );
- } elsif ($dir ne '-'){
- die "Bad profile: $_";
- }
- }
- if( $opt_t ){
- $repstr = ' ('.++$repcnt.'x)' if $repcnt;
- print ' ' x $l_in, "$l_name$repstr\n";
- }
-
- while (my ($key, $count) = each %outer) {
- next unless $count;
- warn "$key has $count unstacked calls in outer\n";
- }
-
- if( @stack ){
- if( ! $opt_F ){
- warn "Garbled profile is missing some exit time stamps:\n";
- foreach $x (@stack) {
- print $x->[0],"\n";
- }
- die "Try rerunning dprofpp with -F.\n";
- # I don't want -F to be default behavior--yet
- # 9/18/95 dmr
- }
- else{
- warn( "Faking " . scalar( @stack ) . " exit timestamp(s).\n");
- foreach $x ( reverse @stack ){
- $name = $x->[0];
- exitstamp( \@stack, \@tstack,
- $t - $overhead, $times,
- $ctimes, $name, \$in, $tab,
- $curdeep_times, \%outer );
- add_to_tree($curdeep_times, $name,
- $t - $overhead)
- if $opt_S;
- }
- }
- }
- if (defined $opt_g) {
- $runtime = $ctimes->{$opt_g}/$hz;
- $runtime = 0 unless $runtime > 0;
- }
-}
-
-sub exitstamp {
- my($stack, $tstack, $t, $times, $ctimes, $name, $in, $tab, $deep, $outer) = @_;
- my( $x, $c, $z );
-
- $x = pop( @$stack );
- if( ! defined $x ){
- die "Garbled profile, missing an enter time stamp";
- }
- if( $x->[0] ne $name and $opt_G and ($name =~ /$opt_G/)){
- if ($x->[0] =~ /(?:::)?AUTOLOAD$/) {
- if ($opt_A) {
- $name = $x->[0];
- }
- } elsif ( $opt_F ) {
- warn( "Garbled profile, faking exit timestamp:\n\t$name => $x->[0].\n");
- $name = $x->[0];
- } else {
- foreach $z (@stack, $x) {
- print $z->[0],"\n";
- }
- die "Garbled profile, unexpected exit time stamp";
- }
- }
- if( $opt_T || $opt_t ){
- $$in -= $tab;
- }
- # collect childtime
- $c = pop( @$tstack );
- # total time this func has been active
- $z = $t - $x->[1];
- $ctimes->{$name} += $z
- unless --$outer->{$name};
- $times->{$name} += $z - $c;
- # pass my time to my parent
- if( @$tstack ){
- $c = pop( @$tstack );
- push( @$tstack, $c + $z );
- }
-}
-
-
-sub header {
- my $fh = shift;
- chop($_ = <$fh>);
- if( ! /^#fOrTyTwO$/ ){
- die "Not a perl profile";
- }
- while(<$fh>){
- next if /^#/;
- last if /^PART/;
- eval;
- }
- $over_tests = 1 unless $over_tests;
- $time_precision = length int ($hz - 1); # log ;-)
-}
-
-
-# Report avg time-per-function in seconds
-sub percalc {
- my( $calls, $times, $persecs, $idkeys ) = @_;
- my( $x, $t, $n, $key );
-
- for( $x = 0; $x < @$idkeys; ++$x ){
- $key = $idkeys->[$x];
- $n = $calls->{$key};
- $t = $times->{$key} / $hz;
- $persecs->{$key} = $t ? $t / $n : 0;
- }
-}
-
-
-# Runs the given script with the given profiler and the given perl.
-sub run_profiler {
- my $script = shift;
- my $profiler = shift;
- my $startperl = shift;
- my @script_parts = split /\s+/, $script;
-
- system $startperl, "-d:$profiler", @script_parts;
- if( $? / 256 > 0 ){
- my $cmd = join ' ', @script_parts;
- die "Failed: $startperl -d:$profiler $cmd: $!";
- }
-}
-
-
-sub by_time { $times->{$b} <=> $times->{$a} }
-sub by_ctime { $ctimes->{$b} <=> $ctimes->{$a} }
-sub by_calls { $calls->{$b} <=> $calls->{$a} }
-sub by_alpha { $names->{$a} cmp $names->{$b} }
-sub by_avgcpu { $persecs->{$b} <=> $persecs->{$a} }
-# Reversed
-sub rby_time { $times->{$a} <=> $times->{$b} }
-sub rby_ctime { $ctimes->{$a} <=> $ctimes->{$b} }
-sub rby_calls { $calls->{$a} <=> $calls->{$b} }
-sub rby_alpha { $names->{$b} cmp $names->{$a} }
-sub rby_avgcpu { $persecs->{$a} <=> $persecs->{$b} }
-
-
-format CSTAT_top =
-Total Elapsed Time = @>>>>>>> Seconds
-(($rrun_rtime - $overhead) / $hz)
- @>>>>>>>>>> Time = @>>>>>>> Seconds
-$whichtime, $runtime
-@<<<<<<<< Times
-$incl_excl
-%Time ExclSec CumulS #Calls sec/call Csec/c Name
-.
-
-BEGIN {
- my $fmt = ' ^>>> ^>>>> ^>>>>> ^>>>>> ^>>>>> ^>>>>> ^<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<';
- if (-t STDOUT and defined $stty and my ($cols) = `$stty -a` =~ /\bcolumns\s+(\d+)/)
- {
- $fmt .= '<' x ($cols - length $fmt) if $cols > 80;
- }
-
- eval "format STAT = \n$fmt" . '
-$pcnt, $secs, $csecs, $ncalls, $percall, $cpercall, $name
-.';
-}
diff --git a/ext/Devel-DProf/dprof/V.pm b/ext/Devel-DProf/dprof/V.pm
deleted file mode 100644
index e613f6f441..0000000000
--- a/ext/Devel-DProf/dprof/V.pm
+++ /dev/null
@@ -1,63 +0,0 @@
-package V;
-
-use Getopt::Std 'getopts';
-getopts('vp:d:');
-
-require Exporter;
-@ISA = 'Exporter';
-
-@EXPORT = qw( dprofpp $opt_v $results $expected report @results );
-@EXPORT_OK = qw( notok ok $num );
-
-$num = 0;
-$results = $expected = '';
-$perl = $opt_p || $^X;
-$dpp = $opt_d || '../../utils/dprofpp';
-$dpp .= '.com' if $^O eq 'VMS';
-
-print "\nperl: $perl\n" if $opt_v;
-if( ! -f $perl ){ die "Where's Perl?" }
-if( ! -f $dpp ) {
- ($dpp = $^X) =~ s@(^.*)[/|\\].*@$1/dprofpp@;
- die "Where's dprofpp?" if( ! -f $dpp );
-}
-
-sub dprofpp {
- my $switches = shift;
-
- open( D, "$perl \"-I../lib\" $dpp \"$switches\" 2> err |" ) || warn "$0: Can't run. $!\n";
- @results = <D>;
- close D;
-
- open( D, "<err" ) || warn "$0: Can't open: $!\n";
- @err = <D>;
- close D;
- push( @results, @err ) if @err;
-
- $results = qq{@results};
- # ignore Loader (Dyna/Auto etc), leave newline
- $results =~ s/^\w+Loader::import//;
- $results =~ s/\n /\n/gm;
- $results;
-}
-
-sub report {
- $num = shift;
- my $sub = shift;
- my $x;
-
- $x = &$sub;
- $x ? &ok : &notok;
-}
-
-sub ok {
- print "ok $num\n";
-}
-
-sub notok {
- print "not ok $num\n";
- print "\nResult\n{$results}\n";
- print "Expected\n{$expected}\n";
-}
-
-1;
diff --git a/ext/Devel-DProf/dprof/test1_t b/ext/Devel-DProf/dprof/test1_t
deleted file mode 100644
index d504cd5536..0000000000
--- a/ext/Devel-DProf/dprof/test1_t
+++ /dev/null
@@ -1,18 +0,0 @@
-sub foo {
- print "in sub foo\n";
- bar();
-}
-
-sub bar {
- print "in sub bar\n";
-}
-
-sub baz {
- print "in sub baz\n";
- bar();
- foo();
-}
-
-bar();
-baz();
-foo();
diff --git a/ext/Devel-DProf/dprof/test1_v b/ext/Devel-DProf/dprof/test1_v
deleted file mode 100644
index 542a503414..0000000000
--- a/ext/Devel-DProf/dprof/test1_v
+++ /dev/null
@@ -1,24 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$expected =
-qq{main::bar
-main::baz
- main::bar
- main::foo
- main::bar
-main::foo
- main::bar
-};
-report 1, sub { $expected eq $results };
-
-dprofpp('-TF');
-report 2, sub { $expected eq $results };
-
-dprofpp( '-t' );
-report 3, sub { $expected eq $results };
-
-dprofpp('-tF');
-report 4, sub { $expected eq $results };
diff --git a/ext/Devel-DProf/dprof/test2_t b/ext/Devel-DProf/dprof/test2_t
deleted file mode 100644
index edc46c527e..0000000000
--- a/ext/Devel-DProf/dprof/test2_t
+++ /dev/null
@@ -1,21 +0,0 @@
-sub foo {
- print "in sub foo\n";
- bar();
-}
-
-sub bar {
- print "in sub bar\n";
-}
-
-sub baz {
- print "in sub baz\n";
- bar();
- bar();
- bar();
- foo();
-}
-
-bar();
-bar();
-baz();
-foo();
diff --git a/ext/Devel-DProf/dprof/test2_v b/ext/Devel-DProf/dprof/test2_v
deleted file mode 100644
index 8b775b3131..0000000000
--- a/ext/Devel-DProf/dprof/test2_v
+++ /dev/null
@@ -1,36 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$expected =
-qq{main::bar
-main::bar
-main::baz
- main::bar
- main::bar
- main::bar
- main::foo
- main::bar
-main::foo
- main::bar
-};
-report 5, sub { $expected eq $results };
-
-dprofpp('-TF');
-report 6, sub { $expected eq $results };
-
-dprofpp( '-t' );
-$expected =
-qq{main::bar (2x)
-main::baz
- main::bar (3x)
- main::foo
- main::bar
-main::foo
- main::bar
-};
-report 7, sub { $expected eq $results };
-
-dprofpp('-tF');
-report 8, sub { $expected eq $results };
diff --git a/ext/Devel-DProf/dprof/test3_t b/ext/Devel-DProf/dprof/test3_t
deleted file mode 100644
index a5327f4d7a..0000000000
--- a/ext/Devel-DProf/dprof/test3_t
+++ /dev/null
@@ -1,19 +0,0 @@
-sub foo {
- print "in sub foo\n";
- exit(0);
- bar();
-}
-
-sub bar {
- print "in sub bar\n";
-}
-
-sub baz {
- print "in sub baz\n";
- bar();
- foo();
-}
-
-bar();
-baz();
-foo();
diff --git a/ext/Devel-DProf/dprof/test3_v b/ext/Devel-DProf/dprof/test3_v
deleted file mode 100644
index df7543e2b8..0000000000
--- a/ext/Devel-DProf/dprof/test3_v
+++ /dev/null
@@ -1,29 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$e1 = $expected =
-qq{main::bar
-main::baz
- main::bar
- main::foo
-};
-report 9, sub { $expected eq $results };
-
-dprofpp('-TF');
-$e2 = $expected =
-qq{main::bar
-main::baz
- main::bar
- main::foo
-};
-report 10, sub { $expected eq $results };
-
-dprofpp( '-t' );
-$expected = $e1;
-report 11, sub { 1 };
-
-dprofpp('-tF');
-$expected = $e2;
-report 12, sub { $expected eq $results };
diff --git a/ext/Devel-DProf/dprof/test4_t b/ext/Devel-DProf/dprof/test4_t
deleted file mode 100644
index 729968270a..0000000000
--- a/ext/Devel-DProf/dprof/test4_t
+++ /dev/null
@@ -1,24 +0,0 @@
-sub foo {
- print "in sub foo\n";
- bar();
-}
-
-sub bar {
- print "in sub bar\n";
-}
-
-sub baz {
- print "in sub baz\n";
- bar();
- bar();
- bar();
- foo();
-}
-
-bar();
-
-eval { fork };
-
-bar();
-baz();
-foo();
diff --git a/ext/Devel-DProf/dprof/test4_v b/ext/Devel-DProf/dprof/test4_v
deleted file mode 100644
index d9677ff785..0000000000
--- a/ext/Devel-DProf/dprof/test4_v
+++ /dev/null
@@ -1,36 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$expected =
-qq{main::bar
-main::bar
-main::baz
- main::bar
- main::bar
- main::bar
- main::foo
- main::bar
-main::foo
- main::bar
-};
-report 13, sub { $expected eq $results };
-
-dprofpp('-TF');
-report 14, sub { $expected eq $results };
-
-dprofpp( '-t' );
-$expected =
-qq{main::bar (2x)
-main::baz
- main::bar (3x)
- main::foo
- main::bar
-main::foo
- main::bar
-};
-report 15, sub { $expected eq $results };
-
-dprofpp('-tF');
-report 16, sub { $expected eq $results };
diff --git a/ext/Devel-DProf/dprof/test5_t b/ext/Devel-DProf/dprof/test5_t
deleted file mode 100644
index 0b1113757f..0000000000
--- a/ext/Devel-DProf/dprof/test5_t
+++ /dev/null
@@ -1,25 +0,0 @@
-# Test that dprof doesn't break
-# &bar; used as &bar(@_);
-
-sub foo1 {
- print "in foo1(@_)\n";
- bar(@_);
-}
-sub foo2 {
- print "in foo2(@_)\n";
- &bar;
-}
-sub bar {
- print "in bar(@_)\n";
- if( @_ > 0 ){
- &yeppers;
- }
-}
-sub yeppers {
- print "rest easy\n";
-}
-
-
-&foo1( A );
-&foo2( B );
-
diff --git a/ext/Devel-DProf/dprof/test5_v b/ext/Devel-DProf/dprof/test5_v
deleted file mode 100644
index 9e9298c689..0000000000
--- a/ext/Devel-DProf/dprof/test5_v
+++ /dev/null
@@ -1,15 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$expected =
-qq{main::foo1
- main::bar
- main::yeppers
-main::foo2
- main::bar
- main::yeppers
-};
-report 17, sub { $expected eq $results };
-
diff --git a/ext/Devel-DProf/dprof/test6_t b/ext/Devel-DProf/dprof/test6_t
deleted file mode 100644
index 7b8bf4a722..0000000000
--- a/ext/Devel-DProf/dprof/test6_t
+++ /dev/null
@@ -1,29 +0,0 @@
-sub foo {
- my $x;
- my $y;
- print "in sub foo\n";
- for( $x = 1; $x < 100; ++$x ){
- bar();
- for( $y = 1; $y < 100; ++$y ){
- }
- }
-}
-
-sub bar {
- my $x;
- print "in sub bar\n";
- for( $x = 1; $x < 100; ++$x ){
- }
- die "bar exiting";
-}
-
-sub baz {
- print "in sub baz\n";
- eval { bar(); };
- eval { foo(); };
-}
-
-eval { bar(); };
-baz();
-eval { foo(); };
-
diff --git a/ext/Devel-DProf/dprof/test6_v b/ext/Devel-DProf/dprof/test6_v
deleted file mode 100644
index 2f651ea794..0000000000
--- a/ext/Devel-DProf/dprof/test6_v
+++ /dev/null
@@ -1,16 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$expected =
-qq{main::bar
-main::baz
- main::bar
- main::foo
- main::bar
-main::foo
- main::bar
-};
-report 18, sub { $expected eq $results };
-
diff --git a/ext/Devel-DProf/dprof/test7_t b/ext/Devel-DProf/dprof/test7_t
deleted file mode 100644
index 56dbfd341c..0000000000
--- a/ext/Devel-DProf/dprof/test7_t
+++ /dev/null
@@ -1,9 +0,0 @@
-BEGIN {
- print "in BEGIN\n";
-}
-
-sub foo {
- print "in sub foo\n";
-}
-
-foo();
diff --git a/ext/Devel-DProf/dprof/test7_v b/ext/Devel-DProf/dprof/test7_v
deleted file mode 100644
index 1d19fe5cd5..0000000000
--- a/ext/Devel-DProf/dprof/test7_v
+++ /dev/null
@@ -1,10 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-T' );
-$expected =
-qq{main::BEGIN
-main::foo
-};
-report 19, sub { $expected eq $results };
diff --git a/ext/Devel-DProf/dprof/test8_t b/ext/Devel-DProf/dprof/test8_t
deleted file mode 100644
index 6154c8a530..0000000000
--- a/ext/Devel-DProf/dprof/test8_t
+++ /dev/null
@@ -1,15 +0,0 @@
-sub foo {
- print "in sub foo\n";
-}
-
-sub bar {
- print "in sub bar\n";
- $^P -= 0x40;
-}
-
-foo();
-$^P -= 0x40;
-foo();
-$^P += 0x40;
-bar();
-$^P += 0x40;
diff --git a/ext/Devel-DProf/dprof/test8_v b/ext/Devel-DProf/dprof/test8_v
deleted file mode 100644
index d5de3087fe..0000000000
--- a/ext/Devel-DProf/dprof/test8_v
+++ /dev/null
@@ -1,11 +0,0 @@
-# perl
-
-use V;
-
-dprofpp( '-t' );
-$expected =
-qq{main::foo (2x)
-main::bar
-};
-
-report 20, sub { $expected eq $results };
diff --git a/ext/Devel-DProf/t/DProf.t b/ext/Devel-DProf/t/DProf.t
deleted file mode 100644
index c5e1d7b717..0000000000
--- a/ext/Devel-DProf/t/DProf.t
+++ /dev/null
@@ -1,93 +0,0 @@
-#!perl
-
-BEGIN {
- if ($ENV{PERL_CORE}) {
- require 'test.pl'; # for which_perl() etc
- require Config; import Config;
- if ($Config{'extensions'} !~ /\bDevel\/DProf\b/){
- print "1..0 # Skip: Devel::DProf was not built\n";
- exit 0;
- }
- $perl = which_perl();
- }
- else {
- $perl = $^X;
- }
-}
-
-END {
- while(-e 'tmon.out' && unlink 'tmon.out') {}
- while(-e 'err' && unlink 'err') {}
-}
-
-use Benchmark qw( timediff timestr );
-use Getopt::Std 'getopts';
-getopts('vI:p:');
-
-# -v Verbose
-# -I Add to @INC
-# -p Name of perl binary
-
-@tests = @ARGV ? @ARGV : sort (<dprof/*_t>, <dprof/*_v>); # glob-sort, for OS/2
-
-$path_sep = $Config{path_sep} || ':';
-$perl5lib = $opt_I || join( $path_sep, @INC );
-$perl = $opt_p if $opt_p;
-
-if( $opt_v ){
- print "tests: @tests\n";
- print "perl: $perl\n";
- print "perl5lib: $perl5lib\n";
-}
-if( $perl =~ m|^\./| ){
- # turn ./perl into ../perl, because of chdir(t) above.
- $perl = ".$perl";
-}
-if( ! -f $perl ){ die "Where's Perl?" }
-
-sub profile {
- my $test = shift;
- my @results;
- local $ENV{PERL5LIB} = $perl5lib;
- my $opt_d = '-d:DProf';
-
- my $t_start = new Benchmark;
- open( R, "$perl -f \"$opt_d\" $test |" ) || warn "$0: Can't run. $!\n";
- @results = <R>;
- close R or warn "Could not close: $!";
- my $t_total = timediff( new Benchmark, $t_start );
-
- if( $opt_v ){
- print "\n";
- print @results
- }
-
- print '# ' . timestr( $t_total, 'nop' ), "\n";
-}
-
-
-sub verify {
- my $test = shift;
-
- my $command = $perl.' "-I./dprof" '.$test;
- $command .= ' -v' if $opt_v;
- $command .= ' -p '. $perl;
- system $command;
-}
-
-
-$| = 1;
-print "1..20\n";
-while( @tests ){
- $test = shift @tests;
- $test =~ s/\.$// if $^O eq 'VMS';
- if( $test =~ /_t$/i ){
- print "# $test" . '.' x (20 - length $test);
- profile $test;
- }
- else{
- verify $test;
- }
-}
-
-unlink("tmon.out");