[p5sagit/p5-mst-13.2.git] / ext / Devel / DProf / DProf.xs

#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

#ifdef DBG_SUB
#  define DBG_SUB_NOTIFY(A) dprof_dbg_sub_notify(A)
void
dprof_dbg_sub_notify(SV *Sub) {
    CV   *cv = INT2PTR(CV*,SvIVX(Sub));
    GV   *gv = cv ? CvGV(cv) : NULL;
    if (cv && gv) {
	warn("XS DBsub(%s::%s)\n",
	     ((GvSTASH(gv) && HvNAME(GvSTASH(gv))) ?
	      HvNAME(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))
#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 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

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 */
        char *name;
        U32 id;
        opcode ptype;
};

typedef union prof_any PROFANY;

typedef struct {
    U32		dprof_ticks;
    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;
    U32		total;
    U32		lastid;
    U32		default_perldb;
    UV		depth;
#ifdef OS2
    ULONG	frequ;
    long long	start_cnt;
#endif
#ifdef PERL_IMPLICIT_CONTEXT
#  define register
    pTHX;
#  undef register
#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_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.aTHX
#endif
#ifdef OS2
#  define g_frequ		g_prof_state.frequ
#  define g_start_cnt		g_prof_state.start_cnt
#endif

clock_t
dprof_times(pTHX_ struct tms *t)
{
#ifdef OS2
    ULONG rc;
    QWORD cnt;
    STRLEN n_a;
    
    if (!g_frequ) {
	if (CheckOSError(DosTmrQueryFreq(&g_frequ)))
	    croak("DosTmrQueryFreq: %s", SvPV(perl_get_sv("!",TRUE),n_a));
	else
	    g_frequ = g_frequ/DPROF_HZ;	/* count per tick */
	if (CheckOSError(DosTmrQueryTime(&cnt)))
	    croak("DosTmrQueryTime: %s",
		  SvPV(perl_get_sv("!",TRUE), n_a));
	g_start_cnt = toLongLong(cnt);
    }

    if (CheckOSError(DosTmrQueryTime(&cnt)))
	    croak("DosTmrQueryTime: %s", SvPV(perl_get_sv("!",TRUE), n_a));
    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 */
    return times(t);
#  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, char *pname, 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 realtime1, realtime2;

    realtime1 = Times(&t1);

    while (base < ix) {
	opcode ptype = g_profstack[base++].ptype;
	if (ptype == OP_TIME) {
	    long tms_utime = g_profstack[base++].tms_utime;
	    long tms_stime = g_profstack[base++].tms_stime;
	    long realtime = g_profstack[base++].realtime;

	    prof_dumpt(aTHX_ tms_utime, tms_stime, realtime);
	}
	else if (ptype == OP_GV) {
	    U32 id = g_profstack[base++].id;
	    char *pname = g_profstack[base++].name;
	    char *gname = g_profstack[base++].name;

	    prof_dumps(aTHX_ id, pname, gname);
	}
	else {
	    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
prof_mark(pTHX_ opcode ptype)
{
    struct tms t;
    clock_t realtime, rdelta, udelta, sdelta;
    U32 id;
    SV *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 *cv;

	cv = INT2PTR(CV*,SvIVX(Sub));
	svp = hv_fetch(g_cv_hash, (char*)&cv, sizeof(CV*), TRUE);
	if (!SvOK(*svp)) {
	    GV *gv = CvGV(cv);
		
	    sv_setiv(*svp, id = ++g_lastid);
	    pname = ((GvSTASH(gv) && HvNAME(GvSTASH(gv))) 
		     ? HvNAME(GvSTASH(gv)) 
		     : "(null)");
	    gname = GvNAME(gv);
	    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. */
    }
}

#ifdef PL_NEEDED
#  define defstash PL_defstash
#endif

/* Counts overhead of prof_mark and extra XS call. */
static void
test_time(pTHX_ clock_t *r, clock_t *u, clock_t *s)
{
    CV *cv = perl_get_cv("Devel::DProf::NONESUCH_noxs", FALSE);
    int i, j, k = 0;
    HV *oldstash = PL_curstash;
    struct tms t1, t2;
    clock_t realtime1 = 0, realtime2 = 0;
    U32 ototal = g_total;
    U32 ostack = g_SAVE_STACK;
    U32 operldb = PL_perldb;

    g_SAVE_STACK = 1000000;
    realtime1 = Times(&t1);
    
    while (k < 2) {
	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) {
	    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)
{
    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)
{
    dMARK;
    dORIGMARK;
    SV *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(INT2PTR(SV*,SvIV(Sub)), GIMME_V | G_NODEBUG);
    }
    else
#endif
    {
	HV *oldstash = PL_curstash;

        DBG_SUB_NOTIFY(Sub);

	SAVEDESTRUCTOR_X(check_depth, INT2PTR(void*,g_depth));
	g_depth++;

        prof_mark(aTHX_ OP_ENTERSUB);
        PUSHMARK(ORIGMARK);
        perl_call_sv(INT2PTR(SV*,SvIV(Sub)), GIMME_V | G_NODEBUG);
        PL_curstash = oldstash;
        prof_mark(aTHX_ OP_LEAVESUB);
	g_depth--;
    }
    return;
}

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 *oldstash = PL_curstash;
		SV *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.
         */
        {
	    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 */

	{
	    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_prof_pid = (int)getpid();

	New(0, 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;
    }
Commit	Line	Data
146174a9	1	#define PERL_NO_GET_CONTEXT
583a019e	2	#include "EXTERN.h"
	3	#include "perl.h"
	4	#include "XSUB.h"
	5
94277a97	6	/* define DBG_SUB to cause a warning on each subroutine entry. */
7399586d	7	/#define DBG_SUB 1 /
94277a97	8
	9	/* define DBG_TIMER to cause a warning when the timer is turned on and off. */
	10	/#define DBG_TIMER 1 /
583a019e	11
ae7638f4	12	#ifdef DEBUGGING
	13	#define ASSERT(x) assert(x)
	14	#else
	15	#define ASSERT(x)
	16	#endif
	17
583a019e	18	#ifdef DBG_SUB
94277a97	19	# define DBG_SUB_NOTIFY(A) dprof_dbg_sub_notify(A)
	20	void
	21	dprof_dbg_sub_notify(SV *Sub) {
	22	CV cv = INT2PTR(CV,SvIVX(Sub));
	23	GV *gv = cv ? CvGV(cv) : NULL;
	24	if (cv && gv) {
	25	warn("XS DBsub(%s::%s)\n",
	26	((GvSTASH(gv) && HvNAME(GvSTASH(gv))) ?
	27	HvNAME(GvSTASH(gv)) : "(null)"),
	28	GvNAME(gv));
	29	} else {
	30	warn("XS DBsub(unknown) at %x", Sub);
	31	}
	32	}
583a019e	33	#else
94277a97	34	# define DBG_SUB_NOTIFY(A) /* nothing */
583a019e	35	#endif
583a019e	36
94277a97	37
583a019e	38	#ifdef DBG_TIMER
146174a9	39	# define DBG_TIMER_NOTIFY(A) warn(A)
583a019e	40	#else
	41	# define DBG_TIMER_NOTIFY(A) /* nothing */
	42	#endif
	43
583a019e	44	/* HZ == clock ticks per second */
583a019e	45	#ifdef VMS
552e38a9	46	# define HZ ((I32)CLK_TCK)
583a019e	47	# define DPROF_HZ HZ
583a019e	48	# include <starlet.h> /* prototype for sys$gettim() */
3e4b306a	49	# include <lib$routines.h>
146174a9	50	# define Times(ptr) (dprof_times(aTHX_ ptr))
583a019e	51	#else
	52	# ifndef HZ
	53	# ifdef CLK_TCK
552e38a9	54	# define HZ ((I32)CLK_TCK)
583a019e	55	# else
	56	# define HZ 60
	57	# endif
	58	# endif
	59	# ifdef OS2 /* times() has significant overhead */
146174a9	60	# define Times(ptr) (dprof_times(aTHX_ ptr))
583a019e	61	# define INCL_DOSPROFILE
	62	# define INCL_DOSERRORS
	63	# include <os2.h>
	64	# define toLongLong(arg) ((long long)&(arg))
146174a9	65	# define DPROF_HZ g_dprof_ticks
583a019e	66	# else
	67	# define Times(ptr) (times(ptr))
	68	# define DPROF_HZ HZ
	69	# endif
	70	#endif
	71
	72	XS(XS_Devel__DProf_END); /* used by prof_mark() */
	73
583a019e	74	/* Everything is built on times(2). See its manpage for a description
	75	* of the timings.
	76	*/
	77
583a019e	78	union prof_any {
	79	clock_t tms_utime; /* cpu time spent in user space */
	80	clock_t tms_stime; /* cpu time spent in system */
	81	clock_t realtime; /* elapsed real time, in ticks */
	82	char *name;
	83	U32 id;
	84	opcode ptype;
	85	};
	86
	87	typedef union prof_any PROFANY;
	88
146174a9	89	typedef struct {
	90	U32 dprof_ticks;
	91	char* out_file_name; /* output file (defaults to tmon.out) */
	92	PerlIO* fp; /* pointer to tmon.out file */
4a9d6100	93	Off_t TIMES_LOCATION; /* Where in the file to store the time totals */
146174a9	94	int SAVE_STACK; /* How much data to buffer until end of run */
	95	int prof_pid; /* pid of profiled process */
	96	struct tms prof_start;
	97	struct tms prof_end;
	98	clock_t rprof_start; /* elapsed real time ticks */
	99	clock_t rprof_end;
	100	clock_t wprof_u;
	101	clock_t wprof_s;
	102	clock_t wprof_r;
	103	clock_t otms_utime;
	104	clock_t otms_stime;
	105	clock_t orealtime;
	106	PROFANY* profstack;
	107	int profstack_max;
	108	int profstack_ix;
	109	HV* cv_hash;
	110	U32 total;
	111	U32 lastid;
	112	U32 default_perldb;
425d70b4	113	UV depth;
146174a9	114	#ifdef OS2
	115	ULONG frequ;
	116	long long start_cnt;
	117	#endif
	118	#ifdef PERL_IMPLICIT_CONTEXT
	119	# define register
	120	pTHX;
	121	# undef register
	122	#endif
	123	} prof_state_t;
	124
	125	prof_state_t g_prof_state;
	126
	127	#define g_dprof_ticks g_prof_state.dprof_ticks
	128	#define g_out_file_name g_prof_state.out_file_name
	129	#define g_fp g_prof_state.fp
	130	#define g_TIMES_LOCATION g_prof_state.TIMES_LOCATION
	131	#define g_SAVE_STACK g_prof_state.SAVE_STACK
	132	#define g_prof_pid g_prof_state.prof_pid
	133	#define g_prof_start g_prof_state.prof_start
	134	#define g_prof_end g_prof_state.prof_end
	135	#define g_rprof_start g_prof_state.rprof_start
	136	#define g_rprof_end g_prof_state.rprof_end
	137	#define g_wprof_u g_prof_state.wprof_u
	138	#define g_wprof_s g_prof_state.wprof_s
	139	#define g_wprof_r g_prof_state.wprof_r
	140	#define g_otms_utime g_prof_state.otms_utime
	141	#define g_otms_stime g_prof_state.otms_stime
	142	#define g_orealtime g_prof_state.orealtime
	143	#define g_profstack g_prof_state.profstack
	144	#define g_profstack_max g_prof_state.profstack_max
	145	#define g_profstack_ix g_prof_state.profstack_ix
	146	#define g_cv_hash g_prof_state.cv_hash
	147	#define g_total g_prof_state.total
	148	#define g_lastid g_prof_state.lastid
	149	#define g_default_perldb g_prof_state.default_perldb
	150	#define g_depth g_prof_state.depth
	151	#ifdef PERL_IMPLICIT_CONTEXT
	152	# define g_THX g_prof_state.aTHX
	153	#endif
	154	#ifdef OS2
	155	# define g_frequ g_prof_state.frequ
	156	# define g_start_cnt g_prof_state.start_cnt
	157	#endif
583a019e	158
146174a9	159	clock_t
146174a9	160	dprof_times(pTHX_ struct tms *t)
583a019e	161	{
146174a9	162	#ifdef OS2
	163	ULONG rc;
	164	QWORD cnt;
cb50131a	165	STRLEN n_a;
146174a9	166
	167	if (!g_frequ) {
	168	if (CheckOSError(DosTmrQueryFreq(&g_frequ)))
cb50131a	169	croak("DosTmrQueryFreq: %s", SvPV(perl_get_sv("!",TRUE),n_a));
146174a9	170	else
	171	g_frequ = g_frequ/DPROF_HZ; /* count per tick */
	172	if (CheckOSError(DosTmrQueryTime(&cnt)))
	173	croak("DosTmrQueryTime: %s",
cb50131a	174	SvPV(perl_get_sv("!",TRUE), n_a));
146174a9	175	g_start_cnt = toLongLong(cnt);
583a019e	176	}
146174a9	177
146174a9	178	if (CheckOSError(DosTmrQueryTime(&cnt)))
cb50131a	179	croak("DosTmrQueryTime: %s", SvPV(perl_get_sv("!",TRUE), n_a));
146174a9	180	t->tms_stime = 0;
	181	return (t->tms_utime = (toLongLong(cnt) - g_start_cnt)/g_frequ);
	182	#else /* !OS2 */
	183	# ifdef VMS
	184	clock_t retval;
	185	/* Get wall time and convert to 10 ms intervals to
	186	* produce the return value dprof expects */
	187	# if defined(__DECC) && defined (__ALPHA)
	188	# include <ints.h>
	189	uint64 vmstime;
	190	_ckvmssts(sys$gettim(&vmstime));
	191	vmstime /= 100000;
	192	retval = vmstime & 0x7fffffff;
	193	# else
	194	/* (Older hw or ccs don't have an atomic 64-bit type, so we
	195	* juggle 32-bit ints (and a float) to produce a time_t result
	196	* with minimal loss of information.) */
	197	long int vmstime[2],remainder,divisor = 100000;
	198	_ckvmssts(sys$gettim((unsigned long int *)vmstime));
	199	vmstime[1] &= 0x7fff; /* prevent overflow in EDIV */
	200	_ckvmssts(lib$ediv(&divisor,vmstime,(long int *)&retval,&remainder));
	201	# endif
	202	/* Fill in the struct tms using the CRTL routine . . .*/
	203	times((tbuffer_t *)t);
	204	return (clock_t) retval;
	205	# else /* !VMS && !OS2 */
	206	return times(t);
	207	# endif
	208	#endif
	209	}
583a019e	210
583a019e	211	static void
146174a9	212	prof_dumpa(pTHX_ opcode ptype, U32 id)
583a019e	213	{
146174a9	214	if (ptype == OP_LEAVESUB) {
	215	PerlIO_printf(g_fp,"- %"UVxf"\n", (UV)id);
	216	}
	217	else if(ptype == OP_ENTERSUB) {
	218	PerlIO_printf(g_fp,"+ %"UVxf"\n", (UV)id);
	219	}
	220	else if(ptype == OP_GOTO) {
	221	PerlIO_printf(g_fp,"* %"UVxf"\n", (UV)id);
	222	}
	223	else if(ptype == OP_DIE) {
	224	PerlIO_printf(g_fp,"/ %"UVxf"\n", (UV)id);
	225	}
	226	else {
	227	PerlIO_printf(g_fp,"Profiler unknown prof code %d\n", ptype);
583a019e	228	}
	229	}
	230
	231	static void
146174a9	232	prof_dumps(pTHX_ U32 id, char pname, char gname)
583a019e	233	{
146174a9	234	PerlIO_printf(g_fp,"& %"UVxf" %s %s\n", (UV)id, pname, gname);
583a019e	235	}
583a019e	236
583a019e	237	static void
146174a9	238	prof_dumpt(pTHX_ long tms_utime, long tms_stime, long realtime)
583a019e	239	{
146174a9	240	PerlIO_printf(g_fp,"@ %ld %ld %ld\n", tms_utime, tms_stime, realtime);
583a019e	241	}
	242
	243	static void
146174a9	244	prof_dump_until(pTHX_ long ix)
583a019e	245	{
	246	long base = 0;
	247	struct tms t1, t2;
	248	clock_t realtime1, realtime2;
	249
	250	realtime1 = Times(&t1);
	251
146174a9	252	while (base < ix) {
146174a9	253	opcode ptype = g_profstack[base++].ptype;
583a019e	254	if (ptype == OP_TIME) {
146174a9	255	long tms_utime = g_profstack[base++].tms_utime;
	256	long tms_stime = g_profstack[base++].tms_stime;
	257	long realtime = g_profstack[base++].realtime;
	258
	259	prof_dumpt(aTHX_ tms_utime, tms_stime, realtime);
	260	}
	261	else if (ptype == OP_GV) {
	262	U32 id = g_profstack[base++].id;
	263	char *pname = g_profstack[base++].name;
	264	char *gname = g_profstack[base++].name;
	265
	266	prof_dumps(aTHX_ id, pname, gname);
	267	}
	268	else {
	269	U32 id = g_profstack[base++].id;
	270	prof_dumpa(aTHX_ ptype, id);
583a019e	271	}
583a019e	272	}
146174a9	273	PerlIO_flush(g_fp);
583a019e	274	realtime2 = Times(&t2);
	275	if (realtime2 != realtime1 \|\| t1.tms_utime != t2.tms_utime
	276	\|\| t1.tms_stime != t2.tms_stime) {
146174a9	277	g_wprof_r += realtime2 - realtime1;
	278	g_wprof_u += t2.tms_utime - t1.tms_utime;
	279	g_wprof_s += t2.tms_stime - t1.tms_stime;
	280
	281	PerlIO_printf(g_fp,"+ & Devel::DProf::write\n");
	282	PerlIO_printf(g_fp,"@ %"IVdf" %"IVdf" %"IVdf"\n",
	283	/* The (IV) casts are one possibility:
	284	* the Painfully Correct Way would be to
	285	* have Clock_t_f. */
	286	(IV)(t2.tms_utime - t1.tms_utime),
	287	(IV)(t2.tms_stime - t1.tms_stime),
	288	(IV)(realtime2 - realtime1));
	289	PerlIO_printf(g_fp,"- & Devel::DProf::write\n");
	290	g_otms_utime = t2.tms_utime;
	291	g_otms_stime = t2.tms_stime;
	292	g_orealtime = realtime2;
	293	PerlIO_flush(g_fp);
583a019e	294	}
	295	}
	296
583a019e	297	static void
146174a9	298	prof_mark(pTHX_ opcode ptype)
583a019e	299	{
146174a9	300	struct tms t;
146174a9	301	clock_t realtime, rdelta, udelta, sdelta;
146174a9	302	U32 id;
	303	SV Sub = GvSV(PL_DBsub); / name of current sub */
	304
	305	if (g_SAVE_STACK) {
ae7638f4	306	if (g_profstack_ix + 10 > g_profstack_max) {
146174a9	307	g_profstack_max = g_profstack_max * 3 / 2;
	308	Renew(g_profstack, g_profstack_max, PROFANY);
	309	}
	310	}
583a019e	311
146174a9	312	realtime = Times(&t);
	313	rdelta = realtime - g_orealtime;
	314	udelta = t.tms_utime - g_otms_utime;
	315	sdelta = t.tms_stime - g_otms_stime;
	316	if (rdelta \|\| udelta \|\| sdelta) {
	317	if (g_SAVE_STACK) {
ae7638f4	318	ASSERT(g_profstack_ix + 4 <= g_profstack_max);
146174a9	319	g_profstack[g_profstack_ix++].ptype = OP_TIME;
	320	g_profstack[g_profstack_ix++].tms_utime = udelta;
	321	g_profstack[g_profstack_ix++].tms_stime = sdelta;
	322	g_profstack[g_profstack_ix++].realtime = rdelta;
	323	}
	324	else { /* Write it to disk now so's not to eat up core */
	325	if (g_prof_pid == (int)getpid()) {
	326	prof_dumpt(aTHX_ udelta, sdelta, rdelta);
	327	PerlIO_flush(g_fp);
583a019e	328	}
583a019e	329	}
146174a9	330	g_orealtime = realtime;
	331	g_otms_stime = t.tms_stime;
	332	g_otms_utime = t.tms_utime;
	333	}
583a019e	334
146174a9	335	{
	336	SV **svp;
	337	char gname, pname;
	338	CV *cv;
	339
	340	cv = INT2PTR(CV*,SvIVX(Sub));
	341	svp = hv_fetch(g_cv_hash, (char)&cv, sizeof(CV), TRUE);
	342	if (!SvOK(*svp)) {
	343	GV *gv = CvGV(cv);
	344
	345	sv_setiv(*svp, id = ++g_lastid);
	346	pname = ((GvSTASH(gv) && HvNAME(GvSTASH(gv)))
	347	? HvNAME(GvSTASH(gv))
	348	: "(null)");
	349	gname = GvNAME(gv);
	350	if (CvXSUB(cv) == XS_Devel__DProf_END)
	351	return;
	352	if (g_SAVE_STACK) { /* Store it for later recording -JH */
ae7638f4	353	ASSERT(g_profstack_ix + 4 <= g_profstack_max);
146174a9	354	g_profstack[g_profstack_ix++].ptype = OP_GV;
	355	g_profstack[g_profstack_ix++].id = id;
	356	g_profstack[g_profstack_ix++].name = pname;
	357	g_profstack[g_profstack_ix++].name = gname;
	358	}
	359	else { /* Write it to disk now so's not to eat up core */
	360	/* Only record the parent's info */
	361	if (g_prof_pid == (int)getpid()) {
	362	prof_dumps(aTHX_ id, pname, gname);
	363	PerlIO_flush(g_fp);
583a019e	364	}
146174a9	365	else
146174a9	366	PL_perldb = 0; /* Do not debug the kid. */
583a019e	367	}
583a019e	368	}
146174a9	369	else {
	370	id = SvIV(*svp);
	371	}
	372	}
583a019e	373
146174a9	374	g_total++;
146174a9	375	if (g_SAVE_STACK) { /* Store it for later recording -JH */
ae7638f4	376	ASSERT(g_profstack_ix + 2 <= g_profstack_max);
146174a9	377	g_profstack[g_profstack_ix++].ptype = ptype;
	378	g_profstack[g_profstack_ix++].id = id;
	379
	380	/* Only record the parent's info */
	381	if (g_SAVE_STACK < g_profstack_ix) {
	382	if (g_prof_pid == (int)getpid())
	383	prof_dump_until(aTHX_ g_profstack_ix);
	384	else
	385	PL_perldb = 0; /* Do not debug the kid. */
	386	g_profstack_ix = 0;
	387	}
	388	}
	389	else { /* Write it to disk now so's not to eat up core */
583a019e	390
146174a9	391	/* Only record the parent's info */
	392	if (g_prof_pid == (int)getpid()) {
	393	prof_dumpa(aTHX_ ptype, id);
	394	PerlIO_flush(g_fp);
	395	}
	396	else
	397	PL_perldb = 0; /* Do not debug the kid. */
	398	}
583a019e	399	}
583a019e	400
583a019e	401	#ifdef PL_NEEDED
	402	# define defstash PL_defstash
	403	#endif
	404
	405	/* Counts overhead of prof_mark and extra XS call. */
	406	static void
146174a9	407	test_time(pTHX_ clock_t r, clock_t u, clock_t *s)
583a019e	408	{
583a019e	409	CV *cv = perl_get_cv("Devel::DProf::NONESUCH_noxs", FALSE);
583a019e	410	int i, j, k = 0;
146174a9	411	HV *oldstash = PL_curstash;
583a019e	412	struct tms t1, t2;
9c5ffd7c	413	clock_t realtime1 = 0, realtime2 = 0;
146174a9	414	U32 ototal = g_total;
	415	U32 ostack = g_SAVE_STACK;
	416	U32 operldb = PL_perldb;
583a019e	417
146174a9	418	g_SAVE_STACK = 1000000;
583a019e	419	realtime1 = Times(&t1);
	420
	421	while (k < 2) {
	422	i = 0;
	423	/* Disable debugging of perl_call_sv on second pass: */
146174a9	424	PL_curstash = (k == 0 ? PL_defstash : PL_debstash);
146174a9	425	PL_perldb = g_default_perldb;
583a019e	426	while (++i <= 100) {
583a019e	427	j = 0;
146174a9	428	g_profstack_ix = 0; /* Do not let the stack grow */
583a019e	429	while (++j <= 100) {
146174a9	430	/* prof_mark(aTHX_ OP_ENTERSUB); */
583a019e	431
146174a9	432	PUSHMARK(PL_stack_sp);
	433	perl_call_sv((SV*)cv, G_SCALAR);
	434	PL_stack_sp--;
	435	/* prof_mark(aTHX_ OP_LEAVESUB); */
583a019e	436	}
583a019e	437	}
146174a9	438	PL_curstash = oldstash;
583a019e	439	if (k == 0) { /* Put time with debugging */
	440	realtime2 = Times(&t2);
	441	*r = realtime2 - realtime1;
	442	*u = t2.tms_utime - t1.tms_utime;
	443	*s = t2.tms_stime - t1.tms_stime;
146174a9	444	}
146174a9	445	else { /* Subtract time without debug */
583a019e	446	realtime1 = Times(&t1);
	447	*r -= realtime1 - realtime2;
	448	*u -= t1.tms_utime - t2.tms_utime;
	449	*s -= t1.tms_stime - t2.tms_stime;
	450	}
	451	k++;
	452	}
146174a9	453	g_total = ototal;
	454	g_SAVE_STACK = ostack;
	455	PL_perldb = operldb;
583a019e	456	}
	457
	458	static void
146174a9	459	prof_recordheader(pTHX)
583a019e	460	{
146174a9	461	clock_t r, u, s;
	462
	463	/* g_fp is opened in the BOOT section */
	464	PerlIO_printf(g_fp, "#fOrTyTwO\n");
	465	PerlIO_printf(g_fp, "$hz=%"IVdf";\n", (IV)DPROF_HZ);
	466	PerlIO_printf(g_fp, "$XS_VERSION='DProf %s';\n", XS_VERSION);
	467	PerlIO_printf(g_fp, "# All values are given in HZ\n");
	468	test_time(aTHX_ &r, &u, &s);
	469	PerlIO_printf(g_fp,
	470	"$over_utime=%"IVdf"; $over_stime=%"IVdf"; $over_rtime=%"IVdf";\n",
	471	/* The (IV) casts are one possibility:
	472	* the Painfully Correct Way would be to
	473	* have Clock_t_f. */
	474	(IV)u, (IV)s, (IV)r);
	475	PerlIO_printf(g_fp, "$over_tests=10000;\n");
	476
	477	g_TIMES_LOCATION = PerlIO_tell(g_fp);
	478
	479	/* Pad with whitespace. */
	480	/* This should be enough even for very large numbers. */
	481	PerlIO_printf(g_fp, "%*s\n", 240 , "");
	482
	483	PerlIO_printf(g_fp, "\n");
	484	PerlIO_printf(g_fp, "PART2\n");
	485
	486	PerlIO_flush(g_fp);
583a019e	487	}
	488
	489	static void
146174a9	490	prof_record(pTHX)
583a019e	491	{
146174a9	492	/* g_fp is opened in the BOOT section */
	493
	494	/* Now that we know the runtimes, fill them in at the recorded
	495	location -JH */
583a019e	496
146174a9	497	if (g_SAVE_STACK) {
	498	prof_dump_until(aTHX_ g_profstack_ix);
	499	}
	500	PerlIO_seek(g_fp, g_TIMES_LOCATION, SEEK_SET);
	501	/* Write into reserved 240 bytes: */
	502	PerlIO_printf(g_fp,
	503	"$rrun_utime=%"IVdf"; $rrun_stime=%"IVdf"; $rrun_rtime=%"IVdf";",
	504	/* The (IV) casts are one possibility:
	505	* the Painfully Correct Way would be to
	506	* have Clock_t_f. */
	507	(IV)(g_prof_end.tms_utime-g_prof_start.tms_utime-g_wprof_u),
	508	(IV)(g_prof_end.tms_stime-g_prof_start.tms_stime-g_wprof_s),
	509	(IV)(g_rprof_end-g_rprof_start-g_wprof_r));
	510	PerlIO_printf(g_fp, "\n$total_marks=%"IVdf, (IV)g_total);
583a019e	511
146174a9	512	PerlIO_close(g_fp);
583a019e	513	}
	514
	515	#define NONESUCH()
	516
583a019e	517	static void
d7b9cf63	518	check_depth(pTHX_ void *foo)
583a019e	519	{
4b19af01	520	U32 need_depth = PTR2UV(foo);
146174a9	521	if (need_depth != g_depth) {
146174a9	522	if (need_depth > g_depth) {
583a019e	523	warn("garbled call depth when profiling");
146174a9	524	}
146174a9	525	else {
425d70b4	526	IV marks = g_depth - need_depth;
583a019e	527
146174a9	528	/* warn("Check_depth: got %d, expected %d\n", g_depth, need_depth); */
583a019e	529	while (marks--) {
146174a9	530	prof_mark(aTHX_ OP_DIE);
583a019e	531	}
146174a9	532	g_depth = need_depth;
583a019e	533	}
	534	}
	535	}
	536
	537	#define for_real
	538	#ifdef for_real
	539
	540	XS(XS_DB_sub)
	541	{
c6c619a9	542	dMARK;
146174a9	543	dORIGMARK;
	544	SV Sub = GvSV(PL_DBsub); / name of current sub */
	545
	546	#ifdef PERL_IMPLICIT_CONTEXT
	547	/* profile only the interpreter that loaded us */
	548	if (g_THX != aTHX) {
	549	PUSHMARK(ORIGMARK);
d781cccd	550	perl_call_sv(INT2PTR(SV*,SvIV(Sub)), GIMME_V \| G_NODEBUG);
146174a9	551	}
	552	else
	553	#endif
	554	{
	555	HV *oldstash = PL_curstash;
583a019e	556
94277a97	557	DBG_SUB_NOTIFY(Sub);
583a019e	558
a6f8e609	559	SAVEDESTRUCTOR_X(check_depth, INT2PTR(void*,g_depth));
146174a9	560	g_depth++;
583a019e	561
146174a9	562	prof_mark(aTHX_ OP_ENTERSUB);
146174a9	563	PUSHMARK(ORIGMARK);
d781cccd	564	perl_call_sv(INT2PTR(SV*,SvIV(Sub)), GIMME_V \| G_NODEBUG);
8063af02	565	PL_curstash = oldstash;
146174a9	566	prof_mark(aTHX_ OP_LEAVESUB);
	567	g_depth--;
	568	}
	569	return;
583a019e	570	}
	571
	572	XS(XS_DB_goto)
	573	{
146174a9	574	#ifdef PERL_IMPLICIT_CONTEXT
	575	if (g_THX == aTHX)
	576	#endif
	577	{
	578	prof_mark(aTHX_ OP_GOTO);
583a019e	579	return;
146174a9	580	}
583a019e	581	}
	582
	583	#endif /* for_real */
	584
	585	#ifdef testing
	586
	587	MODULE = Devel::DProf PACKAGE = DB
	588
	589	void
	590	sub(...)
146174a9	591	PPCODE:
146174a9	592	{
583a019e	593	dORIGMARK;
146174a9	594	HV *oldstash = PL_curstash;
146174a9	595	SV Sub = GvSV(PL_DBsub); / name of current sub */
583a019e	596	/* SP -= items; added by xsubpp */
94277a97	597	DBG_SUB_NOTIFY(Sub);
583a019e	598
146174a9	599	sv_setiv(PL_DBsingle, 0); /* disable DB single-stepping */
583a019e	600
146174a9	601	prof_mark(aTHX_ OP_ENTERSUB);
146174a9	602	PUSHMARK(ORIGMARK);
583a019e	603
146174a9	604	PL_curstash = PL_debstash; /* To disable debugging of perl_call_sv */
d781cccd	605	perl_call_sv(Sub, GIMME_V);
146174a9	606	PL_curstash = oldstash;
583a019e	607
146174a9	608	prof_mark(aTHX_ OP_LEAVESUB);
583a019e	609	SPAGAIN;
583a019e	610	/* PUTBACK; added by xsubpp */
146174a9	611	}
583a019e	612
	613	#endif /* testing */
	614
	615	MODULE = Devel::DProf PACKAGE = Devel::DProf
	616
	617	void
	618	END()
146174a9	619	PPCODE:
	620	{
	621	if (PL_DBsub) {
	622	/* maybe the process forked--we want only
	623	* the parent's profile.
	624	*/
	625	if (
	626	#ifdef PERL_IMPLICIT_CONTEXT
	627	g_THX == aTHX &&
	628	#endif
	629	g_prof_pid == (int)getpid())
	630	{
	631	g_rprof_end = Times(&g_prof_end);
	632	DBG_TIMER_NOTIFY("Profiler timer is off.\n");
	633	prof_record(aTHX);
	634	}
	635	}
	636	}
583a019e	637
	638	void
	639	NONESUCH()
	640
	641	BOOT:
146174a9	642	{
	643	g_TIMES_LOCATION = 42;
	644	g_SAVE_STACK = 1<<14;
	645	g_profstack_max = 128;
	646	#ifdef PERL_IMPLICIT_CONTEXT
	647	g_THX = aTHX;
	648	#endif
	649
583a019e	650	/* Before we go anywhere make sure we were invoked
	651	* properly, else we'll dump core.
	652	*/
146174a9	653	if (!PL_DBsub)
146174a9	654	croak("DProf: run perl with -d to use DProf.\n");
583a019e	655
	656	/* When we hook up the XS DB::sub we'll be redefining
	657	* the DB::sub from the PM file. Turn off warnings
	658	* while we do this.
	659	*/
	660	{
7c436af3	661	bool warn_tmp = PL_dowarn;
146174a9	662	PL_dowarn = 0;
	663	newXS("DB::sub", XS_DB_sub, file);
	664	newXS("DB::goto", XS_DB_goto, file);
	665	PL_dowarn = warn_tmp;
583a019e	666	}
583a019e	667
146174a9	668	sv_setiv(PL_DBsingle, 0); /* disable DB single-stepping */
583a019e	669
	670	{
	671	char *buffer = getenv("PERL_DPROF_BUFFER");
	672
	673	if (buffer) {
146174a9	674	g_SAVE_STACK = atoi(buffer);
583a019e	675	}
	676
	677	buffer = getenv("PERL_DPROF_TICKS");
	678
	679	if (buffer) {
146174a9	680	g_dprof_ticks = atoi(buffer); /* Used under OS/2 only */
	681	}
	682	else {
	683	g_dprof_ticks = HZ;
583a019e	684	}
583a019e	685
146174a9	686	buffer = getenv("PERL_DPROF_OUT_FILE_NAME");
	687	g_out_file_name = savepv(buffer ? buffer : "tmon.out");
	688	}
583a019e	689
146174a9	690	if ((g_fp = PerlIO_open(g_out_file_name, "w")) == NULL)
	691	croak("DProf: unable to write '%s', errno = %d\n",
	692	g_out_file_name, errno);
583a019e	693
146174a9	694	g_default_perldb = PERLDBf_NONAME \| PERLDBf_SUB \| PERLDBf_GOTO;
	695	g_cv_hash = newHV();
	696	g_prof_pid = (int)getpid();
583a019e	697
146174a9	698	New(0, g_profstack, g_profstack_max, PROFANY);
146174a9	699	prof_recordheader(aTHX);
583a019e	700	DBG_TIMER_NOTIFY("Profiler timer is on.\n");
146174a9	701	g_orealtime = g_rprof_start = Times(&g_prof_start);
	702	g_otms_utime = g_prof_start.tms_utime;
	703	g_otms_stime = g_prof_start.tms_stime;
	704	PL_perldb = g_default_perldb;
	705	}