← Index
NYTProf Performance Profile   « block view • line view • sub view »
For ddd2.pl
  Run on Tue May 25 16:52:24 2010
Reported on Tue May 25 16:56:45 2010

File /project/perl/lib/LWP/Protocol/http.pm
Statements Executed 57294
Statement Execution Time 92.9s
Subroutines — ordered by exclusive time
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
4681292.0s92.0sLWP::Protocol::http::SocketMethods::::CORE:sselectLWP::Protocol::http::SocketMethods::CORE:sselect (opcode)
46111576ms146sLWP::Protocol::http::::request LWP::Protocol::http::request
4611165.3ms49.6sLWP::Protocol::http::::_new_socket LWP::Protocol::http::_new_socket
9221142.0ms50.9msLWP::Protocol::http::::__ANON__[:166] LWP::Protocol::http::__ANON__[:166]
4611134.4ms138msLWP::Protocol::http::::_fixup_header LWP::Protocol::http::_fixup_header
4681132.4ms92.0sLWP::Protocol::http::SocketMethods::::can_readLWP::Protocol::http::SocketMethods::can_read
4682132.0ms91.0msLWP::Protocol::http::::__ANON__[:352] LWP::Protocol::http::__ANON__[:352]
4682131.2ms92.1sLWP::Protocol::http::SocketMethods::::sysreadLWP::Protocol::http::SocketMethods::sysread
4611129.8ms292msLWP::Protocol::http::::_get_sock_info LWP::Protocol::http::_get_sock_info
4681218.1ms18.1msLWP::Protocol::http::SocketMethods::::CORE:sysreadLWP::Protocol::http::SocketMethods::CORE:sysread (opcode)
23053211.8ms11.8msLWP::Protocol::http::::CORE:subst LWP::Protocol::http::CORE:subst (opcode)
461119.49ms9.49msLWP::Protocol::http::SocketMethods::::increment_response_countLWP::Protocol::http::SocketMethods::increment_response_count
1383329.34ms9.34msLWP::Protocol::http::::CORE:match LWP::Protocol::http::CORE:match (opcode)
461118.73ms8.73msLWP::Protocol::http::::socket_class LWP::Protocol::http::socket_class
461115.20ms5.20msLWP::Protocol::http::::_check_sock LWP::Protocol::http::_check_sock
461115.09ms5.09msLWP::Protocol::http::::_extra_sock_opts LWP::Protocol::http::_extra_sock_opts
0000s0sLWP::Protocol::http::::BEGIN LWP::Protocol::http::BEGIN
0000s0sLWP::Protocol::http::Socket::::BEGIN LWP::Protocol::http::Socket::BEGIN
0000s0sLWP::Protocol::http::SocketMethods::::pingLWP::Protocol::http::SocketMethods::ping
0000s0sLWP::Protocol::http::::hlist_remove LWP::Protocol::http::hlist_remove
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
1# $Id: http.pm,v 1.70 2005/12/08 10:28:01 gisle Exp $
2#
3
4package LWP::Protocol::http;
5
63152µs125µsuse strict;
# spent 25µs making 1 call to strict::import
7
816µsrequire LWP::Debug;
914µsrequire HTTP::Response;
1015µsrequire HTTP::Status;
111245µsrequire Net::HTTP;
12
1334.25ms1219µsuse vars qw(@ISA @EXTRA_SOCK_OPTS);
# spent 219µs making 1 call to vars::import
14
1517µsrequire LWP::Protocol;
1616µs@ISA = qw(LWP::Protocol);
17
1814µsmy $CRLF = "\015\012";
19
20sub _new_socket
21
# spent 49.6s (65.3ms+49.5) within LWP::Protocol::http::_new_socket which was called 461 times, avg 108ms/call: # 461 times (65.3ms+49.5s) by LWP::Protocol::http::request at line 154, avg 108ms/call
{
22414968.9ms my($self, $host, $port, $timeout) = @_;
23 my $conn_cache = $self->{ua}{conn_cache};
24 if ($conn_cache) {
25 if (my $sock = $conn_cache->withdraw("http", "$host:$port")) {
26 return $sock if $sock && !$sock->can_read(0);
27 # if the socket is readable, then either the peer has closed the
28 # connection or there are some garbage bytes on it. In either
29 # case we abandon it.
30 $sock->close;
31 }
32 }
33
34 local($^W) = 0; # IO::Socket::INET can be noisy
35 my $sock = $self->socket_class->new(PeerAddr => $host,
# spent 49.5s making 461 calls to IO::Socket::INET::new, avg 107ms/call # spent 8.73ms making 461 calls to LWP::Protocol::http::socket_class, avg 19µs/call # spent 5.09ms making 461 calls to LWP::Protocol::http::_extra_sock_opts, avg 11µs/call
36 PeerPort => $port,
37 Proto => 'tcp',
38 Timeout => $timeout,
39 KeepAlive => !!$conn_cache,
40 SendTE => 1,
41 $self->_extra_sock_opts($host, $port),
42 );
43
44 unless ($sock) {
45 # IO::Socket::INET leaves additional error messages in $@
46 $@ =~ s/^.*?: //;
47 die "Can't connect to $host:$port ($@)";
48 }
49
50 # perl 5.005's IO::Socket does not have the blocking method.
51 eval { $sock->blocking(0); };
# spent 5.46ms making 461 calls to IO::Handle::blocking, avg 12µs/call
52
53 $sock;
54}
55
56sub socket_class
57
# spent 8.73ms within LWP::Protocol::http::socket_class which was called 461 times, avg 19µs/call: # 461 times (8.73ms+0s) by LWP::Protocol::http::_new_socket at line 35, avg 19µs/call
{
5892210.6ms my $self = shift;
59 (ref($self) || $self) . "::Socket";
60}
61
62sub _extra_sock_opts # to be overridden by subclass
63
# spent 5.09ms within LWP::Protocol::http::_extra_sock_opts which was called 461 times, avg 11µs/call: # 461 times (5.09ms+0s) by LWP::Protocol::http::_new_socket at line 35, avg 11µs/call
{
644616.11ms return @EXTRA_SOCK_OPTS;
65}
66
67sub _check_sock
684615.75ms
# spent 5.20ms within LWP::Protocol::http::_check_sock which was called 461 times, avg 11µs/call: # 461 times (5.20ms+0s) by LWP::Protocol::http::request at line 155, avg 11µs/call
{
69 #my($self, $req, $sock) = @_;
70}
71
72sub _get_sock_info
73
# spent 292ms (29.8+262) within LWP::Protocol::http::_get_sock_info which was called 461 times, avg 633µs/call: # 461 times (29.8ms+262ms) by LWP::Protocol::http::request at line 328, avg 633µs/call
{
7492228.4ms my($self, $res, $sock) = @_;
75 if (defined(my $peerhost = $sock->peerhost)) {
# spent 109ms making 460 calls to HTTP::Message::__ANON__[(eval 0)[HTTP/Message.pm:371]:1], avg 236µs/call # spent 93.5ms making 461 calls to IO::Socket::INET::peerhost, avg 203µs/call # spent 59.1ms making 461 calls to IO::Socket::INET::peerport, avg 128µs/call # spent 197µs making 1 call to HTTP::Message::AUTOLOAD
76 $res->header("Client-Peer" => "$peerhost:" . $sock->peerport);
77 }
78}
79
80sub _fixup_header
81
# spent 138ms (34.4+104) within LWP::Protocol::http::_fixup_header which was called 461 times, avg 300µs/call: # 461 times (34.4ms+104ms) by LWP::Protocol::http::request at line 159, avg 300µs/call
{
82230535.8ms my($self, $h, $url, $proxy) = @_;
83
84 # Extract 'Host' header
85 my $hhost = $url->authority;
# spent 30.7ms making 461 calls to URI::_generic::authority, avg 67µs/call
86 if ($hhost =~ s/^([^\@]*)\@//) { # get rid of potential "user:pass@"
# spent 2.89ms making 461 calls to LWP::Protocol::http::CORE:subst, avg 6µs/call
87 # add authorization header if we need them. HTTP URLs do
88 # not really support specification of user and password, but
89 # we allow it.
90 if (defined($1) && not $h->header('Authorization')) {
91 require URI::Escape;
92 $h->authorization_basic(map URI::Escape::uri_unescape($_),
93 split(":", $1, 2));
94 }
95 }
96 $h->init_header('Host' => $hhost);
# spent 70.2ms making 461 calls to HTTP::Headers::init_header, avg 152µs/call
97
98 if ($proxy) {
99 # Check the proxy URI's userinfo() for proxy credentials
100 # export http_proxy="http://proxyuser:proxypass@proxyhost:port"
101 my $p_auth = $proxy->userinfo();
102 if(defined $p_auth) {
103 require URI::Escape;
104 $h->proxy_authorization_basic(map URI::Escape::uri_unescape($_),
105 split(":", $p_auth, 2))
106 }
107 }
108}
109
110sub hlist_remove {
111 my($hlist, $k) = @_;
112 $k = lc $k;
113 for (my $i = @$hlist - 2; $i >= 0; $i -= 2) {
114 next unless lc($hlist->[$i]) eq $k;
115 splice(@$hlist, $i, 2);
116 }
117}
118
119sub request
120
# spent 146s (576ms+146) within LWP::Protocol::http::request which was called 461 times, avg 318ms/call: # 461 times (576ms+146s) by LWP::UserAgent::send_request at line 215 of LWP/UserAgent.pm, avg 318ms/call
{
12135022521ms my($self, $request, $proxy, $arg, $size, $timeout) = @_;
122 LWP::Debug::trace('()');
# spent 5.17ms making 461 calls to LWP::Debug::trace, avg 11µs/call
123
124 $size ||= 4096;
125
126 # check method
127 my $method = $request->method;
# spent 26.3ms making 461 calls to HTTP::Request::method, avg 57µs/call
128 unless ($method =~ /^[A-Za-z0-9_!\#\$%&\'*+\-.^\`|~]+$/) { # HTTP token
# spent 4.13ms making 461 calls to LWP::Protocol::http::CORE:match, avg 9µs/call
129 return new HTTP::Response &HTTP::Status::RC_BAD_REQUEST,
130 'Library does not allow method ' .
131 "$method for 'http:' URLs";
132 }
133
134 my $url = $request->url;
# spent 12.2ms making 461 calls to HTTP::Request::uri, avg 26µs/call
135 my($host, $port, $fullpath);
136
137 # Check if we're proxy'ing
138 if (defined $proxy) {
139 # $proxy is an URL to an HTTP server which will proxy this request
140 $host = $proxy->host;
141 $port = $proxy->port;
142 $fullpath = $method eq "CONNECT" ?
143 ($url->host . ":" . $url->port) :
144 $url->as_string;
145 }
146 else {
147 $host = $url->host;
# spent 88.7ms making 461 calls to URI::_server::host, avg 192µs/call
148 $port = $url->port;
# spent 79.2ms making 461 calls to URI::_server::port, avg 172µs/call
149 $fullpath = $url->path_query;
# spent 26.6ms making 461 calls to URI::_generic::path_query, avg 58µs/call
150 $fullpath = "/$fullpath" unless $fullpath =~ m,^/,;
# spent 2.92ms making 461 calls to LWP::Protocol::http::CORE:match, avg 6µs/call
151 }
152
153 # connect to remote site
154 my $socket = $self->_new_socket($host, $port, $timeout);
# spent 49.6s making 461 calls to LWP::Protocol::http::_new_socket, avg 108ms/call
155 $self->_check_sock($request, $socket);
# spent 5.20ms making 461 calls to LWP::Protocol::http::_check_sock, avg 11µs/call
156
157 my @h;
158 my $request_headers = $request->headers->clone;
# spent 196ms making 461 calls to HTTP::Headers::clone, avg 425µs/call # spent 6.25ms making 461 calls to HTTP::Message::headers, avg 14µs/call
159 $self->_fixup_header($request_headers, $url, $proxy);
# spent 138ms making 461 calls to LWP::Protocol::http::_fixup_header, avg 300µs/call
160
161
# spent 50.9ms (42.0+8.96) within LWP::Protocol::http::__ANON__[/project/perl/lib/LWP/Protocol/http.pm:166] which was called 922 times, avg 55µs/call: # 922 times (42.0ms+8.96ms) by HTTP::Headers::scan at line 217 of HTTP/Headers.pm, avg 55µs/call
$request_headers->scan(sub {
162368851.3ms my($k, $v) = @_;
163 $k =~ s/^://;
# spent 4.44ms making 922 calls to LWP::Protocol::http::CORE:subst, avg 5µs/call
164 $v =~ s/\n/ /g;
# spent 4.52ms making 922 calls to LWP::Protocol::http::CORE:subst, avg 5µs/call
165 push(@h, $k, $v);
166 });
# spent 143ms making 461 calls to HTTP::Headers::scan, avg 310µs/call
167
168 my $content_ref = $request->content_ref;
# spent 22.8ms making 461 calls to HTTP::Message::content_ref, avg 50µs/call
169 $content_ref = $$content_ref if ref($$content_ref);
170 my $chunked;
171 my $has_content;
172
173 if (ref($content_ref) eq 'CODE') {
174 my $clen = $request_headers->header('Content-Length');
175 $has_content++ if $clen;
176 unless (defined $clen) {
177 push(@h, "Transfer-Encoding" => "chunked");
178 $has_content++;
179 $chunked++;
180 }
181 }
182 else {
183 # Set (or override) Content-Length header
184 my $clen = $request_headers->header('Content-Length');
# spent 77.6ms making 461 calls to HTTP::Headers::header, avg 168µs/call
185 if (defined($$content_ref) && length($$content_ref)) {
186 $has_content = length($$content_ref);
187 if (!defined($clen) || $clen ne $has_content) {
188 if (defined $clen) {
189 warn "Content-Length header value was wrong, fixed";
190 hlist_remove(\@h, 'Content-Length');
191 }
192 push(@h, 'Content-Length' => $has_content);
193 }
194 }
195 elsif ($clen) {
196 warn "Content-Length set when there is no content, fixed";
197 hlist_remove(\@h, 'Content-Length');
198 }
199 }
200
201 my $write_wait = 0;
202 $write_wait = 2
# spent 73.0ms making 461 calls to HTTP::Headers::header, avg 158µs/call # spent 2.29ms making 461 calls to LWP::Protocol::http::CORE:match, avg 5µs/call
203 if ($request_headers->header("Expect") || "") =~ /100-continue/;
204
205 my $req_buf = $socket->format_request($method, $fullpath, @h);
# spent 350ms making 461 calls to Net::HTTP::Methods::format_request, avg 759µs/call
206 #print "------\n$req_buf\n------\n";
207
208 if (!$has_content || $write_wait || $has_content > 8*1024) {
209 # XXX need to watch out for write timeouts
210 my $n = $socket->syswrite($req_buf, length($req_buf));
# spent 37.2ms making 461 calls to IO::Handle::syswrite, avg 81µs/call
211 die $! unless defined($n);
212 die "short write" unless $n == length($req_buf);
213 #LWP::Debug::conns($req_buf);
214 $req_buf = "";
215 }
216
217 my($code, $mess, @junk);
218 my $drop_connection;
219
220 if ($has_content) {
221 my $eof;
222 my $wbuf;
223 my $woffset = 0;
224 if (ref($content_ref) eq 'CODE') {
225 my $buf = &$content_ref();
226 $buf = "" unless defined($buf);
227 $buf = sprintf "%x%s%s%s", length($buf), $CRLF, $buf, $CRLF
228 if $chunked;
229 substr($buf, 0, 0) = $req_buf if $req_buf;
230 $wbuf = \$buf;
231 }
232 else {
233 if ($req_buf) {
234 my $buf = $req_buf . $$content_ref;
235 $wbuf = \$buf;
236 }
237 else {
238 $wbuf = $content_ref;
239 }
240 $eof = 1;
241 }
242
243 my $fbits = '';
244 vec($fbits, fileno($socket), 1) = 1;
245
246 while ($woffset < length($$wbuf)) {
247
248 my $time_before;
249 my $sel_timeout = $timeout;
250 if ($write_wait) {
251 $time_before = time;
252 $sel_timeout = $write_wait if $write_wait < $sel_timeout;
253 }
254
255 my $rbits = $fbits;
256 my $wbits = $write_wait ? undef : $fbits;
257 my $nfound = select($rbits, $wbits, undef, $sel_timeout);
258 unless (defined $nfound) {
259 die "select failed: $!";
260 }
261
262 if ($write_wait) {
263 $write_wait -= time - $time_before;
264 $write_wait = 0 if $write_wait < 0;
265 }
266
267 if (defined($rbits) && $rbits =~ /[^\0]/) {
268 # readable
269 my $buf = $socket->_rbuf;
270 my $n = $socket->sysread($buf, 1024, length($buf));
271 unless ($n) {
272 die "EOF";
273 }
274 $socket->_rbuf($buf);
275 if ($buf =~ /\015?\012\015?\012/) {
276 # a whole response present
277 ($code, $mess, @h) = $socket->read_response_headers(laxed => 1,
278 junk_out => \@junk,
279 );
280 if ($code eq "100") {
281 $write_wait = 0;
282 undef($code);
283 }
284 else {
285 $drop_connection++;
286 last;
287 # XXX should perhaps try to abort write in a nice way too
288 }
289 }
290 }
291 if (defined($wbits) && $wbits =~ /[^\0]/) {
292 my $n = $socket->syswrite($$wbuf, length($$wbuf), $woffset);
293 unless ($n) {
294 die "syswrite: $!" unless defined $n;
295 die "syswrite: no bytes written";
296 }
297 $woffset += $n;
298
299 if (!$eof && $woffset >= length($$wbuf)) {
300 # need to refill buffer from $content_ref code
301 my $buf = &$content_ref();
302 $buf = "" unless defined($buf);
303 $eof++ unless length($buf);
304 $buf = sprintf "%x%s%s%s", length($buf), $CRLF, $buf, $CRLF
305 if $chunked;
306 $wbuf = \$buf;
307 $woffset = 0;
308 }
309 }
310 }
311 }
312
313 ($code, $mess, @h) = $socket->read_response_headers(laxed => 1, junk_out => \@junk)
# spent 93.1s making 461 calls to Net::HTTP::Methods::read_response_headers, avg 202ms/call
314 unless $code;
315 ($code, $mess, @h) = $socket->read_response_headers(laxed => 1, junk_out => \@junk)
316 if $code eq "100";
317
318 my $response = HTTP::Response->new($code, $mess);
# spent 140ms making 461 calls to HTTP::Response::new, avg 304µs/call
319 my $peer_http_version = $socket->peer_http_version;
# spent 13.8ms making 461 calls to Net::HTTP::Methods::__ANON__[Net/HTTP/Methods.pm:81], avg 30µs/call
320 $response->protocol("HTTP/$peer_http_version");
# spent 26.4ms making 461 calls to HTTP::Message::protocol, avg 57µs/call
321 while (@h) {
322 my($k, $v) = splice(@h, 0, 2);
323 $response->push_header($k, $v);
# spent 776ms making 4602 calls to HTTP::Message::__ANON__[(eval 0)[HTTP/Message.pm:371]:1], avg 169µs/call # spent 183µs making 1 call to HTTP::Message::AUTOLOAD
324 }
325 $response->push_header("Client-Junk" => \@junk) if @junk;
326
327 $response->request($request);
# spent 28.1ms making 461 calls to HTTP::Response::request, avg 61µs/call
328 $self->_get_sock_info($response, $socket);
# spent 292ms making 461 calls to LWP::Protocol::http::_get_sock_info, avg 633µs/call
329
330 if ($method eq "CONNECT") {
331 $response->{client_socket} = $socket; # so it can be picked up
332 return $response;
333 }
334
335 if (my @te = $response->remove_header('Transfer-Encoding')) {
# spent 48.2ms making 460 calls to HTTP::Message::__ANON__[(eval 0)[HTTP/Message.pm:371]:1], avg 105µs/call # spent 157µs making 1 call to HTTP::Message::AUTOLOAD
336 $response->push_header('Client-Transfer-Encoding', \@te);
337 }
338 $response->push_header('Client-Response-Num', $socket->increment_response_count);
# spent 80.8ms making 461 calls to HTTP::Message::__ANON__[(eval 0)[HTTP/Message.pm:371]:1], avg 175µs/call # spent 9.49ms making 461 calls to LWP::Protocol::http::SocketMethods::increment_response_count, avg 21µs/call
339
340 my $complete;
341
# spent 91.0ms (32.0+59.1) within LWP::Protocol::http::__ANON__[/project/perl/lib/LWP/Protocol/http.pm:352] which was called 468 times, avg 195µs/call: # 461 times (31.5ms+57.0ms) by LWP::Protocol::collect at line 112 of LWP/Protocol.pm, avg 192µs/call # 7 times (458µs+2.08ms) by LWP::Protocol::collect at line 119 of LWP/Protocol.pm, avg 362µs/call
$response = $self->collect($arg, $response, sub {
342374430.6ms my $buf = ""; #prevent use of uninitialized value in SSLeay.xs
343 my $n;
344 READ:
# spent 59.1ms making 468 calls to Net::HTTP::Methods::read_entity_body, avg 126µs/call
345 {
346 $n = $socket->read_entity_body($buf, $size);
347 die "Can't read entity body: $!" unless defined $n;
348 redo READ if $n == -1;
349 }
350 $complete++ if !$n;
351 return \$buf;
352 } );
# spent 515ms making 461 calls to LWP::Protocol::collect, avg 1.12ms/call
353 $drop_connection++ unless $complete;
354
355 @h = $socket->get_trailers;
# spent 9.99ms making 461 calls to Net::HTTP::Methods::get_trailers, avg 22µs/call
356 while (@h) {
357 my($k, $v) = splice(@h, 0, 2);
358 $response->push_header($k, $v);
359 }
360
361 # keep-alive support
362 unless ($drop_connection) {
363 if (my $conn_cache = $self->{ua}{conn_cache}) {
364 my %connection = map { (lc($_) => 1) }
365 split(/\s*,\s*/, ($response->header("Connection") || ""));
366 if (($peer_http_version eq "1.1" && !$connection{close}) ||
367 $connection{"keep-alive"})
368 {
369 LWP::Debug::debug("Keep the http connection to $host:$port");
370 $conn_cache->deposit("http", "$host:$port", $socket);
371 }
372 }
373 }
374
375 $response;
376}
377
378
379#-----------------------------------------------------------
380package LWP::Protocol::http::SocketMethods;
381
382
# spent 92.1s (31.2ms+92.1) within LWP::Protocol::http::SocketMethods::sysread which was called 468 times, avg 197ms/call: # 461 times (30.7ms+92.0s) by Net::HTTP::Methods::my_readline at line 232 of Net/HTTP/Methods.pm, avg 200ms/call # 7 times (408µs+703µs) by Net::HTTP::Methods::my_read at line 213 of Net/HTTP/Methods.pm, avg 159µs/call
sub sysread {
383187247.5ms my $self = shift;
384 if (my $timeout = ${*$self}{io_socket_timeout}) {
385 die "read timeout" unless $self->can_read($timeout);
# spent 92.0s making 468 calls to LWP::Protocol::http::SocketMethods::can_read, avg 197ms/call
386 }
387 else {
388 # since we have made the socket non-blocking we
389 # use select to wait for some data to arrive
390 $self->can_read(undef) || die "Assert";
391 }
392 sysread($self, $_[0], $_[1], $_[2] || 0);
# spent 18.1ms making 468 calls to LWP::Protocol::http::SocketMethods::CORE:sysread, avg 39µs/call
393}
394
395
# spent 92.0s (32.4ms+92.0) within LWP::Protocol::http::SocketMethods::can_read which was called 468 times, avg 197ms/call: # 468 times (32.4ms+92.0s) by LWP::Protocol::http::SocketMethods::sysread at line 385, avg 197ms/call
sub can_read {
396280892.0s my($self, $timeout) = @_;
397 my $fbits = '';
398 vec($fbits, fileno($self), 1) = 1;
399 my $nfound = select($fbits, undef, undef, $timeout);
# spent 92.0s making 468 calls to LWP::Protocol::http::SocketMethods::CORE:sselect, avg 197ms/call
400 die "select failed: $!" unless defined $nfound;
401 return $nfound > 0;
402}
403
404sub ping {
405 my $self = shift;
406 !$self->can_read(0);
407}
408
409
# spent 9.49ms within LWP::Protocol::http::SocketMethods::increment_response_count which was called 461 times, avg 21µs/call: # 461 times (9.49ms+0s) by LWP::Protocol::http::request at line 338, avg 21µs/call
sub increment_response_count {
41092210.2ms my $self = shift;
411 return ++${*$self}{'myhttp_response_count'};
412}
413
414#-----------------------------------------------------------
415package LWP::Protocol::http::Socket;
4163142µs1149µsuse vars qw(@ISA);
# spent 149µs making 1 call to vars::import
41716µs@ISA = qw(LWP::Protocol::http::SocketMethods Net::HTTP);
418
419114µs1;
# spent 9.34ms within LWP::Protocol::http::CORE:match which was called 1383 times, avg 7µs/call: # 461 times (4.13ms+0s) by LWP::Protocol::http::request at line 128 of LWP/Protocol/http.pm, avg 9µs/call # 461 times (2.92ms+0s) by LWP::Protocol::http::request at line 150 of LWP/Protocol/http.pm, avg 6µs/call # 461 times (2.29ms+0s) by LWP::Protocol::http::request at line 202 of LWP/Protocol/http.pm, avg 5µs/call
sub LWP::Protocol::http::CORE:match; # xsub
# spent 11.8ms within LWP::Protocol::http::CORE:subst which was called 2305 times, avg 5µs/call: # 922 times (4.52ms+0s) by LWP::Protocol::http::__ANON__[/project/perl/lib/LWP/Protocol/http.pm:166] at line 164 of LWP/Protocol/http.pm, avg 5µs/call # 922 times (4.44ms+0s) by LWP::Protocol::http::__ANON__[/project/perl/lib/LWP/Protocol/http.pm:166] at line 163 of LWP/Protocol/http.pm, avg 5µs/call # 461 times (2.89ms+0s) by LWP::Protocol::http::_fixup_header at line 86 of LWP/Protocol/http.pm, avg 6µs/call
sub LWP::Protocol::http::CORE:subst; # xsub
# spent 92.0s within LWP::Protocol::http::SocketMethods::CORE:sselect which was called 468 times, avg 197ms/call: # 468 times (92.0s+0s) by LWP::Protocol::http::SocketMethods::can_read at line 399 of LWP/Protocol/http.pm, avg 197ms/call
sub LWP::Protocol::http::SocketMethods::CORE:sselect; # xsub
# spent 18.1ms within LWP::Protocol::http::SocketMethods::CORE:sysread which was called 468 times, avg 39µs/call: # 468 times (18.1ms+0s) by LWP::Protocol::http::SocketMethods::sysread at line 392 of LWP/Protocol/http.pm, avg 39µs/call
sub LWP::Protocol::http::SocketMethods::CORE:sysread; # xsub
Report produced by the NYTProf 3.11 Perl profiler, developed by Tim Bunce and Adam Kaplan.