|16:19||• Closed ticket [46b6edad51]: HTTP Keep-Alive with Pipelined requests fails plus 7 other changes artifact: 6ef9c8e164 user: jan.nijtmans|
|13:53||• Ticket [46b6edad51]: 3 changes artifact: 08f5911b02 user: kjnash|
|13:49||• Add attachment http-files-82cbd9d626.tar.gz to ticket [46b6edad51] artifact: b20101791d user: kjnash|
|13:48||• Delete attachment "http-files-05ac4d0cdd.tar.gz" from ticket [46b6edad51] artifact: d787247cd7 user: kjnash|
|16:57||• Add attachment http-files-05ac4d0cdd.tar.gz to ticket [46b6edad51] artifact: 6e5cd5468e user: kjnash|
|16:57||• Delete attachment "http-files-1ec9da4c46.tar.gz" from ticket [46b6edad51] artifact: c0ce0c6f18 user: kjnash|
|16:56||• Ticket [46b6edad51] HTTP Keep-Alive with Pipelined requests fails status still Open with 3 other changes artifact: b4bab9be11 user: kjnash|
|16:52||• Delete attachment "http-files-386c1a2e43.tar.gz" from ticket [46b6edad51] artifact: af6510d8dc user: kjnash|
|16:51||• Add attachment http-files-1ec9da4c46.tar.gz to ticket [46b6edad51] artifact: 23cac41655 user: kjnash|
|15:17||• Ticket [46b6edad51] HTTP Keep-Alive with Pipelined requests fails status still Open with 3 other changes artifact: 53062fd542 user: kjnash|
|14:16||• Ticket [46b6edad51]: 3 changes artifact: 4a5a554659 user: kjnash|
|16:03||• Ticket [46b6edad51]: 3 changes artifact: 28a5513d9b user: dgp|
|20:09||• Ticket [46b6edad51]: 3 changes artifact: bf136b6a48 user: erikleunissen|
|15:12||• Ticket [46b6edad51]: 3 changes artifact: 9ff7ace97d user: kjnash|
|15:11||• Add attachment http-files-386c1a2e43.tar.gz to ticket [46b6edad51] artifact: 71d8fb450f user: kjnash|
|14:27||• Ticket [46b6edad51] HTTP Keep-Alive with Pipelined requests fails status still Open with 3 other changes artifact: 1de540d019 user: kjnash|
|17:23||• Ticket [46b6edad51]: 3 changes artifact: cb29f91f5e user: kjnash|
|16:04||• Add attachment test-http-3.tcl to ticket [46b6edad51] artifact: aab840d278 user: kjnash|
|16:01||• New ticket [46b6edad51] HTTP Keep-Alive with Pipelined requests fails. artifact: b5944ec8ff user: kjnash|
|Title:||HTTP Keep-Alive with Pipelined requests fails|
|Submitter:||kjnash||Created on:||2017-11-30 16:01:25|
|Subsystem:||29. http Package||Assigned To:||jan.nijtmans|
|Status:||Closed||Last Modified:||2018-09-23 16:19:49|
|Closed on:||2018-09-23 16:19:49|
When http::geturl is called with -keepalive 1, and then called again (for a URL on the same server) before the first HTTP response is complete, the two HTTP requests are not correctly handled: (a) if the time delay between the two calls is short, then the first HTTP request is never sent; (b) if the delay is longer, but not long enough for the first HTTP response to be complete, then the second HTTP response is thrown away, and the first HTTP response is assigned to the second HTTP request. In both cases, Tcl reports the first HTTP response as a timeout. The attached test script has a full explanation.
jan.nijtmans added on 2018-09-23 16:19:49:
Merged to all branches now. There will probably be a follow-up (like a TIP or so), registering the 'official' acceptance of this in the core. But that still means that this bug is now officially fixed. Thanks, Keath, for all your work!
kjnash added on 2018-09-20 13:53:24:
Branch bug-46b6edad51-concurrent-http commit 82cbd9d626 has been merged with core-8-6-branch. The attached file http-files-82cbd9d626.tar.gz provides all the files that were modified in core-8-6-branch. Earlier tarballs have been removed.
kjnash added on 2018-04-20 16:56:55:
Attached file http-files-05ac4d0cdd.tar.gz provides all the files that differ from Tcl 8.6.8. The tarball is up to date with branch bug-46b6edad51-concurrent-http commit 05ac4d0cdd. Earlier tarballs have been removed.
kjnash added on 2018-04-13 15:17:38:
I mean ticket 3599789 which provides a patch as artefact a3dcbc5e6dd81251. KA = Keep-Alive
kjnash added on 2018-04-12 14:16:02:
Yes, I believe that if the changes in branch bug-46b6edad51-concurrent-http are accepted, they will fix bug a3dcbc5e6dd81251. The patch supplied with ticket a3dcbc5e6dd81251 makes three changes: 1. http::CloseSocket - when closing a socket, use sockettokenmap to reset tokens for any KA requests that use the socket. 2. http::geturl - create sockettokenmap as a list of tokens for KA-requests that use a particular socket. 3. http::wait - return immediately (without waiting) if the socket for a KA request is found to be closed. These changes handle some of the issues that arise when a persistent socket is half-closed by the server, but this event is not detected by the client. Branch bug-46b6edad51-concurrent-http handles this problem slightly differently: (a) when a persistent socket is idle, "fileevent readable" events are bound to http::CheckEof which closes the socket if it is half-closed by the server; (b) http::Event, http::Connect and http::Connected include code to handle an asynchronous close event when a persistent socket is used after a period of idleness; (c) if a request has not been completed when its persistent socket closes, its token is not reset (as in ticket a3dcbc5e6dd81251), but sent over a new connection; (d) several tests in tests/httpPipeline.test cover cases where the server half-closes a persistent socket either after timeout, or by unexpectedly sending a "Connection: close" header. The tests pass.
dgp added on 2018-04-06 16:03:03:
Is there any overlap between this work and the older ticket... https://core.tcl-lang.org/tcl/tktview?name=3599789 ?
erikleunissen added on 2018-04-01 20:09:10:
Regarding: II. FIXING STALLED HTTPS TRANSFERS This bug shows many similarities with the bug reported here: https://sourceforge.net/p/tls/bugs/38/ See especially the report.pdf attached to this ticket. The bug was cross-filed here: http://core.tcl.tk/tcl/tktview/1945538fffffffffffff Regards, Erik Leunissen --
kjnash added on 2018-04-01 15:12:20:
Attached file http-files-386c1a2e43.tar.gz provides all the files that differ from Tcl 8.6.8.
kjnash added on 2018-04-01 14:27:46:
Tcl branch bug-46b6edad51-concurrent-http fixes this problem. I. QUEUEING AND PIPELINING REQUESTS The existing http library does not handle concurrent use of a persistent connection correctly: it makes it the responsibility of the caller of http::geturl to check that one HTTP transaction is complete before submitting the next. Also, it does not monitor idle connections for closure at the server end. The revisions in branch bug-46b6edad51-concurrent-http arrange for such HTTP requests to be queued until the connection is available, and pipelined if appropriate. If the connection is lost, RFC 7230 permits retry in some cases. Connections are monitored for server timeout. New options for http::config control these facilities: -pipeline boolean Specifies whether HTTP/1.1 transactions on a persistent socket will be pipelined. The default is 1. -postfresh boolean Specifies whether requests that use the POST method will always use a fresh socket, overriding the -keepalive option of command http::geturl. The default is 0. -repost boolean Specifies what to do if a POST request over a persistent connection fails because the server has half-closed the connection. If boolean true, the request will be automatically retried; if boolean false it will not, and the application that uses http::geturl is expected to seek user confirmation before retrying the POST. The default is 0. Further discussion in section "PERSISTENT CONNECTIONS" of http(n). II. FIXING STALLED HTTPS TRANSFERS Tcltls bug https://core.tcl.tk/tcltls/tktview/94c6a431fe A https request for an entity that is larger than the http::geturl option -blocksize hangs if the server response uses a persistent connection and does not use chunked encoding (but does supply an accurate Content-Length header). The client does not finish reading until the server times out. I am not sure whether this bug lies in Tcltls, Tcl stacked channels, the Tcl [read] command, fileevent generation or buffering, but I have submitted a ticket on Tcltls. The bug is worked around by specifying the number of characters for each [read] as ($state(totalsize) - $state(currentsize)). It appears to be necessary to do this for every read, not only for the last read(s) where this quantity is smaller than the -blocksize that would normally be requested. III. DON'T ASSUME THE SERVER WILL DEFAULT TO KEEP-ALIVE This behavior is mandatory in RFC 7230, but NOT in the superseded RFC 2616. Much of the web is delivered by servers that comply with the older standard. These may close the connection if the client does not send a "Connection: keep-alive" header. The http package sends the header only for HTTP/1.0. The new branch fixes this bug and sends the header for HTTP/1.1 also. IV. DISABLING COMPRESSION By default http asks the server to compress the HTTP response. A new option for http::config allows this request to be switched off. -zip boolean If the value is boolean true, then by default requests will send a header "Accept-Encoding: gzip,deflate,compress". If the value is boolean false, then by default this header will not be sent. In either case the default can be overridden for an individual request by supplying a custom Accept-Encoding header in the -headers option of http::geturl. The default is 1. V. MODIFIED FILES (cf. 8.6.8) EDITED doc/http.n EDITED library/http/http.tcl EDITED tests/http11.test ADDED tests/httpPipeline.test ADDED tests/httpTest.tcl ADDED tests/httpTestScript.tcl VI. TESTS The tests in the new test file tests/httpPipeline.test all have a constraint "serverNeeded" and so by default are not executed. There are over 1000 tests, which are time-consuming because they use a delay to ensure that persistent connections to the server have timed out, and they also suffer from network latency. Some tests may appear superfluous, but they do reveal bugs, including some where test failure depends on the details of client/server timing and is therefore intermittent. VII. DETAILS OF COMMIT 680b022c38 Refactor variable names: conn_id -> connId (in ::http::CloseSocket) socketmap -> socketMapping (namespace variable) In http::Event, local variable "n" is used for multiple purposes. Reserve it for an increment to the length of the response body, and apply n -> nsl, nhl, ntl for its other meanings. Set n 0 in one place. In http::Event, local variable "chunk" is used for two purposes. chunk -> hexLenChunk when it holds the length of the chunk in hexadecimal. Refactor command name: Eof -> Eot (the command is sometimes called when not eof. Comments in command header updated.) VIII. DETAILS OF COMMIT 78b23edb6b a. Carefully distinguish expected and premature eof. In http::Eot -- - Remove optional argument "force" which is never used. - Do not use ($state(state) eq "header") to test whether the transaction is complete. Rely on a new argument "reason" instead. - Pass modified value $reason to http::Finish. In http::Finish -- - Call CloseSocket if state(status) is "eof". In http::Entry -- - Set state(state) to new value "complete" to signal that the response is complete, or will be complete when the server closes the connection. The value is read within http::Entry (and nowhere else) as a test of completion. b. Bugfixes in recording the size of the response body. In http::Entry -- - Do not count $n towards the size of the body if it is not the length of a part of the body. Set it to 0 instead. This includes HTTP trailers. c. Terminate the response and the connection if there is an error with chunked encoding. d. When using -handler option to http::geturl, terminate the response if the handler command does not return an integer result as required (and documented). - This change can be reversed if considered inappropriate, but at the cost of (a) not knowing the size of the response body, (b) having to use server eof to define the end of the resource, and (c) incompatibility with keep-alive. e. Add some commented-out "##Log" calls for debugging. f. Define new variable state(log_size) purely for logging the cumulative size of the response body. And cf. commit 60eee5809f: a. Amend the command "handler" in tests/http11.test, to return an integer as required by http(n), and also not to check for eof which is the responsibility of http. This form of the test is appropriate for commits from 78b23edb6b onwards. IX. DETAILS OF COMMIT 8ac0b6f067 Bugfixes for occasional test failures, and improvements for (possibly hypothetical) cases. library/http/http.tcl 1. Modify http::init to use CloseSocket and clear old persistent-connection data gracefully. 2. Unset state(after) when its event is cancelled. 3. When the server sends "Connection: close", do not clear the queues in http::Event because they are needed if http::Finish is called with error status. Instead clear them in http::CloseQueuedQueries; also (in several places) use socketPlayCmd(*), socketClosing(*) instead of empty queues to signal that the socket will close. 4. Split http::Unset from http::CloseQueuedQueries and http::geturl. 5. In http::geturl and http::ReplayCore, initialize all socket*($state(socketinfo)) array elements. Test existence of the correct variables before setting traces. Reorder code so the new tests occur before setting the variables. 6. Re-sync http::geturl and http::ReplayCore. If socket cannot be opened, pass error message to http::Finish and set state(sock) NONE. Initialise socketPlayCmd(*) to same value. 7. Add Log calls to report code that appears to be rarely executed. 8. Improve Log messages in http::CancelReadPipeline http::CancelWritePipeline 9. Do not use "unset socketRdState(*)" as a "hard cancel" for queued tokens in http::ReplayIfDead; instead a "soft cancel" is provided in http::ReplayCore and http::ReInit, that does not set the state to indicate failure. The state(after) event is now cancelled in http::ReInit not http::ReplayIfDead. 10. Split http::ReInit from http::ReplayCore, and also apply it to queued tokens so they are re-initialized correctly. 11. Do not use argument skipCB when calling Finish from ReplayCore - this was copied in error from http::geturl. tests/httpPipeline.test Rename tests to distinguish them from tests in other files.
- http-files-82cbd9d626.tar.gz added by kjnash on 2018-09-20 13:49:46. [details]
- test-http-3.tcl added by kjnash on 2017-11-30 16:04:38. [details]