38853 - onEndLoad function of XMLSinkObserver is called lately

Status: VERIFIED FIXED

(Core :: Networking, defect, P3)

Description

[Jan Varga [:janv]]

after load of XML/RDF datasource, template is builded correctly
but observer is called after about 15 seconds

Comment 1

[Jan Varga [:janv]]

I find out this is specific only for dynamically generated datasources
e.g. with PHP

with static datasource observer is called immediatly


in my little debugging I also find out that method "OnStopRequest" isn't called
immediatly. Maybe specific to netwerk module...

Comment 2

[Chris Waterson]

can you please provide a test case? what is PHP?

Comment 3

[Jan Varga [:janv]]

Attachment: testcase

Comment 4

[Jan Varga [:janv]]

PHP is language for creating dynamic pages (e.g. querying database)


testcase buttons:

"Search"  - URI is based on entered data (enter "1" as id for example)
          - dynamic datasource (requests.php)

"Search2" - static datasource (requests.rdf)

when you hit "Search2" button, cursor changes its state to "wait"
after datasource is loaded, observer is called and that observer change cursor
state back to "auto"
this is how it would work and it is working correctly.

when you hit "search" button it should working as "search2"
but in this case observer is called after several seconds (15 seconds for me)
also working about 2 weeks ago


Datasource requests are logged in web server logs.
"Search" request is logged as successfull request (code 200)
"Search2" request is logged as ... (code 304)


in the debug build it is same as in non-debug, optimized build

Comment 5

[Jan Varga [:janv]]

works in M15

Comment 6

[Chris Waterson]

ruslan: this sounds like it might have something to do with HTTP? Does it sound 
familiar?

Comment 7

[Andreas Otte]

I also see this when just running TestProtocols (necko test program) against a
static local webpage. The OnStopRequest event needs 15 seconds to come through.

Comment 8

[Chris Waterson]

-> networking for now, based on andreas' comments.

Comment 9

[ruslan]

Might be incorrect http 1.1 response (whithout or with incorrect content-length 
or busted chunk-encoding) coming out of server which causes the client to wait 
until the connection gets closed. Is there a url where I can consistently see 
it?

Comment 10

[Jan Varga [:janv]]

DS URLs:

http://fri.utcru.sk/lis/datasources/results/requests.rdf
or
http://fri.utcru.sk/lis/datasources/results/requests.php?patiend_id=1

strange...
- when first one is loaded, observer is called imediately but web server log
  this request with error code 304
- when second one is loaded, observer is called after about 15 seconds, but
  this request is loged as correct (code 200)

also you can find DS URLs in testcase

Comment 11

[Jan Varga [:janv]]

sorry, second URL should be:
http://fri.utcru.sk/lis/datasources/results/requests.php?patient_id=1

Comment 12

[ruslan]

Let me look into it first

Comment 13

[ruslan]

I added a feature to chunk converter about a week ago to support trailer headers 
which seemed to have  unmasked a very tedious bug in the converter which as 
result would not tell back the http handler about the end  of request condition 
under certain circumstances. I've a fix, I'll test it a bit more and will check 
it in today

Comment 14

[ruslan]

Fixed

Comment 15

[Tom Everingham]

verified