Closed Bug 138572 Opened 23 years ago Closed 23 years ago

Multiple socket open

Categories

(Core :: Networking: HTTP, defect)

x86
Linux
defect
Not set
critical

Tracking

()

VERIFIED INVALID

People

(Reporter: abrillant, Assigned: darin.moz)

Details

From Bugzilla Helper: User-Agent: Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.0rc1) Gecko/20020417 BuildID: 2002041711 I'm building a web server, I have noticed that Mozilla open several socket connection for each URL ! Reproducible: Always Steps to Reproduce: 1.Use a tools that listen for a Socket on the 80 port 2.Send a local connection 3.You can see several socket Expected Results: It should open one Socket as needed otherwise it costs a lot of software/hardware resource.
-> Networking:http
Assignee: Matti → darin
Component: Browser-General → Networking: HTTP
QA Contact: imajes-qa → tever
reporter: can you please provide more details. does your server support keep-alives? what is the behavior like with IE?
I have compared this behavior with konqueror, I have noticed almost 5 sockets was enabled for each user connection. My program is available here : http://www.djefer.com/~aws. It uses the 8080 port for HTTP connection look at the SocketService code this is simple a java ServerSocket and a loop for each user connection.
my guess is that your server does not support keep-alives. as a result mozilla opens a bunch of parallel socket connections. this is normal behavior. 4x behaves this way. perhaps konqueror doesn't parallelize as much. at any rate, can you verify that your server doesn't implement keep-alives?
Indeed keep-alive isn't supported, rather my server is a HTTP/1.0 server, for GET/POST request I don't care about keep-alive, This web server has been tested with IE5 too, it doesn't behave like that. I don't think several socket for a static page are a normal behavior as it costs a lot for the server part too.
it's common practice to open multiple parallel connections to a HTTP/1.0 server (up to a certain limit of course). that's how HTTP/1.0 was designed, and that's how all popular web browsers are implemented. support for keep-alive connections is the accepted/standard answer to this performance problem, as it amortizes the cost of setting up a TCP/IP connection over several HTTP transactions. i'm tempted to mark this WONTFIX, but something about your bug description intrigues me... you say that mozilla opens multiple socket connections *for each URL*... do you mean that mozilla is fetching the same URL more than once when it should only need to fetch it once? if so, then i've misunderstood the problem. can you provide an URL that demonstrates the problem? is your webserver online such that i can observe the problem you're describing? or can you enable the following environment variable before running mozilla to trace mozilla's HTTP module: setenv NSPR_LOG_MODULES nsHttp:5 setenv NSPR_LOG_MODULES http.log thx!
Here a set of trace about the problem : [[My Server is starting]] Starting JASon 0.5 ... [http_1.0] Listening on port :10001 [[I run mozilla with http://127.0.0.1:10001]] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32805,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32806,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32807,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32808,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32809,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32810,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32811,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32812,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32813,localport=10001] (0) jason.core SocketService : SOCKET = Socket[addr=localhost.localdomain/127.0.0.1,port=32814,localport=10001] ??????????????????????????????????????????????????????? As you can see it opens a lot of sockets, this is the same behavior for updating the current page !!! Below the mozilla LOG 1024[8087eb0]: nsHttpHandler::Init 1024[8087eb0]: nsHttpHandler::PrefsChanged [pref=(null)] 1024[8087eb0]: nsHttpAuthCache::Init 1024[8087eb0]: nsHttpHandler::BuildUserAgent 1024[8087eb0]: nsHttpHandler::NewURI 1024[8087eb0]: nsHttpHandler::NewProxiedChannel [proxyInfo=0] 1024[8087eb0]: Creating nsHttpChannel @863cac0 1024[8087eb0]: nsHttpChannel::Init [this=863cac0] 1024[8087eb0]: host=127.0.0.1 port=10001 1024[8087eb0]: uri=http://127.0.0.1:10001/ 1024[8087eb0]: Creating nsHttpConnectionInfo @863cc00 1024[8087eb0]: nsHttpHandler::AddStandardRequestHeaders 1024[8087eb0]: nsHttpChannel::AddAuthorizationHeaders? [this=863cac0] 1024[8087eb0]: nsHttpAuthCache::GetAuthEntryForPath [host=127.0.0.1:10001 path=/] 1024[8087eb0]: nsHttpChannel::AsyncOpen [this=863cac0] 1024[8087eb0]: nsHttpHandler::OnModifyRequest [chan=863cac0] 1024[8087eb0]: nsHttpChannel::SetRequestHeader [this=863cac0 header=Cookie value=] 1024[8087eb0]: CreateAtomTable 1024[8087eb0]: nsHttpChannel::Connect [this=863cac0] 1024[8087eb0]: nsHttpChannel::OpenCacheEntry [this=863cac0] 1024[8087eb0]: got cache entry [access=3] 1024[8087eb0]: nsHTTPChannel::CheckCache [this=863cac0 entry=82bc708] 1024[8087eb0]: nsHttpResponseHead::Parse [this=8657628] 1024[8087eb0]: nsHttpResponseHead::ParseVersion [version=HTTP/1.1 200 OK] 1024[8087eb0]: Have status line [version=11 status=200 statusText=OK] 1024[8087eb0]: nsHttpResponseHead::ParseContentType [type=text/html] 1024[8087eb0]: nsHttpResponseHead::MustValidate ?? 1024[8087eb0]: no mandatory validation requirement 1024[8087eb0]: Validating based on expiration time 1024[8087eb0]: CheckCache [this=863cac0 doValidation=1] 1024[8087eb0]: Creating nsHttpTransaction @8657780 1024[8087eb0]: nsHttpTransaction::SetupRequest [this=8657780] 1024[8087eb0]: http request [ 1024[8087eb0]: GET / HTTP/1.1 1024[8087eb0]: Host: 127.0.0.1:10001 1024[8087eb0]: User-Agent: Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.0rc1) Gecko/20020417 1024[8087eb0]: Accept: text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,video/x-mng,image/png,image/jpeg,image/gif;q=0.2,text/css,*/*;q=0.1 1024[8087eb0]: Accept-Language: en-us, en;q=0.50 1024[8087eb0]: Accept-Encoding: gzip, deflate, compress;q=0.9 1024[8087eb0]: Accept-Charset: ISO-8859-1, utf-8;q=0.66, *;q=0.66 1024[8087eb0]: Keep-Alive: 300 1024[8087eb0]: Connection: keep-alive 1024[8087eb0]: ] 1024[8087eb0]: nsHttpHandler::InitiateTransaction 1024[8087eb0]: nsHttpHandler::GetConnection_Locked 1024[8087eb0]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1024[8087eb0]: total-count=0, persistent-count=0 1024[8087eb0]: >> creating new connection... 1024[8087eb0]: Creating nsHttpConnection @86578e8 1024[8087eb0]: nsHttpConnection::Init [this=86578e8] 1024[8087eb0]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=86578e8] 1024[8087eb0]: nsHttpConnection::SetTransaction [this=86578e8 trans=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=86578e8] 1026[814f200]: nsHttpConnection::OnStartRequest [this=86578e8] 1026[814f200]: nsHttpConnection::OnDataWritable [this=86578e8] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnDataWritable [this=86578e8] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpConnection::OnStopRequest [this=86578e8 ctxt=86578ec status=0] 1026[814f200]: nsHttpConnection::OnStopRequest [this=86578e8 ctxt=0 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=86578e8 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @81513b8 1026[814f200]: nsHttpConnection::Init [this=81513b8] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=81513b8] 1026[814f200]: nsHttpConnection::SetTransaction [this=81513b8 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=86578e8(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=81513b8] 1026[814f200]: nsHttpConnection::OnStartRequest [this=81513b8] 1026[814f200]: nsHttpConnection::OnDataWritable [this=81513b8] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnDataWritable [this=81513b8] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpConnection::OnStopRequest [this=81513b8 ctxt=81513bc status=0] 1026[814f200]: nsHttpConnection::OnStopRequest [this=81513b8 ctxt=0 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=81513b8 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @8152308 1026[814f200]: nsHttpConnection::Init [this=8152308] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=8152308] 1026[814f200]: nsHttpConnection::SetTransaction [this=8152308 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=81513b8(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @81513b8 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8152308] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8152308] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8152308] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8152308] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8152308 ctxt=815230c status=0] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8152308 ctxt=0 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=8152308 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @8151d48 1026[814f200]: nsHttpConnection::Init [this=8151d48] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=8151d48] 1026[814f200]: nsHttpConnection::SetTransaction [this=8151d48 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=8152308(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @8152308 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8151d48] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8151d48] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8151d48] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8151d48] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8151d48 ctxt=8151d4c status=0] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8151d48 ctxt=0 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=8151d48 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @81518a0 1026[814f200]: nsHttpConnection::Init [this=81518a0] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=81518a0] 1026[814f200]: nsHttpConnection::SetTransaction [this=81518a0 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=8151d48(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @8151d48 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=81518a0] 1026[814f200]: nsHttpConnection::OnStartRequest [this=81518a0] 1026[814f200]: nsHttpConnection::OnDataWritable [this=81518a0] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnStopRequest [this=81518a0 ctxt=0 status=804b0014] 1026[814f200]: nsHttpConnection::OnStopRequest [this=81518a0 ctxt=81518a4 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=81518a0 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @8151470 1026[814f200]: nsHttpConnection::Init [this=8151470] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=8151470] 1026[814f200]: nsHttpConnection::SetTransaction [this=8151470 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=81518a0(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @81518a0 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8151470] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8151470] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8151470] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8151470 ctxt=0 status=804b0014] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8151470 ctxt=8151474 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=8151470 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @86589a0 1026[814f200]: nsHttpConnection::Init [this=86589a0] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=86589a0] 1026[814f200]: nsHttpConnection::SetTransaction [this=86589a0 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=8151470(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @8151470 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=86589a0] 1026[814f200]: nsHttpConnection::OnStartRequest [this=86589a0] 1026[814f200]: nsHttpConnection::OnDataWritable [this=86589a0] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnStopRequest [this=86589a0 ctxt=0 status=804b0014] 1026[814f200]: nsHttpConnection::OnStopRequest [this=86589a0 ctxt=86589a4 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=86589a0 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @8658760 1026[814f200]: nsHttpConnection::Init [this=8658760] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=8658760] 1026[814f200]: nsHttpConnection::SetTransaction [this=8658760 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=86589a0(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @86589a0 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8658760] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8658760] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8658760] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8658760 ctxt=0 status=804b0014] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8658760 ctxt=8658764 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=8658760 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @8659110 1026[814f200]: nsHttpConnection::Init [this=8659110] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=8659110] 1026[814f200]: nsHttpConnection::SetTransaction [this=8659110 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=8658760(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @8658760 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8659110] 1026[814f200]: nsHttpConnection::OnStartRequest [this=8659110] 1026[814f200]: nsHttpConnection::OnDataWritable [this=8659110] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8659110 ctxt=0 status=804b0014] 1026[814f200]: nsHttpConnection::OnStopRequest [this=8659110 ctxt=8659114 status=804b0014] 1026[814f200]: nsHttpTransaction::OnStopTransaction [this=8657780 status=804b0014] 1026[814f200]: restarting transaction @8657780 1026[814f200]: nsHttpConnection::DropTransaction [trans=8657780] 1026[814f200]: nsHttpHandler::InitiateTransaction 1026[814f200]: nsHttpHandler::GetConnection_Locked 1026[814f200]: nsHttpHandler::AtActiveConnectionLimit_Locked [host=127.0.0.1:10001 caps=1] 1026[814f200]: >> comparing against active connection [conn=8659110 host=127.0.0.1:10001] 1026[814f200]: total-count=1, persistent-count=0 1026[814f200]: >> creating new connection... 1026[814f200]: Creating nsHttpConnection @86594d8 1026[814f200]: nsHttpConnection::Init [this=86594d8] 1026[814f200]: nsHttpHandler::DispatchTransaction_Locked [trans=8657780 conn=86594d8] 1026[814f200]: nsHttpConnection::SetTransaction [this=86594d8 trans=8657780] 1026[814f200]: nsHttpHandler::ReclaimConnection [conn=8659110(127.0.0.1:10001) keep-alive=0] 1026[814f200]: closing connection: connection can't be reused 1026[814f200]: active connection count is now 1 1026[814f200]: nsHttpHandler::ProcessTransactionQ_Locked 1026[814f200]: >> unable to process transaction queue at this time 1026[814f200]: Destroying nsHttpConnection @8659110 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0007] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0004] 1026[814f200]: nsHttpConnection::OnStartRequest [this=86594d8] 1026[814f200]: nsHttpConnection::OnStartRequest [this=86594d8] 1026[814f200]: nsHttpConnection::OnDataWritable [this=86594d8] 1026[814f200]: calling mTransaction->OnDataWritable 1026[814f200]: nsHttpTransaction::OnDataWritable [this=8657780] 1026[814f200]: nsHttpTransaction::OnStatus [this=8657780 status=804b0005] 1026[814f200]: nsHttpConnection::OnStopRequest [this=86594d8 ctxt=0 status=804b0014]
hmm... it appears that your server is hanging up the connection before mozilla can read any data! as a result, mozilla tries to issue a new transaction on a new connection. check netstat and i bet you'll simply see a bunch of sockets in the TIME_WAIT state. this looks like a server problem to me. marking INVALID
Status: UNCONFIRMED → RESOLVED
Closed: 23 years ago
Resolution: --- → INVALID
Catching up. If this is still a problem, please reopen bug. Thanks! Marking Verified.
Status: RESOLVED → VERIFIED
QA Contact: tever → jimmylee
You need to log in before you can comment on or make changes to this bug.