1104873 - 80 ms delay fetching images after html body with local website

Status: UNCONFIRMED

(Core :: Networking: HTTP, defect, P3)

Description

[Peter Ham]

User Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_9_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/38.0.2125.122 Safari/537.36

Steps to reproduce:

load test website


Actual results:

html body loads immediately (in < 10 ms)
fetching net resources pauses for 80 ms (this is the issue)
first and only image referenced downloads


Expected results:

There should be no delay between fetching the html body and the image.

Comment 1

[Peter Ham]

Attachment: tar file with the trivial web page and image that it loads

If you host this web page on a local to your box web site (so that the first page loads in less than 50 ms or so), then you can reproduce this.

Comment 2

[Peter Ham]

This might not be a bug, but perhaps undocumented feature or behavior. I notice that internet explore loads the image immediately while chrome has similar behavior to firefox where there's a delay before the image is fetched.

Comment 3

[Peter Ham]

Attachment: test.txt

Here's a tcpdump output which shows the delay between the ack of the .htmfile and the http get of the image.

Comment 4

[Peter Ham]

Attachment: internet explorer seems more correct, it loads the image immediately

Comment 5

[Patrick McManus [:mcmanus]]

this is kind of interesting.. ODA happens quickly, but OnStop trails quite a ways after that even though the ODA filled out the h1 content length. It might just be the main thread dealing with that data.

honza likes this kind of problem.

peter - this is an nspr log.

https://developer.mozilla.org/en-US/docs/Mozilla/Debugging/HTTP_logging

2014-11-25 19:22:18.962185 UTC - 126793600[7f8d0645f4a0]: uri=http://localhost/test_one_image.html
2014-11-25 19:22:19.023440 UTC - 126793600[7f8d0645f4a0]: nsHttpChannel::OnStartRequest [this=7f8cd4c91800 request=7f8cd4d8a1c0 status=0]
2014-11-25 19:22:19.023450 UTC - 126793600[7f8d0645f4a0]: nsHttpChannel::ProcessResponse [this=7f8cd4c91800 httpStatus=200]

2014-11-25 19:22:19.082263 UTC - 126793600[7f8d0645f4a0]: nsInputStreamPump::RetargetDeliveryTo [this=d4d8a1c0 aNewTarget=7f8ce3f80a60] success listener [7f8cd4c91bf0] rv[0]
2014-11-25 19:22:19.082290 UTC - 126793600[7f8d0645f4a0]: HttpBaseChannel::DoApplyContentConversions [this=7f8cd4c91800]
2014-11-25 19:22:19.082312 UTC - 126793600[7f8d0645f4a0]: converter removed 'gzip' content-encoding

2014-11-25 19:22:19.082453 UTC - -477104384[7f8ce4a6d5e0]: nsHttpChannel::OnDataAvailable [this=7f8cd4c91800 request=7f8cd4d8a1c0 offset=0 count=195]
2014-11-25 19:22:19.540061 UTC - 126793600[7f8d0645f4a0]: sending progress and status notification [this=7f8cd4c91800 status=804b0006 progress=195/195]

2014-11-25 19:22:19.541435 UTC - 126793600[7f8d0645f4a0]: nsHttpChannel::OnStopRequest [this=7f8cd4c91800 request=7f8cd4d8a1c0 status=0]
2014-11-25 19:22:19.540644 UTC - 126793600[7f8d0645f4a0]: nsHttpChannel::Init [this=7f8cd82bc000]
2014-11-25 19:22:19.540941 UTC - 126793600[7f8d0645f4a0]: uri=http://localhost/empty_square_normal.gif

Comment 6

[Peter Ham]

Thanks for the update!

Comment 7

[Firefox Bug Husbandry Bot]

Bulk change to priority: https://bugzilla.mozilla.org/show_bug.cgi?id=1399258

Comment 8

[Firefox Bug Husbandry Bot]

Bulk change to priority: https://bugzilla.mozilla.org/show_bug.cgi?id=1399258