Profile of Storable.pm

Filename	/usr/lib/perl/5.10/Storable.pm
Statements	Executed 541 statements in 997ms

Subroutines
Calls	P	F	Exclusive Time	Inclusive Time	Subroutine
57	1	1	982ms	982ms	Storable::::mretrieveStorable::mretrieve (xsub)
57	2	2	13.6ms	996ms	Storable::::thawStorable::thaw
1	1	1	1.61ms	19.0ms	Storable::::BEGIN@23Storable::BEGIN@23
1	1	1	1.43ms	1.80ms	Storable::::BEGIN@22Storable::BEGIN@22
57	1	1	188µs	188µs	Storable::::CORE:substStorable::CORE:subst (opcode)
1	1	1	124µs	124µs	Storable::::bootstrapStorable::bootstrap (xsub)
1	1	1	20µs	123µs	Storable::::BEGIN@24Storable::BEGIN@24
1	1	1	19µs	353µs	Storable::::BEGIN@44Storable::BEGIN@44
0	0	0	0s	0s	Storable::::CLONEStorable::CLONE
0	0	0	0s	0s	Storable::::retrieve_fdStorable::retrieve_fd

Call graph for these subroutines as a Graphviz dot language file.

Line	State ments	Time on line	Calls	Time in subs	Code
1
- -
8	1	195µs
9	1	1µs
10	1	18µs
- -
12	1	2µs
13	1	4µs
- -
22	3	177µs	2	2.00ms	# spent 1.80ms (1.43+372µs) within Storable::BEGIN@22 which was called: # once (1.43ms+372µs) by Cache::Memcached::BEGIN@14 at line 22 # spent 1.80ms making 1 call to Storable::BEGIN@22 # spent 194µs making 1 call to AutoLoader::import
23	3	146µs	2	22.2ms	# spent 19.0ms (1.61+17.4) within Storable::BEGIN@23 which was called: # once (1.61ms+17.4ms) by Cache::Memcached::BEGIN@14 at line 23 # spent 19.0ms making 1 call to Storable::BEGIN@23 # spent 3.19ms making 1 call to FileHandle::import
24	3	182µs	2	226µs	# spent 123µs (20+103) within Storable::BEGIN@24 which was called: # once (20µs+103µs) by Cache::Memcached::BEGIN@14 at line 24 # spent 123µs making 1 call to Storable::BEGIN@24 # spent 103µs making 1 call to vars::import
- -
26	1	700ns
27	1	2µs
- -
34	2	9µs
35	1	64µs			# spent 93µs executing statements in string eval # includes 56µs spent executing 1 call to 1 sub defined therein.
- -
38	1	1µs
- -
44					# spent 353µs (19+334) within Storable::BEGIN@44 which was called: # once (19µs+334µs) by Cache::Memcached::BEGIN@14 at line 53
45	3	14µs	1	334µs	# spent 334µs making 1 call to Exporter::import
- -
53	1	144µs	1	353µs	# spent 353µs making 1 call to Storable::BEGIN@44
- -
55
- -
61
- -
65	1	1µs
66	1	1µs
67	1	14µs	1	575µs	# spent 575µs making 1 call to DynaLoader::bootstrap
68	1	70µs
- -
403					#line 404 "../../lib/Storable.pm (autosplit into ../../lib/auto/Storable/thaw.al)"
404					#
405					# thaw
406					#
407					# Recreate objects in memory from an existing frozen image created
408					# by freeze. If the frozen image passed is undef, return undef.
409					#
410					# spent 996ms (13.6+982) within Storable::thaw which was called 57 times, avg 17.5ms/call: # 56 times (13.6ms+982ms) by Cache::Memcached::__ANON__[/usr/share/perl5/Cache/Memcached.pm:714] at line 706 of Cache/Memcached.pm, avg 17.8ms/call # once (30µs+146µs) by Cache::Memcached::__ANON__[/usr/share/perl5/Cache/Memcached.pm:714] at line 51 of AutoLoader.pm sub thaw {
411	57	11.0ms			my ($frozen) = @_;
412	57	99µs			return undef unless defined $frozen;
413	57	38µs			my $self;
414	57	76µs			my $da = $@; # Could be from exception handler
415	114	984ms	57	982ms	eval { $self = mretrieve($frozen) }; # Call C routine # spent 982ms making 57 calls to Storable::mretrieve, avg 17.2ms/call
416	57	538µs	57	188µs	logcroak $@ if $@ =~ s/\.?\n$/,/; # spent 188µs making 57 calls to Storable::CORE:subst, avg 3µs/call
417	57	52µs			$@ = $da;
418	57	539µs			return $self;
419					}
420
421	1	9µs			1;
422					__END__

					# spent 188µs within Storable::CORE:subst which was called 57 times, avg 3µs/call: # 57 times (188µs+0s) by Storable::thaw at line 416, avg 3µs/call sub Storable::CORE:subst; # opcode
					# spent 124µs within Storable::bootstrap which was called: # once (124µs+0s) by DynaLoader::bootstrap at line 215 of DynaLoader.pm sub Storable::bootstrap; # xsub
					# spent 982ms within Storable::mretrieve which was called 57 times, avg 17.2ms/call: # 57 times (982ms+0s) by Storable::thaw at line 415, avg 17.2ms/call sub Storable::mretrieve; # xsub