CINXE.COM
Devel::DProf - a Perl code profiler - Perldoc Browser
<!DOCTYPE html> <html lang="en"> <head> <meta charset="utf-8"> <meta http-equiv="X-UA-Compatible" content="IE=edge"> <meta name="viewport" content="width=device-width, initial-scale=1"> <title>Devel::DProf - a Perl code profiler - Perldoc Browser</title> <link rel="search" href="/opensearch.xml" type="application/opensearchdescription+xml" title="Perldoc Browser"> <link rel="canonical" href="https://perldoc.perl.org/Devel::DProf"> <link rel="stylesheet" href="https://stackpath.bootstrapcdn.com/bootstrap/4.5.2/css/bootstrap.min.css" integrity="sha384-JcKb8q3iqJ61gNV9KGb8thSsNjpSL0n8PARn9HuZOnIxN0hoP+VmmDGMN5t9UJ0Z" crossorigin="anonymous"> <link rel="stylesheet" href="https://cdnjs.cloudflare.com/ajax/libs/highlight.js/10.5.0/styles/stackoverflow-light.min.css" integrity="sha512-cG1IdFxqipi3gqLmksLtuk13C+hBa57a6zpWxMeoY3Q9O6ooFxq50DayCdm0QrDgZjMUn23z/0PMZlgft7Yp5Q==" crossorigin="anonymous" /> <style> body { background: #f4f4f5; color: #020202; } .navbar-dark { background-image: -webkit-linear-gradient(top, #005f85 0, #002e49 100%); background-image: -o-linear-gradient(top, #005f85 0, #002e49 100%); background-image: linear-gradient(to bottom, #005f85 0, #002e49 100%); filter: progid:DXImageTransform.Microsoft.gradient(startColorstr='#ff005f85', endColorstr='#ff002e49', GradientType=0); background-repeat: repeat-x; } .navbar-dark .navbar-nav .nav-link, .navbar-dark .navbar-nav .nav-link:focus { color: #fff } .navbar-dark .navbar-nav .nav-link:hover { color: #ffef68 } #wrapperlicious { margin: 0 auto; font: 0.9em 'Helvetica Neue', Helvetica, sans-serif; font-weight: normal; line-height: 1.5em; margin: 0; padding: 0; } #wrapperlicious h1 { font-size: 1.5em } #wrapperlicious h2 { font-size: 1.3em } #wrapperlicious h3 { font-size: 1.1em } #wrapperlicious h4 { font-size: 0.9em } #wrapperlicious h1, #wrapperlicious h2, #wrapperlicious h3, #wrapperlicious h4, #wrapperlicious dt { color: #020202; margin-top: 1em; margin-bottom: 1em; position: relative; font-weight: bold; } #wrapperlicious a { color: inherit; text-decoration: underline } #wrapperlicious #toc { text-decoration: none } #wrapperlicious a:hover { color: #2a2a2a } #wrapperlicious a img { border: 0 } #wrapperlicious :not(pre) > code { color: inherit; background-color: rgba(0, 0, 0, 0.04); border-radius: 3px; font: 0.9em Consolas, Menlo, Monaco, monospace; padding: 0.3em; } #wrapperlicious dd { margin: 0; margin-left: 2em; } #wrapperlicious dt { color: #2a2a2a; font-weight: bold; margin-left: 0.9em; } #wrapperlicious p { margin-bottom: 1em; margin-top: 1em; } #wrapperlicious li > p { margin-bottom: 0; margin-top: 0; } #wrapperlicious pre { border: 1px solid #c1c1c1; border-radius: 3px; font: 100% Consolas, Menlo, Monaco, monospace; margin-bottom: 1em; margin-top: 1em; } #wrapperlicious pre > code { display: block; background-color: #f6f6f6; font: 0.9em Consolas, Menlo, Monaco, monospace; line-height: 1.5em; text-align: left; white-space: pre; padding: 1em; } #wrapperlicious dl, #wrapperlicious ol, #wrapperlicious ul { margin-bottom: 1em; margin-top: 1em; } #wrapperlicious ul { list-style-type: square; } #wrapperlicious ul ul { margin-bottom: 0px; margin-top: 0px; } #footer { font-size: 0.8em; padding-top: 0.5em; text-align: center; } #more { display: inline; font-size: 0.8em; } #perldocdiv { background-color: #fff; border: 1px solid #c1c1c1; border-bottom-left-radius: 5px; border-bottom-right-radius: 5px; margin-left: auto; margin-right: auto; padding: 3em; padding-top: 1em; max-width: 960px; } #moduleversion { float: right } #wrapperlicious .leading-notice { font-style: italic; padding-left: 1em; margin-top: 1em; margin-bottom: 1em; } #wrapperlicious .permalink { display: none; left: -0.75em; position: absolute; padding-right: 0.25em; text-decoration: none; } #wrapperlicious h1:hover .permalink, #wrapperlicious h2:hover .permalink, #wrapperlicious h3:hover .permalink, #wrapperlicious h4:hover .permalink, #wrapperlicious dt:hover .permalink { display: block; } </style> <!-- Global site tag (gtag.js) - Google Analytics --> <script async src="https://www.googletagmanager.com/gtag/js?id=G-KVNWBNT5FB"></script> <script> window.dataLayer = window.dataLayer || []; function gtag(){dataLayer.push(arguments);} gtag('js', new Date()); gtag('config', 'G-KVNWBNT5FB'); gtag('config', 'UA-50555-3'); </script> </head> <body> <nav class="navbar navbar-expand-md navbar-dark bg-dark justify-content-between"> <button class="navbar-toggler" type="button" data-toggle="collapse" data-target="#navbarNav" aria-controls="navbarNav" aria-expanded="false" aria-label="Toggle navigation"> <span class="navbar-toggler-icon"></span> </button> <a class="navbar-brand" href="/"><img src="/images/perl_camel_30.png" width="30" height="30" class="d-inline-block align-top" alt="Perl Camel Logo"> Perldoc Browser</a> <div class="collapse navbar-collapse" id="navbarNav"> <ul class="navbar-nav mr-auto"> <li class="nav-item dropdown text-nowrap"> <a class="nav-link dropdown-toggle" href="#" id="dropdownlink-stable" role="button" data-toggle="dropdown" aria-haspopup="true" aria-expanded="false">5.10.1</a> <div class="dropdown-menu" aria-labelledby="dropdownlink-stable"> <a class="dropdown-item" href="/Devel::DProf">Latest</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.40.1/Devel::DProf">5.40.1</a> <a class="dropdown-item" href="/5.40.0/Devel::DProf">5.40.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.38.3/Devel::DProf">5.38.3</a> <a class="dropdown-item" href="/5.38.2/Devel::DProf">5.38.2</a> <a class="dropdown-item" href="/5.38.1/Devel::DProf">5.38.1</a> <a class="dropdown-item" href="/5.38.0/Devel::DProf">5.38.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.36.3/Devel::DProf">5.36.3</a> <a class="dropdown-item" href="/5.36.2/Devel::DProf">5.36.2</a> <a class="dropdown-item" href="/5.36.1/Devel::DProf">5.36.1</a> <a class="dropdown-item" href="/5.36.0/Devel::DProf">5.36.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.34.3/Devel::DProf">5.34.3</a> <a class="dropdown-item" href="/5.34.2/Devel::DProf">5.34.2</a> <a class="dropdown-item" href="/5.34.1/Devel::DProf">5.34.1</a> <a class="dropdown-item" href="/5.34.0/Devel::DProf">5.34.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.32.1/Devel::DProf">5.32.1</a> <a class="dropdown-item" href="/5.32.0/Devel::DProf">5.32.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.30.3/Devel::DProf">5.30.3</a> <a class="dropdown-item" href="/5.30.2/Devel::DProf">5.30.2</a> <a class="dropdown-item" href="/5.30.1/Devel::DProf">5.30.1</a> <a class="dropdown-item" href="/5.30.0/Devel::DProf">5.30.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.28.3/Devel::DProf">5.28.3</a> <a class="dropdown-item" href="/5.28.2/Devel::DProf">5.28.2</a> <a class="dropdown-item" href="/5.28.1/Devel::DProf">5.28.1</a> <a class="dropdown-item" href="/5.28.0/Devel::DProf">5.28.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.26.3/Devel::DProf">5.26.3</a> <a class="dropdown-item" href="/5.26.2/Devel::DProf">5.26.2</a> <a class="dropdown-item" href="/5.26.1/Devel::DProf">5.26.1</a> <a class="dropdown-item" href="/5.26.0/Devel::DProf">5.26.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.24.4/Devel::DProf">5.24.4</a> <a class="dropdown-item" href="/5.24.3/Devel::DProf">5.24.3</a> <a class="dropdown-item" href="/5.24.2/Devel::DProf">5.24.2</a> <a class="dropdown-item" href="/5.24.1/Devel::DProf">5.24.1</a> <a class="dropdown-item" href="/5.24.0/Devel::DProf">5.24.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.22.4/Devel::DProf">5.22.4</a> <a class="dropdown-item" href="/5.22.3/Devel::DProf">5.22.3</a> <a class="dropdown-item" href="/5.22.2/Devel::DProf">5.22.2</a> <a class="dropdown-item" href="/5.22.1/Devel::DProf">5.22.1</a> <a class="dropdown-item" href="/5.22.0/Devel::DProf">5.22.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.20.3/Devel::DProf">5.20.3</a> <a class="dropdown-item" href="/5.20.2/Devel::DProf">5.20.2</a> <a class="dropdown-item" href="/5.20.1/Devel::DProf">5.20.1</a> <a class="dropdown-item" href="/5.20.0/Devel::DProf">5.20.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.18.4/Devel::DProf">5.18.4</a> <a class="dropdown-item" href="/5.18.3/Devel::DProf">5.18.3</a> <a class="dropdown-item" href="/5.18.2/Devel::DProf">5.18.2</a> <a class="dropdown-item" href="/5.18.1/Devel::DProf">5.18.1</a> <a class="dropdown-item" href="/5.18.0/Devel::DProf">5.18.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.16.3/Devel::DProf">5.16.3</a> <a class="dropdown-item" href="/5.16.2/Devel::DProf">5.16.2</a> <a class="dropdown-item" href="/5.16.1/Devel::DProf">5.16.1</a> <a class="dropdown-item" href="/5.16.0/Devel::DProf">5.16.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.14.4/Devel::DProf">5.14.4</a> <a class="dropdown-item" href="/5.14.3/Devel::DProf">5.14.3</a> <a class="dropdown-item" href="/5.14.2/Devel::DProf">5.14.2</a> <a class="dropdown-item" href="/5.14.1/Devel::DProf">5.14.1</a> <a class="dropdown-item" href="/5.14.0/Devel::DProf">5.14.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.12.5/Devel::DProf">5.12.5</a> <a class="dropdown-item" href="/5.12.4/Devel::DProf">5.12.4</a> <a class="dropdown-item" href="/5.12.3/Devel::DProf">5.12.3</a> <a class="dropdown-item" href="/5.12.2/Devel::DProf">5.12.2</a> <a class="dropdown-item" href="/5.12.1/Devel::DProf">5.12.1</a> <a class="dropdown-item" href="/5.12.0/Devel::DProf">5.12.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item active" href="/5.10.1/Devel::DProf">5.10.1</a> <a class="dropdown-item" href="/5.10.0/Devel::DProf">5.10.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.8.9/Devel::DProf">5.8.9</a> <a class="dropdown-item" href="/5.8.8/Devel::DProf">5.8.8</a> <a class="dropdown-item" href="/5.8.7/Devel::DProf">5.8.7</a> <a class="dropdown-item" href="/5.8.6/Devel::DProf">5.8.6</a> <a class="dropdown-item" href="/5.8.5/Devel::DProf">5.8.5</a> <a class="dropdown-item" href="/5.8.4/Devel::DProf">5.8.4</a> <a class="dropdown-item" href="/5.8.3/Devel::DProf">5.8.3</a> <a class="dropdown-item" href="/5.8.2/Devel::DProf">5.8.2</a> <a class="dropdown-item" href="/5.8.1/Devel::DProf">5.8.1</a> <a class="dropdown-item" href="/5.8.0/Devel::DProf">5.8.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.6.2/Devel::DProf">5.6.2</a> <a class="dropdown-item" href="/5.6.1/Devel::DProf">5.6.1</a> <a class="dropdown-item" href="/5.6.0/Devel::DProf">5.6.0</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.005_04/Devel::DProf">5.005_04</a> <a class="dropdown-item" href="/5.005_03/Devel::DProf">5.005_03</a> <a class="dropdown-item" href="/5.005_02/Devel::DProf">5.005_02</a> <a class="dropdown-item" href="/5.005_01/Devel::DProf">5.005_01</a> <a class="dropdown-item" href="/5.005/Devel::DProf">5.005</a> </div> </li> <li class="nav-item dropdown text-nowrap"> <a class="nav-link dropdown-toggle" href="#" id="dropdownlink-dev" role="button" data-toggle="dropdown" aria-haspopup="true" aria-expanded="false">Dev</a> <div class="dropdown-menu" aria-labelledby="dropdownlink-dev"> <a class="dropdown-item" href="/blead/Devel::DProf">blead</a> <a class="dropdown-item" href="/5.41.10/Devel::DProf">5.41.10</a> <a class="dropdown-item" href="/5.41.9/Devel::DProf">5.41.9</a> <a class="dropdown-item" href="/5.41.8/Devel::DProf">5.41.8</a> <a class="dropdown-item" href="/5.41.7/Devel::DProf">5.41.7</a> <a class="dropdown-item" href="/5.41.6/Devel::DProf">5.41.6</a> <a class="dropdown-item" href="/5.41.5/Devel::DProf">5.41.5</a> <a class="dropdown-item" href="/5.41.4/Devel::DProf">5.41.4</a> <a class="dropdown-item" href="/5.41.3/Devel::DProf">5.41.3</a> <a class="dropdown-item" href="/5.41.2/Devel::DProf">5.41.2</a> <a class="dropdown-item" href="/5.41.1/Devel::DProf">5.41.1</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.40.1-RC1/Devel::DProf">5.40.1-RC1</a> <a class="dropdown-item" href="/5.40.0-RC2/Devel::DProf">5.40.0-RC2</a> <a class="dropdown-item" href="/5.40.0-RC1/Devel::DProf">5.40.0-RC1</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.39.10/Devel::DProf">5.39.10</a> <a class="dropdown-item" href="/5.39.9/Devel::DProf">5.39.9</a> <a class="dropdown-item" href="/5.39.8/Devel::DProf">5.39.8</a> <a class="dropdown-item" href="/5.39.7/Devel::DProf">5.39.7</a> <a class="dropdown-item" href="/5.39.6/Devel::DProf">5.39.6</a> <a class="dropdown-item" href="/5.39.5/Devel::DProf">5.39.5</a> <a class="dropdown-item" href="/5.39.4/Devel::DProf">5.39.4</a> <a class="dropdown-item" href="/5.39.3/Devel::DProf">5.39.3</a> <a class="dropdown-item" href="/5.39.2/Devel::DProf">5.39.2</a> <a class="dropdown-item" href="/5.39.1/Devel::DProf">5.39.1</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.38.3-RC1/Devel::DProf">5.38.3-RC1</a> </div> </li> <li class="nav-item dropdown text-nowrap"> <a class="nav-link dropdown-toggle" href="#" id="dropdownlink-nav" role="button" data-toggle="dropdown" aria-haspopup="true" aria-expanded="false">Documentation</a> <div class="dropdown-menu" aria-labelledby="dropdownlink-nav"> <a class="dropdown-item" href="/5.10.1/perl">Perl</a> <a class="dropdown-item" href="/5.10.1/perlintro">Intro</a> <a class="dropdown-item" href="/5.10.1/perl#Tutorials">Tutorials</a> <a class="dropdown-item" href="/5.10.1/perlfaq">FAQs</a> <a class="dropdown-item" href="/5.10.1/perl#Reference-Manual">Reference</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.10.1/perlop">Operators</a> <a class="dropdown-item" href="/5.10.1/functions">Functions</a> <a class="dropdown-item" href="/5.10.1/variables">Variables</a> <a class="dropdown-item" href="/5.10.1/modules">Modules</a> <a class="dropdown-item" href="/5.10.1/perlutil">Utilities</a> <div class="dropdown-divider"></div> <a class="dropdown-item" href="/5.10.1/perldelta">Release Notes</a> <a class="dropdown-item" href="/5.10.1/perlcommunity">Community</a> <a class="dropdown-item" href="/5.10.1/perlhist">History</a> </div> </li> </ul> <ul class="navbar-nav"> <script> function set_expand (expand) { var perldocdiv = document.getElementById('perldocdiv'); var width = window.getComputedStyle(perldocdiv).getPropertyValue('max-width'); var expanded = (width == '' || width == 'none') ? true : false; if (expand === null) { expand = !expanded; } if ((expand && !expanded) || (!expand && expanded)) { perldocdiv.style.setProperty('max-width', expand ? 'none' : '960px'); var button_classlist = document.getElementById('content-expand-button').classList; if (expand) { button_classlist.add('btn-light'); button_classlist.remove('btn-outline-light'); } else { button_classlist.add('btn-outline-light'); button_classlist.remove('btn-light'); } } return expand; } function toggle_expand () { var expand = set_expand(null); document.cookie = 'perldoc_expand=' + (expand ? 1 : 0) + '; path=/; expires=Tue, 19 Jan 2038 03:14:07 UTC'; } function read_expand () { return document.cookie.split(';').some(function (item) { return item.indexOf('perldoc_expand=1') >= 0 }); } if (document.readyState === 'loading') { document.addEventListener('DOMContentLoaded', function () { if (read_expand()) { set_expand(true); } }); } else if (read_expand()) { set_expand(true); } </script> <button id="content-expand-button" type="button" class="btn btn-outline-light d-none d-lg-inline-block mr-4" onclick="toggle_expand()">Expand</button> </ul> <form class="form-inline" method="get" action="/5.10.1/search"> <input class="form-control mr-3" type="search" name="q" placeholder="Search" aria-label="Search" value=""> </form> </div> </nav> <div id="wrapperlicious" class="container-fluid"> <div id="perldocdiv"> <div id="links"> <a href="/5.10.1/Devel::DProf">Devel::DProf</a> <div id="more"> (<a href="/5.10.1/Devel::DProf.txt">source</a>, <a href="https://metacpan.org/pod/Devel::DProf">CPAN</a>) </div> <div id="moduleversion">version 20080331.00</div> </div> <div class="leading-notice"> You are viewing the version of this documentation from Perl 5.10.1. <a href="/Devel::DProf">View the latest version</a> </div> <h1><a id="toc">CONTENTS</a></h1> <ul> <li> <a class="text-decoration-none" href="#NAME">NAME</a> </li> <li> <a class="text-decoration-none" href="#SYNOPSIS">SYNOPSIS</a> </li> <li> <a class="text-decoration-none" href="#DESCRIPTION">DESCRIPTION</a> </li> <li> <a class="text-decoration-none" href="#PROFILE-FORMAT">PROFILE FORMAT</a> </li> <li> <a class="text-decoration-none" href="#AUTOLOAD">AUTOLOAD</a> </li> <li> <a class="text-decoration-none" href="#ENVIRONMENT">ENVIRONMENT</a> </li> <li> <a class="text-decoration-none" href="#BUGS">BUGS</a> </li> <li> <a class="text-decoration-none" href="#SEE-ALSO">SEE ALSO</a> </li> </ul> <h1 id="NAME"><a class="permalink" href="#NAME">#</a>NAME</h1> <p>Devel::DProf - a Perl code profiler</p> <h1 id="SYNOPSIS"><a class="permalink" href="#SYNOPSIS">#</a>SYNOPSIS</h1> <pre><code class="plaintext">perl -d:DProf test.pl</code></pre> <h1 id="DESCRIPTION"><a class="permalink" href="#DESCRIPTION">#</a>DESCRIPTION</h1> <p>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.</p> <p>To profile a Perl script run the perl interpreter with the <b>-d</b> debugging switch. The profiler uses the debugging hooks. So to profile script <i>test.pl</i> the following command should be used:</p> <pre><code class="plaintext">perl -d:DProf test.pl</code></pre> <p>When the script terminates (or when the output buffer is filled) the profiler will dump the profile information to a file called <i>tmon.out</i>. A tool like <i>dprofpp</i> 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:</p> <pre><code class="plaintext">dprofpp</code></pre> <p>To print an execution graph of the subroutines in the script use the following command:</p> <pre><code class="plaintext">dprofpp -T</code></pre> <p>Consult <a href="/5.10.1/dprofpp">dprofpp</a> for other options.</p> <h1 id="PROFILE-FORMAT"><a class="permalink" href="#PROFILE-FORMAT">#</a><a id="PROFILE"></a>PROFILE FORMAT</h1> <p>The old profile is a text file which looks like this:</p> <pre><code>#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 [....]</code></pre> <p>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.</p> <p>The columns in <b>PART2</b> are:</p> <pre><code class="plaintext">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</code></pre> <p>With newer perls another format is used, which may look like this:</p> <pre><code>#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</code></pre> <p>(with high value of $ENV{PERL_DPROF_TICKS}).</p> <p>New <code>$over_*</code> 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.</p> <p>Lines starting with <code>@</code> mark the amount of time passed since the previous <code>@</code> line. The numbers following the <code>@</code> are integer tick counts representing user, system, and real time. Divide these numbers by the $hz value in the header to get seconds.</p> <p>Lines starting with <code>&</code> map subroutine identifiers (an integer) to subroutine packages and names. These should only occur once per subroutine.</p> <p>Lines starting with <code>+</code> or <code>-</code> mark normal entering and exit of subroutines. The number following is a reference to a subroutine identifier.</p> <p>Lines starting with <code>*</code> mark where subroutines are entered by <code>goto &subr</code>, but note that the return will still be marked as coming from the original sub. The sequence might look like this:</p> <pre><code class="plaintext">+ 5 * 6 - 5</code></pre> <p>Lines starting with <code>/</code> is like <code>-</code> but mark where subroutines are exited by dying. Example:</p> <pre><code class="plaintext">+ 5 + 6 / 6 / 5</code></pre> <p>Finally you might find <code>@</code> time stamp marks surrounded by <code>+ & Devel::DProf::write</code> and <code>- & Devel::DProf::write</code> lines. These 3 lines are outputted when printing of the mark above actually consumed measurable time.</p> <h1 id="AUTOLOAD"><a class="permalink" href="#AUTOLOAD">#</a>AUTOLOAD</h1> <p>When Devel::DProf finds a call to an <code>&AUTOLOAD</code> subroutine it looks at the <code>$AUTOLOAD</code> variable to find the real name of the sub being called. See <a href="/5.10.1/perlsub#Autoloading">"Autoloading" in perlsub</a>.</p> <h1 id="ENVIRONMENT"><a class="permalink" href="#ENVIRONMENT">#</a>ENVIRONMENT</h1> <p><code>PERL_DPROF_BUFFER</code> sets size of output buffer in words. Defaults to 2**14.</p> <p><code>PERL_DPROF_TICKS</code> sets number of ticks per second on some systems where a replacement for times() is used. Defaults to the value of <code>HZ</code> macro.</p> <p><code>PERL_DPROF_OUT_FILE_NAME</code> sets the name of the output file. If not set, defaults to tmon.out.</p> <h1 id="BUGS"><a class="permalink" href="#BUGS">#</a>BUGS</h1> <p>Builtin functions cannot be measured by Devel::DProf.</p> <p>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</p> <pre><code>$DB::sub = 'current_sub'; ... $addr = $DB::sub + 0;</code></pre> <p>will set this numeric slot to numeric value of the string <code>current_sub</code>, i.e., to <code>0</code>. 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</i> it as invalid, but will not write over it).</p> <p>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:</p> <pre><code class="plaintext">panic: Devel::DProf inconsistent subroutine return</code></pre> <p>For example, this code will break under Devel::DProf:</p> <pre><code>sub foo { last FOO; } FOO: { foo(); }</code></pre> <p>A pattern like this is used by Test::More's skip() function, for example. See <a href="/5.10.1/perldiag">perldiag</a> for more details.</p> <p>Mail bug reports and feature requests to the perl5-porters mailing list at <i><perl5-porters@perl.org></i>.</p> <h1 id="SEE-ALSO"><a class="permalink" href="#SEE-ALSO">#</a><a id="SEE"></a>SEE ALSO</h1> <p><a href="/5.10.1/perl">perl</a>, <a href="/5.10.1/dprofpp">dprofpp</a>, times(2)</p> </div> <div id="footer"> <p>Perldoc Browser is maintained by Dan Book (<a href="https://metacpan.org/author/DBOOK">DBOOK</a>). Please contact him via the <a href="https://github.com/Grinnz/perldoc-browser/issues">GitHub issue tracker</a> or <a href="mailto:dbook@cpan.org">email</a> regarding any issues with the site itself, search, or rendering of documentation.</p> <p>The Perl documentation is maintained by the Perl 5 Porters in the development of Perl. Please contact them via the <a href="https://github.com/Perl/perl5/issues">Perl issue tracker</a>, the <a href="https://lists.perl.org/list/perl5-porters.html">mailing list</a>, or <a href="https://kiwiirc.com/client/irc.perl.org/p5p">IRC</a> to report any issues with the contents or format of the documentation.</p> </div> </div> <script src="https://cdnjs.cloudflare.com/ajax/libs/jquery/3.5.1/jquery.slim.min.js" integrity="sha512-/DXTXr6nQodMUiq+IUJYCt2PPOUjrHJ9wFrqpJ3XkgPNOZVfMok7cRw6CSxyCQxXn6ozlESsSh1/sMCTF1rL/g==" crossorigin="anonymous"></script> <script src="https://cdnjs.cloudflare.com/ajax/libs/popper.js/1.16.1/umd/popper.min.js" integrity="sha512-ubuT8Z88WxezgSqf3RLuNi5lmjstiJcyezx34yIU2gAHonIi27Na7atqzUZCOoY4CExaoFumzOsFQ2Ch+I/HCw==" crossorigin="anonymous"></script> <script src="https://stackpath.bootstrapcdn.com/bootstrap/4.5.2/js/bootstrap.min.js" integrity="sha384-B4gt1jrGC7Jh4AgTPSdUtOBvfO8shuf57BaghqFfPlYxofvL8/KUEfYiJOMMV+rV" crossorigin="anonymous"></script> <script src="/js/highlight.pack.js"></script> <script>hljs.highlightAll();</script> </body> </html>