← 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:09 2016

Filename/opt/flows/lib/lib/perl5/B/Hooks/EndOfScope/XS.pm
StatementsExecuted 153 statements in 928µs
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
1111.04ms1.40msB::Hooks::EndOfScope::XS::::BEGIN@9B::Hooks::EndOfScope::XS::BEGIN@9
2011163µs330µsB::Hooks::EndOfScope::XS::::on_scope_endB::Hooks::EndOfScope::XS::on_scope_end
202020154µs18.5msB::Hooks::EndOfScope::XS::::__ANON__[:17]B::Hooks::EndOfScope::XS::__ANON__[:17]
201132µs32µsB::Hooks::EndOfScope::XS::::__ANON__[:16]B::Hooks::EndOfScope::XS::__ANON__[:16]
11119µs97µsB::Hooks::EndOfScope::XS::::BEGIN@10B::Hooks::EndOfScope::XS::BEGIN@10
11115µs30µsB::Hooks::EndOfScope::XS::::BEGIN@4B::Hooks::EndOfScope::XS::BEGIN@4
1118µs13µsB::Hooks::EndOfScope::XS::::BEGIN@5B::Hooks::EndOfScope::XS::BEGIN@5
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
1package B::Hooks::EndOfScope::XS;
2# ABSTRACT: Execute code after a scope finished compilation - XS implementation
3
4230µs244µs
# spent 30µs (15+14) within B::Hooks::EndOfScope::XS::BEGIN@4 which was called: # once (15µs+14µs) by Module::Runtime::require_module at line 4
use strict;
# spent 30µs making 1 call to B::Hooks::EndOfScope::XS::BEGIN@4 # spent 14µs making 1 call to strict::import
5247µs218µs
# spent 13µs (8+5) within B::Hooks::EndOfScope::XS::BEGIN@5 which was called: # once (8µs+5µs) by Module::Runtime::require_module at line 5
use warnings;
# spent 13µs making 1 call to B::Hooks::EndOfScope::XS::BEGIN@5 # spent 5µs making 1 call to warnings::import
6
71800nsour $VERSION = '0.20';
8
93146µs21.41ms
# spent 1.40ms (1.04+360µs) within B::Hooks::EndOfScope::XS::BEGIN@9 which was called: # once (1.04ms+360µs) by Module::Runtime::require_module at line 9
use Variable::Magic 0.48 ();
# spent 1.40ms making 1 call to B::Hooks::EndOfScope::XS::BEGIN@9 # spent 11µs making 1 call to UNIVERSAL::VERSION
1017µs168µs
# spent 97µs (19+78) within B::Hooks::EndOfScope::XS::BEGIN@10 which was called: # once (19µs+78µs) by Module::Runtime::require_module at line 13
use Sub::Exporter::Progressive 0.001006 -setup => {
# spent 68µs making 1 call to Sub::Exporter::Progressive::import
11 exports => ['on_scope_end'],
12 groups => { default => ['on_scope_end'] },
132183µs2106µs};
# spent 97µs making 1 call to B::Hooks::EndOfScope::XS::BEGIN@10 # spent 10µs making 1 call to UNIVERSAL::VERSION
14
15my $wiz = Variable::Magic::wizard
162063µs
# spent 32µs within B::Hooks::EndOfScope::XS::__ANON__[/opt/flows/lib/lib/perl5/B/Hooks/EndOfScope/XS.pm:16] which was called 20 times, avg 2µs/call: # 20 times (32µs+0s) by Variable::Magic::cast at line 36, avg 2µs/call
data => sub { [$_[1]] },
1740163µs2018.4ms
# spent 18.5ms (154µs+18.4) within B::Hooks::EndOfScope::XS::__ANON__[/opt/flows/lib/lib/perl5/B/Hooks/EndOfScope/XS.pm:17] which was called 20 times, avg 926µs/call: # once (7µs+6.25ms) by Module::Runtime::require_module at line 63 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Transport.pm # once (8µs+5.86ms) by Module::Runtime::require_module at line 610 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Bulk.pm # once (7µs+528µs) by Module::Runtime::require_module at line 165 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Serializer/JSON.pm # once (7µs+490µs) by Module::Runtime::require_module at line 207 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Cxn.pm # once (7µs+456µs) by Module::Runtime::require_module at line 296 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/CxnPool.pm # once (7µs+451µs) by Module::Runtime::require_module at line 63 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Transport.pm # once (8µs+444µs) by Module::Runtime::require_module at line 130 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Logger.pm # once (7µs+423µs) by Module::Runtime::require_module at line 111 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Client/Direct.pm # once (7µs+369µs) by Module::Runtime::require_module at line 159 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Cxn/HTTP.pm # once (7µs+356µs) by Module::Runtime::require_module at line 1774 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/API/2_0.pm # once (7µs+352µs) by Module::Runtime::require_module at line 275 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Bulk.pm # once (8µs+323µs) by Module::Runtime::require_module at line 35 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Logger/LogAny.pm # once (7µs+316µs) by Module::Runtime::require_module at line 76 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Cxn/Factory.pm # once (7µs+290µs) by Module::Runtime::require_module at line 67 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/CxnPool/Static.pm # once (7µs+283µs) by Module::Runtime::require_module at line 192 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/CxnPool/Static.pm # once (6µs+279µs) by Module::Runtime::require_module at line 61 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Client.pm # once (6µs+276µs) by Module::Runtime::require_module at line 10 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Role/Is_Sync.pm # once (19µs+256µs) by Module::Runtime::require_module at line 15 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Serializer/JSON.pm # once (7µs+247µs) by Module::Runtime::require_module at line 76 of /opt/flows/lib/lib/perl5/Search/Elasticsearch/Cxn/HTTPTiny.pm # once (8µs+119µs) by main::BEGIN@147 at line 662 of /opt/flows/lib/lib/perl5/Search/Elasticsearch.pm
free => sub { $_->() for @{ $_[1] }; () },
# spent 18.4ms making 20 calls to namespace::clean::__ANON__[/opt/flows/lib/lib/perl5/namespace/clean.pm:178], avg 918µs/call
18 # When someone localise %^H, our magic doesn't want to be copied
19 # down. We want it to be around only for the scope we've initially
20 # attached ourselfs to. Merely having MGf_LOCAL and a noop svt_local
21 # callback achieves this. If anything wants to attach more magic of our
22 # kind to a localised %^H, things will continue to just work as we'll be
23 # attached with a new and empty callback list.
2415µs130µs local => \undef
# spent 30µs making 1 call to Variable::Magic::wizard
25;
26
27
# spent 330µs (163+166) within B::Hooks::EndOfScope::XS::on_scope_end which was called 20 times, avg 16µs/call: # 20 times (163µs+166µs) by namespace::clean::import at line 178 of /opt/flows/lib/lib/perl5/namespace/clean.pm, avg 16µs/call
sub on_scope_end (&) {
28208µs my $cb = shift;
29
302023µs $^H |= 0x020000;
31
3220119µs2021µs if (my $stack = Variable::Magic::getdata %^H, $wiz) {
# spent 21µs making 20 calls to Variable::Magic::getdata, avg 1µs/call
33 push @{ $stack }, $cb;
34 }
35 else {
3620129µs40177µs Variable::Magic::cast %^H, $wiz, $cb;
# spent 146µs making 20 calls to Variable::Magic::cast, avg 7µs/call # spent 32µs making 20 calls to B::Hooks::EndOfScope::XS::__ANON__[/opt/flows/lib/lib/perl5/B/Hooks/EndOfScope/XS.pm:16], avg 2µs/call
37 }
38}
39
4014µs1;
41
42__END__