← Index
NYTProf Performance Profile   « line view »
For flows_to_es.pl
  Run on Mon May 9 23:27:59 2016
Reported on Mon May 9 23:28:08 2016

Filename/usr/share/perl/5.18/Carp.pm
StatementsExecuted 45 statements in 2.95ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
11117µs17µsCarp::::BEGIN@3Carp::BEGIN@3
11110µs20µsCarp::::BEGIN@441Carp::BEGIN@441
1119µs26µsCarp::::BEGIN@433Carp::BEGIN@433
1119µs20µsCarp::::BEGIN@434Carp::BEGIN@434
1118µs13µsCarp::::BEGIN@5Carp::BEGIN@5
1118µs19µsCarp::::BEGIN@67Carp::BEGIN@67
1117µs20µsCarp::::BEGIN@4Carp::BEGIN@4
1117µs18µsCarp::::BEGIN@448Carp::BEGIN@448
1117µs18µsCarp::::BEGIN@18Carp::BEGIN@18
1117µs7µsCarp::::BEGIN@7Carp::BEGIN@7
1117µs7µsCarp::::BEGIN@105Carp::BEGIN@105
1117µs7µsCarp::::BEGIN@17Carp::BEGIN@17
1116µs17µsCarp::::BEGIN@8Carp::BEGIN@8
0000s0sCarp::::__ANON__[:13]Carp::__ANON__[:13]
0000s0sCarp::::__ANON__[:23]Carp::__ANON__[:23]
0000s0sCarp::::_cgcCarp::_cgc
0000s0sCarp::::caller_infoCarp::caller_info
0000s0sCarp::::carpCarp::carp
0000s0sCarp::::cluckCarp::cluck
0000s0sCarp::::confessCarp::confess
0000s0sCarp::::croakCarp::croak
0000s0sCarp::::export_failCarp::export_fail
0000s0sCarp::::format_argCarp::format_arg
0000s0sCarp::::get_statusCarp::get_status
0000s0sCarp::::get_subnameCarp::get_subname
0000s0sCarp::::long_error_locCarp::long_error_loc
0000s0sCarp::::longmessCarp::longmess
0000s0sCarp::::longmess_heavyCarp::longmess_heavy
0000s0sCarp::::ret_backtraceCarp::ret_backtrace
0000s0sCarp::::ret_summaryCarp::ret_summary
0000s0sCarp::::short_error_locCarp::short_error_loc
0000s0sCarp::::shortmessCarp::shortmess
0000s0sCarp::::shortmess_heavyCarp::shortmess_heavy
0000s0sCarp::::str_len_trimCarp::str_len_trim
0000s0sCarp::::trustsCarp::trusts
0000s0sCarp::::trusts_directlyCarp::trusts_directly
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1package Carp;
2
3357µs117µs
# spent 17µs within Carp::BEGIN@3 which was called: # once (17µs+0s) by IO::Handle::BEGIN@6 at line 3
{ use 5.006; }
# spent 17µs making 1 call to Carp::BEGIN@3
4230µs233µs
# spent 20µs (7+13) within Carp::BEGIN@4 which was called: # once (7µs+13µs) by IO::Handle::BEGIN@6 at line 4
use strict;
# spent 20µs making 1 call to Carp::BEGIN@4 # spent 13µs making 1 call to strict::import
5233µs218µs
# spent 13µs (8+5) within Carp::BEGIN@5 which was called: # once (8µs+5µs) by IO::Handle::BEGIN@6 at line 5
use warnings;
# spent 13µs making 1 call to Carp::BEGIN@5 # spent 5µs making 1 call to warnings::import
6
7
# spent 7µs within Carp::BEGIN@7 which was called: # once (7µs+0s) by IO::Handle::BEGIN@6 at line 15
BEGIN {
8292µs228µs
# spent 17µs (6+11) within Carp::BEGIN@8 which was called: # once (6µs+11µs) by IO::Handle::BEGIN@6 at line 8
no strict "refs";
# spent 17µs making 1 call to Carp::BEGIN@8 # spent 11µs making 1 call to strict::unimport
918µs if(exists($::{"utf8::"}) && exists(*{$::{"utf8::"}}{HASH}->{"is_utf8"}) &&
10 defined(*{*{$::{"utf8::"}}{HASH}->{"is_utf8"}}{CODE})) {
11 *is_utf8 = \&{"utf8::is_utf8"};
12 } else {
13 *is_utf8 = sub { 0 };
14 }
15133µs17µs}
# spent 7µs making 1 call to Carp::BEGIN@7
16
17
# spent 7µs within Carp::BEGIN@17 which was called: # once (7µs+0s) by IO::Handle::BEGIN@6 at line 25
BEGIN {
18288µs229µs
# spent 18µs (7+11) within Carp::BEGIN@18 which was called: # once (7µs+11µs) by IO::Handle::BEGIN@6 at line 18
no strict "refs";
# spent 18µs making 1 call to Carp::BEGIN@18 # spent 11µs making 1 call to strict::unimport
1918µs if(exists($::{"utf8::"}) && exists(*{$::{"utf8::"}}{HASH}->{"downgrade"}) &&
20 defined(*{*{$::{"utf8::"}}{HASH}->{"downgrade"}}{CODE})) {
21 *downgrade = \&{"utf8::downgrade"};
22 } else {
23 *downgrade = sub {};
24 }
251205µs17µs}
# spent 7µs making 1 call to Carp::BEGIN@17
26
271800nsour $VERSION = '1.29';
28
291200nsour $MaxEvalLen = 0;
301100nsour $Verbose = 0;
311100nsour $CarpLevel = 0;
321100nsour $MaxArgLen = 64; # How much of each argument to print. 0 = all.
331100nsour $MaxArgNums = 8; # How many arguments to print. 0 = all.
34
351500nsrequire Exporter;
3616µsour @ISA = ('Exporter');
371800nsour @EXPORT = qw(confess croak carp);
381600nsour @EXPORT_OK = qw(cluck verbose longmess shortmess);
391600nsour @EXPORT_FAIL = qw(verbose); # hook to enable verbose mode
40
41# The members of %Internal are packages that are internal to perl.
42# Carp will not report errors from within these packages if it
43# can. The members of %CarpInternal are internal to Perl's warning
44# system. Carp will not report errors from within these packages
45# either, and will not report calls *to* these packages for carp and
46# croak. They replace $CarpLevel, which is deprecated. The
47# $Max(EvalLen|(Arg(Len|Nums)) variables are used to specify how the eval
48# text and function arguments should be formatted when printed.
49
501200nsour %CarpInternal;
511100nsour %Internal;
52
53# disable these by default, so they can live w/o require Carp
541800ns$CarpInternal{Carp}++;
551300ns$CarpInternal{warnings}++;
561200ns$Internal{Exporter}++;
571100ns$Internal{'Exporter::Heavy'}++;
58
59# if the caller specifies verbose usage ("perl -MCarp=verbose script.pl")
60# then the following method will be called by the Exporter which knows
61# to do this thanks to @EXPORT_FAIL, above. $_[1] will contain the word
62# 'verbose'.
63
64sub export_fail { shift; $Verbose = shift if $_[0] eq 'verbose'; @_ }
65
66sub _cgc {
672375µs231µs
# spent 19µs (8+12) within Carp::BEGIN@67 which was called: # once (8µs+12µs) by IO::Handle::BEGIN@6 at line 67
no strict 'refs';
# spent 19µs making 1 call to Carp::BEGIN@67 # spent 12µs making 1 call to strict::unimport
68 return \&{"CORE::GLOBAL::caller"} if defined &{"CORE::GLOBAL::caller"};
69 return;
70}
71
72sub longmess {
73 # Icky backwards compatibility wrapper. :-(
74 #
75 # The story is that the original implementation hard-coded the
76 # number of call levels to go back, so calls to longmess were off
77 # by one. Other code began calling longmess and expecting this
78 # behaviour, so the replacement has to emulate that behaviour.
79 my $cgc = _cgc();
80 my $call_pack = $cgc ? $cgc->() : caller();
81 if ( $Internal{$call_pack} or $CarpInternal{$call_pack} ) {
82 return longmess_heavy(@_);
83 }
84 else {
85 local $CarpLevel = $CarpLevel + 1;
86 return longmess_heavy(@_);
87 }
88}
89
901100nsour @CARP_NOT;
91
92sub shortmess {
93 my $cgc = _cgc();
94
95 # Icky backwards compatibility wrapper. :-(
96 local @CARP_NOT = $cgc ? $cgc->() : caller();
97 shortmess_heavy(@_);
98}
99
100sub croak { die shortmess @_ }
101sub confess { die longmess @_ }
102sub carp { warn shortmess @_ }
103sub cluck { warn longmess @_ }
104
105
# spent 7µs within Carp::BEGIN@105 which was called: # once (7µs+0s) by IO::Handle::BEGIN@6 at line 112
BEGIN {
10618µs if("$]" >= 5.015002 || ("$]" >= 5.014002 && "$]" < 5.015) ||
107 ("$]" >= 5.012005 && "$]" < 5.013)) {
108 *CALLER_OVERRIDE_CHECK_OK = sub () { 1 };
109 } else {
110 *CALLER_OVERRIDE_CHECK_OK = sub () { 0 };
111 }
11211.76ms17µs}
# spent 7µs making 1 call to Carp::BEGIN@105
113
114sub caller_info {
115 my $i = shift(@_) + 1;
116 my %call_info;
117 my $cgc = _cgc();
118 {
119 # Some things override caller() but forget to implement the
120 # @DB::args part of it, which we need. We check for this by
121 # pre-populating @DB::args with a sentinel which no-one else
122 # has the address of, so that we can detect whether @DB::args
123 # has been properly populated. However, on earlier versions
124 # of perl this check tickles a bug in CORE::caller() which
125 # leaks memory. So we only check on fixed perls.
126 @DB::args = \$i if CALLER_OVERRIDE_CHECK_OK;
127 package DB;
128
- -
133 unless ( defined $call_info{file} ) {
134 return ();
135 }
136
137 my $sub_name = Carp::get_subname( \%call_info );
138 if ( $call_info{has_args} ) {
139 my @args;
140 if (CALLER_OVERRIDE_CHECK_OK && @DB::args == 1
141 && ref $DB::args[0] eq ref \$i
142 && $DB::args[0] == \$i ) {
143 @DB::args = (); # Don't let anyone see the address of $i
144 local $@;
145 my $where = eval {
146 my $func = $cgc or return '';
147 my $gv =
148 *{
149 ( $::{"B::"} || return '') # B stash
150 ->{svref_2object} || return '' # entry in stash
151 }{CODE} # coderef in entry
152 ->($func)->GV;
153 my $package = $gv->STASH->NAME;
154 my $subname = $gv->NAME;
155 return unless defined $package && defined $subname;
156
157 # returning CORE::GLOBAL::caller isn't useful for tracing the cause:
158 return if $package eq 'CORE::GLOBAL' && $subname eq 'caller';
159 " in &${package}::$subname";
160 } || '';
161 @args
162 = "** Incomplete caller override detected$where; \@DB::args were not set **";
163 }
164 else {
165 @args = @DB::args;
166 my $overflow;
167 if ( $MaxArgNums and @args > $MaxArgNums )
168 { # More than we want to show?
169 $#args = $MaxArgNums;
170 $overflow = 1;
171 }
172
173 @args = map { Carp::format_arg($_) } @args;
174
175 if ($overflow) {
176 push @args, '...';
177 }
178 }
179
180 # Push the args onto the subroutine
181 $sub_name .= '(' . join( ', ', @args ) . ')';
182 }
183 $call_info{sub_name} = $sub_name;
184 return wantarray() ? %call_info : \%call_info;
185}
186
187# Transform an argument to a function into a string.
188sub format_arg {
189 my $arg = shift;
190 if ( ref($arg) ) {
191 $arg = defined($overload::VERSION) ? overload::StrVal($arg) : "$arg";
192 }
193 if ( defined($arg) ) {
194 $arg =~ s/'/\\'/g;
195 $arg = str_len_trim( $arg, $MaxArgLen );
196
197 # Quote it?
198 # Downgrade, and use [0-9] rather than \d, to avoid loading
199 # Unicode tables, which would be liable to fail if we're
200 # processing a syntax error.
201 downgrade($arg, 1);
202 $arg = "'$arg'" unless $arg =~ /^-?[0-9.]+\z/;
203 }
204 else {
205 $arg = 'undef';
206 }
207
208 # The following handling of "control chars" is direct from
209 # the original code - it is broken on Unicode though.
210 # Suggestions?
211 is_utf8($arg)
212 or $arg =~ s/([[:cntrl:]]|[[:^ascii:]])/sprintf("\\x{%x}",ord($1))/eg;
213 return $arg;
214}
215
216# Takes an inheritance cache and a package and returns
217# an anon hash of known inheritances and anon array of
218# inheritances which consequences have not been figured
219# for.
220sub get_status {
221 my $cache = shift;
222 my $pkg = shift;
223 $cache->{$pkg} ||= [ { $pkg => $pkg }, [ trusts_directly($pkg) ] ];
224 return @{ $cache->{$pkg} };
225}
226
227# Takes the info from caller() and figures out the name of
228# the sub/require/eval
229sub get_subname {
230 my $info = shift;
231 if ( defined( $info->{evaltext} ) ) {
232 my $eval = $info->{evaltext};
233 if ( $info->{is_require} ) {
234 return "require $eval";
235 }
236 else {
237 $eval =~ s/([\\\'])/\\$1/g;
238 return "eval '" . str_len_trim( $eval, $MaxEvalLen ) . "'";
239 }
240 }
241
242 # this can happen on older perls when the sub (or the stash containing it)
243 # has been deleted
244 if ( !defined( $info->{sub} ) ) {
245 return '__ANON__::__ANON__';
246 }
247
248 return ( $info->{sub} eq '(eval)' ) ? 'eval {...}' : $info->{sub};
249}
250
251# Figures out what call (from the point of view of the caller)
252# the long error backtrace should start at.
253sub long_error_loc {
254 my $i;
255 my $lvl = $CarpLevel;
256 {
257 ++$i;
258 my $cgc = _cgc();
259 my @caller = $cgc ? $cgc->($i) : caller($i);
260 my $pkg = $caller[0];
261 unless ( defined($pkg) ) {
262
263 # This *shouldn't* happen.
264 if (%Internal) {
265 local %Internal;
266 $i = long_error_loc();
267 last;
268 }
269 elsif (defined $caller[2]) {
270 # this can happen when the stash has been deleted
271 # in that case, just assume that it's a reasonable place to
272 # stop (the file and line data will still be intact in any
273 # case) - the only issue is that we can't detect if the
274 # deleted package was internal (so don't do that then)
275 # -doy
276 redo unless 0 > --$lvl;
277 last;
278 }
279 else {
280 return 2;
281 }
282 }
283 redo if $CarpInternal{$pkg};
284 redo unless 0 > --$lvl;
285 redo if $Internal{$pkg};
286 }
287 return $i - 1;
288}
289
290sub longmess_heavy {
291 return @_ if ref( $_[0] ); # don't break references as exceptions
292 my $i = long_error_loc();
293 return ret_backtrace( $i, @_ );
294}
295
296# Returns a full stack backtrace starting from where it is
297# told.
298sub ret_backtrace {
299 my ( $i, @error ) = @_;
300 my $mess;
301 my $err = join '', @error;
302 $i++;
303
304 my $tid_msg = '';
305 if ( defined &threads::tid ) {
306 my $tid = threads->tid;
307 $tid_msg = " thread $tid" if $tid;
308 }
309
310 my %i = caller_info($i);
311 $mess = "$err at $i{file} line $i{line}$tid_msg";
312 if( defined $. ) {
313 local $@ = '';
314 local $SIG{__DIE__};
315 eval {
316 CORE::die;
317 };
318 if($@ =~ /^Died at .*(, <.*?> line \d+).$/ ) {
319 $mess .= $1;
320 }
321 }
322 $mess .= "\.\n";
323
324 while ( my %i = caller_info( ++$i ) ) {
325 $mess .= "\t$i{sub_name} called at $i{file} line $i{line}$tid_msg\n";
326 }
327
328 return $mess;
329}
330
331sub ret_summary {
332 my ( $i, @error ) = @_;
333 my $err = join '', @error;
334 $i++;
335
336 my $tid_msg = '';
337 if ( defined &threads::tid ) {
338 my $tid = threads->tid;
339 $tid_msg = " thread $tid" if $tid;
340 }
341
342 my %i = caller_info($i);
343 return "$err at $i{file} line $i{line}$tid_msg\.\n";
344}
345
346sub short_error_loc {
347 # You have to create your (hash)ref out here, rather than defaulting it
348 # inside trusts *on a lexical*, as you want it to persist across calls.
349 # (You can default it on $_[2], but that gets messy)
350 my $cache = {};
351 my $i = 1;
352 my $lvl = $CarpLevel;
353 {
354 my $cgc = _cgc();
355 my $called = $cgc ? $cgc->($i) : caller($i);
356 $i++;
357 my $caller = $cgc ? $cgc->($i) : caller($i);
358
359 if (!defined($caller)) {
360 my @caller = $cgc ? $cgc->($i) : caller($i);
361 if (@caller) {
362 # if there's no package but there is other caller info, then
363 # the package has been deleted - treat this as a valid package
364 # in this case
365 redo if defined($called) && $CarpInternal{$called};
366 redo unless 0 > --$lvl;
367 last;
368 }
369 else {
370 return 0;
371 }
372 }
373 redo if $Internal{$caller};
374 redo if $CarpInternal{$caller};
375 redo if $CarpInternal{$called};
376 redo if trusts( $called, $caller, $cache );
377 redo if trusts( $caller, $called, $cache );
378 redo unless 0 > --$lvl;
379 }
380 return $i - 1;
381}
382
383sub shortmess_heavy {
384 return longmess_heavy(@_) if $Verbose;
385 return @_ if ref( $_[0] ); # don't break references as exceptions
386 my $i = short_error_loc();
387 if ($i) {
388 ret_summary( $i, @_ );
389 }
390 else {
391 longmess_heavy(@_);
392 }
393}
394
395# If a string is too long, trims it with ...
396sub str_len_trim {
397 my $str = shift;
398 my $max = shift || 0;
399 if ( 2 < $max and $max < length($str) ) {
400 substr( $str, $max - 3 ) = '...';
401 }
402 return $str;
403}
404
405# Takes two packages and an optional cache. Says whether the
406# first inherits from the second.
407#
408# Recursive versions of this have to work to avoid certain
409# possible endless loops, and when following long chains of
410# inheritance are less efficient.
411sub trusts {
412 my $child = shift;
413 my $parent = shift;
414 my $cache = shift;
415 my ( $known, $partial ) = get_status( $cache, $child );
416
417 # Figure out consequences until we have an answer
418 while ( @$partial and not exists $known->{$parent} ) {
419 my $anc = shift @$partial;
420 next if exists $known->{$anc};
421 $known->{$anc}++;
422 my ( $anc_knows, $anc_partial ) = get_status( $cache, $anc );
423 my @found = keys %$anc_knows;
424 @$known{@found} = ();
425 push @$partial, @$anc_partial;
426 }
427 return exists $known->{$parent};
428}
429
430# Takes a package and gives a list of those trusted directly
431sub trusts_directly {
432 my $class = shift;
433231µs243µs
# spent 26µs (9+17) within Carp::BEGIN@433 which was called: # once (9µs+17µs) by IO::Handle::BEGIN@6 at line 433
no strict 'refs';
# spent 26µs making 1 call to Carp::BEGIN@433 # spent 17µs making 1 call to strict::unimport
434286µs231µs
# spent 20µs (9+11) within Carp::BEGIN@434 which was called: # once (9µs+11µs) by IO::Handle::BEGIN@6 at line 434
no warnings 'once';
# spent 20µs making 1 call to Carp::BEGIN@434 # spent 11µs making 1 call to warnings::unimport
435 return @{"$class\::CARP_NOT"}
436 ? @{"$class\::CARP_NOT"}
437 : @{"$class\::ISA"};
438}
439
4401700nsif(!defined($warnings::VERSION) ||
441349µs230µs
# spent 20µs (10+10) within Carp::BEGIN@441 which was called: # once (10µs+10µs) by IO::Handle::BEGIN@6 at line 441
do { no warnings "numeric"; $warnings::VERSION < 1.03 }) {
# spent 20µs making 1 call to Carp::BEGIN@441 # spent 10µs making 1 call to warnings::unimport
442 # Very old versions of warnings.pm import from Carp. This can go
443 # wrong due to the circular dependency. If Carp is invoked before
444 # warnings, then Carp starts by loading warnings, then warnings
445 # tries to import from Carp, and gets nothing because Carp is in
446 # the process of loading and hasn't defined its import method yet.
447 # So we work around that by manually exporting to warnings here.
448269µs229µs
# spent 18µs (7+11) within Carp::BEGIN@448 which was called: # once (7µs+11µs) by IO::Handle::BEGIN@6 at line 448
no strict "refs";
# spent 18µs making 1 call to Carp::BEGIN@448 # spent 11µs making 1 call to strict::unimport
449 *{"warnings::$_"} = \&$_ foreach @EXPORT;
450}
451
452110µs1;
453
454__END__
Report produced by the NYTProf 5.06 Perl profiler, developed by Tim Bunce and Adam Kaplan.