← Index
NYTProf Performance Profile   « block view • line view • sub view »
For conv.pl
  Run on Sun Nov 14 21:09:12 2010
Reported on Sun Nov 14 21:13:14 2010

Filename/usr/share/perl/5.10/Exporter.pm
StatementsExecuted 1048 statements in 8.44ms
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
5656316.64ms18.4msExporter::::importExporter::import
16212.49ms2.69msExporter::::as_heavyExporter::as_heavy
34811464µs464µsExporter::::CORE:matchExporter::CORE:match (opcode)
37321454µs454µsExporter::::CORE:substExporter::CORE:subst (opcode)
1243102µs2.71msExporter::::exportExporter::export
41134µs120µsExporter::::export_to_levelExporter::export_to_level
0000s0sExporter::::__ANON__[:65]Exporter::__ANON__[:65]
0000s0sExporter::::export_failExporter::export_fail
0000s0sExporter::::export_ok_tagsExporter::export_ok_tags
0000s0sExporter::::export_tagsExporter::export_tags
0000s0sExporter::::require_versionExporter::require_version
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1package Exporter;
2
3132µsrequire 5.006;
4
5# Be lean.
6#use strict;
7#no strict 'refs';
8
911µsour $Debug = 0;
1010sour $ExportLevel = 0;
1111µsour $Verbose ||= 0;
1212µsour $VERSION = '5.63';
1311µsour (%Cache);
14
15# Carp 1.05+ does this now for us, but we may be running with an old Carp
1612µs$Carp::Internal{Exporter}++;
17
18
# spent 2.69ms (2.49+202µs) within Exporter::as_heavy which was called 16 times, avg 168µs/call: # 12 times (2.42ms+185µs) by Exporter::export at line 28, avg 217µs/call # 4 times (69µs+17µs) by Exporter::export_to_level at line 82, avg 22µs/call
sub as_heavy {
1964516µs require Exporter::Heavy;
20 # Unfortunately, this does not work if the caller is aliased as *name = \&foo
21 # Thus the need to create a lot of identical subroutines
22 my $c = (caller(1))[3];
231674µs $c =~ s/.*:://;
# spent 74µs making 16 calls to Exporter::CORE:subst, avg 5µs/call
24 \&{"Exporter::Heavy::heavy_$c"};
25}
26
27
# spent 2.71ms (102µs+2.61) within Exporter::export which was called 12 times, avg 226µs/call: # 6 times (54µs+2.50ms) by Exporter::import at line 63, avg 426µs/call # 4 times (32µs+69µs) by Exporter::Heavy::heavy_export_to_level at line 203 of Exporter/Heavy.pm, avg 25µs/call # once (8µs+22µs) by FileHandle::import at line 73 of FileHandle.pm # once (8µs+18µs) by FileHandle::import at line 81 of FileHandle.pm
sub export {
2812128µs2413.6ms goto &{as_heavy()};
# spent 11.0ms making 12 calls to Exporter::Heavy::heavy_export, avg 920µs/call # spent 2.61ms making 12 calls to Exporter::as_heavy, avg 217µs/call
29}
30
31
# spent 18.4ms (6.64+11.8) within Exporter::import which was called 56 times, avg 329µs/call: # once (674µs+9.13ms) by POSIX::import at line 29 of POSIX.pm # once (915µs+205µs) by POSIX::BEGIN@13 at line 13 of POSIX.pm # once (401µs+615µs) by XML::LibXML::SAX::Parser::BEGIN@16 at line 16 of XML/LibXML/SAX/Parser.pm # once (136µs+481µs) by MARC::Charset::Code::BEGIN@8 at line 8 of MARC/Charset/Code.pm # once (38µs+415µs) by MARC::Charset::BEGIN@14 at line 14 of MARC/Charset.pm # once (39µs+405µs) by MARC::Charset::Table::BEGIN@39 at line 39 of MARC/Charset/Table.pm # once (312µs+52µs) by Storable::BEGIN@23 at line 39 of FileHandle.pm # once (319µs+39µs) by MARC::File::XML::BEGIN@13 at line 13 of MARC/File/XML.pm # once (302µs+34µs) by Storable::BEGIN@23 at line 26 of IO/File.pm # once (201µs+37µs) by MARC::Charset::BEGIN@11 at line 11 of MARC/Charset.pm # once (38µs+183µs) by Storable::BEGIN@44 at line 45 of Storable.pm # once (145µs+18µs) by MARC::Charset::Table::BEGIN@40 at line 40 of MARC/Charset/Table.pm # once (144µs+11µs) by MARC::File::Encode::BEGIN@22 at line 22 of MARC/File/Encode.pm # once (110µs+10µs) by XML::SAX::BEGIN@17 at line 17 of XML/SAX.pm # once (98µs+12µs) by MARC::Record::BEGIN@15 at line 15 of MARC/Record.pm # once (98µs+10µs) by Data::Dumper::BEGIN@20 at line 20 of Data/Dumper.pm # once (92µs+8µs) by XML::SAX::BEGIN@15 at line 15 of XML/SAX.pm # once (93µs+5µs) by MARC::Charset::BEGIN@10 at line 10 of MARC/Charset.pm # once (93µs+4µs) by XML::LibXML::BEGIN@18 at line 18 of XML/LibXML.pm # once (88µs+4µs) by MARC::Field::BEGIN@5 at line 5 of MARC/Field.pm # once (86µs+3µs) by MARC::File::SAX::BEGIN@12 at line 12 of MARC/File/SAX.pm # once (83µs+6µs) by MARC::File::SAX::BEGIN@14 at line 14 of MARC/File/SAX.pm # once (81µs+5µs) by IO::File::BEGIN@9 at line 9 of IO/File.pm # once (75µs+8µs) by IO::Handle::BEGIN@7 at line 7 of IO/Handle.pm # once (75µs+6µs) by MARC::File::XML::BEGIN@10 at line 10 of MARC/File/XML.pm # once (76µs+5µs) by IO::File::BEGIN@8 at line 8 of IO/File.pm # once (75µs+5µs) by XML::LibXML::Element::BEGIN@1481 at line 1481 of XML/LibXML.pm # once (73µs+4µs) by Unicode::Normalize::BEGIN@12 at line 12 of Unicode/Normalize.pm # once (71µs+5µs) by SelectSaver::BEGIN@7 at line 7 of SelectSaver.pm # once (70µs+5µs) by XML::LibXML::Error::BEGIN@13 at line 13 of XML/LibXML/Error.pm # once (70µs+4µs) by IO::Handle::BEGIN@6 at line 6 of IO/Handle.pm # once (71µs+3µs) by XML::SAX::Exception::BEGIN@10 at line 10 of XML/SAX/Exception.pm # once (70µs+4µs) by XML::LibXML::XPathContext::BEGIN@15 at line 15 of XML/LibXML/XPathContext.pm # once (69µs+4µs) by IO::Seekable::BEGIN@12 at line 12 of IO/Seekable.pm # once (67µs+5µs) by MARC::File::USMARC::BEGIN@13 at line 13 of MARC/File/USMARC.pm # once (69µs+3µs) by Encode::BEGIN@48 at line 48 of Encode.pm # once (68µs+4µs) by IO::File::BEGIN@11 at line 11 of IO/File.pm # once (68µs+4µs) by Carp::BEGIN@5 at line 5 of Carp/Heavy.pm # once (67µs+4µs) by MARC::Charset::Code::BEGIN@6 at line 6 of MARC/Charset/Code.pm # once (68µs+3µs) by Tie::Hash::BEGIN@5 at line 5 of Tie/Hash.pm # once (67µs+4µs) by IO::BEGIN@6 at line 6 of IO.pm # once (67µs+4µs) by SelectSaver::BEGIN@6 at line 6 of SelectSaver.pm # once (66µs+5µs) by IO::Seekable::BEGIN@6 at line 6 of IO/Seekable.pm # once (64µs+3µs) by MARC::File::XML::BEGIN@12 at line 12 of MARC/File/XML.pm # once (62µs+3µs) by XML::LibXML::Error::BEGIN@225 at line 225 of XML/LibXML/Error.pm # once (59µs+3µs) by MARC::File::USMARC::BEGIN@18 at line 18 of MARC/File/USMARC.pm # once (54µs+2µs) by MARC::Charset::Code::BEGIN@7 at line 7 of MARC/Charset/Code.pm # once (55µs+0s) by MARC::File::SAX::BEGIN@10 at line 10 of MARC/File/SAX.pm # once (52µs+1µs) by MARC::File::XML::BEGIN@14 at line 14 of MARC/File/XML.pm # once (51µs+2µs) by XML::SAX::ParserFactory::BEGIN@10 at line 10 of XML/SAX/ParserFactory.pm # once (51µs+2µs) by MARC::Batch::BEGIN@28 at line 28 of MARC/Batch.pm # once (46µs+0s) by MARC::File::XML::BEGIN@7 at line 7 of MARC/File/XML.pm # once (42µs+0s) by MARC::Record::BEGIN@26 at line 26 of MARC/Record.pm # once (40µs+0s) by XML::LibXML::BEGIN@26 at line 26 of XML/LibXML.pm # once (39µs+0s) by MARC::File::SAX::BEGIN@13 at line 13 of MARC/File/SAX.pm # once (35µs+0s) by main::BEGIN@4 at line 4 of conv.pl
sub import {
328223.74ms my $pkg = shift;
33 my $callpkg = caller($ExportLevel);
34
35 if ($pkg eq "Exporter" and @_ and $_[0] eq "import") {
36 *{$callpkg."::import"} = \&import;
37 return;
38 }
39
40 # We *need* to treat @{"$pkg\::EXPORT_FAIL"} since Carp uses it :-(
41 my($exports, $fail) = (\@{"$pkg\::EXPORT"}, \@{"$pkg\::EXPORT_FAIL"});
42 return export $pkg, $callpkg, @_
43 if $Verbose or $Debug or @$fail > 1;
44 my $export_cache = ($Cache{$pkg} ||= {});
45 my $args = @_ or @_ = @$exports;
46
47 local $_;
48261.88ms if ($args and not %$export_cache) {
49 s/^&//, $export_cache->{$_} = 1
50357380µs foreach (@$exports, @{"$pkg\::EXPORT_OK"});
# spent 380µs making 357 calls to Exporter::CORE:subst, avg 1µs/call
51 }
52 my $heavy;
53 # Try very hard not to use {} and hence have to enter scope on the foreach
54 # We bomb out of the loop with last as soon as heavy is set.
551122.08ms if ($args or $fail) {
56 ($heavy = (/\W/ or $args and not exists $export_cache->{$_}
57 or @$fail and $_ eq $fail->[0])) and last
58348464µs foreach (@_);
# spent 464µs making 348 calls to Exporter::CORE:match, avg 1µs/call
59 } else {
60 ($heavy = /\W/) and last
61 foreach (@_);
62 }
6362.55ms return export $pkg, $callpkg, ($args ? @_ : ()) if $heavy;
# spent 2.55ms making 6 calls to Exporter::export, avg 426µs/call
64 local $SIG{__WARN__} =
65 sub {require Carp; &Carp::carp};
66 # shortcut for the common case of no type character
67 *{"$callpkg\::$_"} = \&{"$pkg\::$_"} foreach @_;
68}
69
70# Default methods
71
72sub export_fail {
73 my $self = shift;
74 @_;
75}
76
77# Unfortunately, caller(1)[3] "does not work" if the caller is aliased as
78# *name = \&foo. Thus the need to create a lot of identical subroutines
79# Otherwise we could have aliased them to export().
80
81
# spent 120µs (34+86) within Exporter::export_to_level which was called 4 times, avg 30µs/call: # 4 times (34µs+86µs) by XML::LibXML::import at line 206 of XML/LibXML.pm, avg 30µs/call
sub export_to_level {
82445µs82.02ms goto &{as_heavy()};
# spent 1.94ms making 4 calls to Exporter::Heavy::heavy_export_to_level, avg 484µs/call # spent 86µs making 4 calls to Exporter::as_heavy, avg 22µs/call
83}
84
85sub export_tags {
86 goto &{as_heavy()};
87}
88
89sub export_ok_tags {
90 goto &{as_heavy()};
91}
92
93sub require_version {
94 goto &{as_heavy()};
95}
96
9717µs1;
98__END__
 
# spent 464µs within Exporter::CORE:match which was called 348 times, avg 1µs/call: # 348 times (464µs+0s) by Exporter::import at line 58, avg 1µs/call
sub Exporter::CORE:match; # opcode
# spent 454µs within Exporter::CORE:subst which was called 373 times, avg 1µs/call: # 357 times (380µs+0s) by Exporter::import at line 50, avg 1µs/call # 16 times (74µs+0s) by Exporter::as_heavy at line 23, avg 5µs/call
sub Exporter::CORE:subst; # opcode
Report produced by the NYTProf 4.04 Perl profiler, developed by Tim Bunce and Adam Kaplan.