148896 - Reload of CGI generated Image causes double-download

Status: RESOLVED DUPLICATE of bug 121084

(Core :: Networking: HTTP, defect)

Description

[Joe McCabe]

2002052808 appears to be performing a double load on images generated by a CGI

By hitting the URL above I see this appear in my server's access logs:

64.236.139.249 - - [03/Jun/2002:17:49:43 -0700] "GET
/cgi-bin/rand_img/rand_img.cgi HTTP/1.1" 200 9778 "-" "Mozilla/5.0 (X11; U;
Linux i686; en-US; rv:1.0.0+) Gecko/20020528"
64.236.139.249 - - [03/Jun/2002:17:49:44 -0700] "GET
/cgi-bin/rand_img/rand_img.cgi HTTP/1.1" 200 11372 "-" "Mozilla/5.0 (X11; U;
Linux i686; en-US; rv:1.0.0+) Gecko/20020528"

The SSLTap output will be attached in a moment.

Comment 1

[Joe McCabe]

Attachment: SSLTap output showing double-load

This SSLTap output is the result of a single click of the "Reload" button on
the CGI generated image.

Comment 2

[benc]

possibly will end up in imagelib or cache...

Comment 3

[Joe McCabe]

More poking shows that this only happens with a reload (or so it seems to me).

Comment 4

[Kai Lahmann (is there, where MNG is)]

reporter (Joe McCabe): can you reproduce this bug with a recent build of mozilla
(for example, 1.1beta)? if so, please comment again with details. if not, please
resolve this bug as WORKSFORME. thanks.

Comment 5

[Joe McCabe]

Using 2002080208 I see this in my access log:

64.236.139.164 - - [05/Aug/2002:08:58:36 -0700] "GET /cgi-bin/office.cgi
HTTP/1.0" 200 7974 "-" "Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.1b)
Gecko/20020802"
64.236.139.164 - - [05/Aug/2002:08:58:37 -0700] "GET /cgi-bin/office.cgi
HTTP/1.0" 200 7974 "-" "Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.1b)
Gecko/20020802"

The exact sequence I followed was:

1) Load CGI above. Once access log entry was created.
2) Hit "Reload" button. Two access log entries were created.
3) Place cursor in Location bar and hit "Enter." One access log enty was created.

Comment 6

[Darin Fisher]

reporter: what is your cache validation preference set to?  see
Preferences->Advanced->Cache.  if set to "Every time I view the page" then this
is most likely a dupe of bug 121084.

Comment 7

[Joe McCabe]

Mem cache: 4096
Disk cache: 50000

"Once per session"

Comment 8

[Joe McCabe]

I'm not able to reproduce this behavior with 

Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.3b) Gecko/20030130

Comment 9

[Jim Dunn]

are you sure?  If I set NSPR_LOG_MODULES=nsHttp:5
I am seeing 2 requests for that image.  With the
2nd one (with a 2/3/2003 build).

when I hit "reload" I see the following output: 
(NOTE: let me know if you want me to post the whole log)

    0[2649d0]: nsHttpChannel::OpenCacheEntry [this=39a83f0]
    0[2649d0]: got cache entry [access=3]
    0[2649d0]: nsHTTPChannel::CheckCache [this=39a83f0 entry=360b8d8]
    0[2649d0]: nsHttpResponseHead::Parse [this=39132f0]
    0[2649d0]: nsHttpResponseHead::ParseVersion [version=HTTP/1.1 200 OK]
    0[2649d0]: Have status line [version=11 status=200 statusText=OK]
    0[2649d0]: nsHttpResponseHead::ParseContentType [type=image/jpeg]
    0[2649d0]: Validating based on VALIDATE_ALWAYS load flag
    0[2649d0]: CheckCache [this=39a83f0 doValidation=1]
    0[2649d0]: nsHttpChannel::SetupTransaction [this=39a83f0] pipelining disallowed
    0[2649d0]: Creating nsHttpTransaction @39f49a8
    0[2649d0]: nsHttpTransaction::Init [this=39f49a8 caps=1]
    0[2649d0]: http request [
    0[2649d0]:   GET /cgi-bin/office.cgi HTTP/1.1

It almost looks like we are just ALWAYS requesting it.

Comment 10

[Joe McCabe]

If you say so. I'm just using the test case I provided earlier - access, reload,
count the log entries on the server.

Comment 11

[Joe McCabe]

So using my previous test case I see this appear in the access logs:

First request:
64.236.139.55 - - [10/Feb/2003:13:09:52 -0800] "GET /cgi-bin/office.cgi
HTTP/1.1" 200 45221 "-" "Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.3b)
Gecko/20030130"

First hit of "Reload":
64.236.139.55 - - [10/Feb/2003:13:09:58 -0800] "GET /cgi-bin/office.cgi
HTTP/1.1" 200 45221 "-" "Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.3b)
Gecko/20030130"

Second hit of "Reload":
64.236.139.55 - - [10/Feb/2003:13:10:08 -0800] "GET /cgi-bin/office.cgi
HTTP/1.1" 200 45221 "-" "Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.3b)
Gecko/20030130"


So I loaded the URL. I then hit "Reload" and waited. I then hit "Reload" again.

Now the bytes transfered indicates that each time Mozilla pulled the entire
content of the URL (no caching used), but this is different than the issue
reported with this bug (a single bonk of "Reload" causes two hits on the server).

Comment 12

[Boris Zbarsky [:bzbarsky]]

This should be fixed by bug 121084...

Comment 13

[Jim Dunn]

I am seeing this fixed with today's daily build
Mozilla/5.0 (Windows; U; Windows NT 5.0; en-US; rv:1.4a) Gecko/20030403

Comment 14

[Joe McCabe]

I am unable to reproduce this with 20040122 (and I suspect any build since
200307xx or so).

Marking Resolved, Duplicate of 121084.

*** This bug has been marked as a duplicate of 121084 ***