Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Regression test test_httpservers fails, hangs on Windows #64354

Closed
jeff5 mannequin opened this issue Jan 7, 2014 · 26 comments
Closed

Regression test test_httpservers fails, hangs on Windows #64354

jeff5 mannequin opened this issue Jan 7, 2014 · 26 comments
Labels
OS-windows stdlib Python modules in the Lib dir tests Tests in the Lib/test dir type-bug An unexpected behavior, bug, or error

Comments

@jeff5
Copy link
Mannequin

jeff5 mannequin commented Jan 7, 2014

BPO 20155
Nosy @terryjreedy, @orsenthil, @ezio-melotti, @bitdancer, @PCManticore, @jeff5
Files
  • issue20155_py.patch: Patch for 2.7
  • Note: these values reflect the state of the issue at the time it was migrated and might not reflect the current state.

    Show more details

    GitHub fields:

    assignee = None
    closed_at = <Date 2015-06-02.19:28:49.194>
    created_at = <Date 2014-01-07.00:19:11.362>
    labels = ['tests', 'type-bug', 'library', 'OS-windows']
    title = 'Regression test test_httpservers fails, hangs on Windows'
    updated_at = <Date 2015-06-02.19:28:49.192>
    user = 'https://github.com/jeff5'

    bugs.python.org fields:

    activity = <Date 2015-06-02.19:28:49.192>
    actor = 'r.david.murray'
    assignee = 'none'
    closed = True
    closed_date = <Date 2015-06-02.19:28:49.194>
    closer = 'r.david.murray'
    components = ['Library (Lib)', 'Tests', 'Windows']
    creation = <Date 2014-01-07.00:19:11.362>
    creator = 'jeff.allen'
    dependencies = []
    files = ['34202']
    hgrepos = []
    issue_num = 20155
    keywords = ['patch']
    message_count = 26.0
    messages = ['207497', '210926', '211990', '211999', '212004', '212026', '212652', '212754', '212755', '221458', '221498', '221499', '221803', '221858', '221859', '221860', '221862', '221863', '221866', '221893', '229576', '229580', '229661', '229662', '229666', '244686']
    nosy_count = 8.0
    nosy_names = ['terry.reedy', 'orsenthil', 'fwierzbicki', 'ezio.melotti', 'r.david.murray', 'Claudiu.Popa', 'python-dev', 'jeff.allen']
    pr_nums = []
    priority = 'normal'
    resolution = 'fixed'
    stage = 'resolved'
    status = 'closed'
    superseder = None
    type = 'behavior'
    url = 'https://bugs.python.org/issue20155'
    versions = ['Python 2.7', 'Python 3.3', 'Python 3.4']

    @jeff5
    Copy link
    Mannequin Author

    jeff5 mannequin commented Jan 7, 2014

    When I run:
    start python -m test.test_httpservers

    test_request_line_trimming reports ERROR, and the test hangs at test_version_none. If I run a copy of the test in which the latter test is skipped with @unittest.skipIf(sys.platform == "win32", "..."), the error report is:

    ======================================================================
    ERROR: test_request_line_trimming (main.BaseHTTPServerTestCase)
    ----------------------------------------------------------------------

    Traceback (most recent call last):
      File "test_httpservers.py", line 122, in test_request_line_trimming
        res = self.con.getresponse()
      File "C:\Python33\lib\http\client.py", line 1131, in getresponse
        response.begin()
      File "C:\Python33\lib\http\client.py", line 354, in begin
        version, status, reason = self._read_status()
      File "C:\Python33\lib\http\client.py", line 316, in _read_status
        line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
      File "C:\Python33\lib\socket.py", line 297, in readinto
        return self._sock.recv_into(b)
    ConnectionResetError: [WinError 10054] An existing connection was forcibly closed by the remote host

    This is essentially the same for Python 2.7.6 and for the current development tip of Jython 2.7b1+, which is actually where the problem first manifested.

    My machine is running 64-bit Windows 7 SP1, recently re-installed to a new, empty disk.

    Careful testing, elaborating the failing tests, shows that what is sent in PUT and GET operations is not quite what is received. Something tampers with the connection between the client and the server. (Identical traffic where the verbs are not PUT and GET arrives as sent.) Something fiddles with the forward message, for example "correcting" the spurious \n in test_request_line_trimming to a full \r\n, and holding back the payload of a PUT even when it was in the first packet. On the reverse path, it appears to act on the error response itself by closing the connection, without passing it to the client.

    Disabling the firewall (Windows Firewall and a commercial one), with the network cable unplugged, makes no difference. Nor does stopping anti-virus, anti-phishing, parental controls, etc.. However, stopping the Windows Basic Filtering Engine (BFE), makes the regression test run without error. Stopping the BFE takes out several dependent services, including Windows Firewall, but it seems likely the BFE itself is the culprit.

    Although the cause lies in the platform, not in Python, it seems to me still an "issue" for Python that the tests fail on a common platform practically out of the box. I'll work on this in the context of the Jython test and report back here.

    @jeff5 jeff5 mannequin added stdlib Python modules in the Lib dir OS-windows labels Jan 7, 2014
    @terryjreedy
    Copy link
    Member

    Installed 3.4.0c1 on Windows:
    test test_httpservers failed -- Traceback (most recent call last):
      File "C:\Programs\Python34.32\lib\test\test_httpservers.py", line 309, in test_invalid_requests
        self.check_status_and_reason(response, 501)
      File "C:\Programs\Python34.32\lib\test\test_httpservers.py", line 264, in check_status_and_reason
        self.assertEqual(response.status, status)
    AssertionError: 200 != 501

    I know very little about http server stuff.

    @PCManticore
    Copy link
    Mannequin

    PCManticore mannequin commented Feb 23, 2014

    Terry, I had the same problem with that failing test_httpservers.test_invalid_request using the latest build on Windows.
    After debugging, I found out that the problem was caused by my antivirus solution. Its http scanning engine caught malformed http requests, like the one in the test ("gEt / hTTP/1.0\r\nhost: 127.0.0.1:50340\r\nAccept-Encoding: identity\r\n\r\n"; for instance) and it modified the first line by uppercasing it, thus making the perfect condition for a failing test.

    @jeff5
    Copy link
    Mannequin Author

    jeff5 mannequin commented Feb 23, 2014

    Thanks for adding to the evidence here. As discussed above, disabling the security product (which is Bitdefender) on my PC didn't stop the problem for me, and I'm reluctant to uninstall. I narrowed it to the Windows Base Filtering Engine, but perhaps the behaviour of the BFE is extended by installing BD.

    If so, you could say this is not a Python problem, it is caused by BD "normalising" the HTTP. Or BD could say it is caused by expecting a defined result from abnormal HTTP.

    I took the view it were best fixed at our end. I found I could test the same thing (AFAICT), but modify the tests so they don't get interfered with.
    http://bugs.jython.org/issue2109
    http://hg.python.org/jython/rev/6441fcfd940b

    Would a patch made from this be applicable to CPython?

    @terryjreedy
    Copy link
    Member

    Except where specifically indicated otherwise, the Python test suite should be the same, and correct, for all implementations. If that change is correct for Jython, it should be correct for CPython.

    David, Ezio, or Senthil: does the change in the Jython patch look correct to any of you, enough that we should apply it?

    @terryjreedy terryjreedy added tests Tests in the Lib/test dir type-bug An unexpected behavior, bug, or error labels Feb 23, 2014
    @jeff5
    Copy link
    Mannequin Author

    jeff5 mannequin commented Feb 23, 2014

    Actual patch for your convenience. I'm not set up to build CPython from source, so I've tested this with my installed CPython 2.7.6, and it's clean.

    [As for keeping the tests in sync, yes that's our aim. Jython's Lib contains only the customised versions, and everything else comes from a copy of CPython's in lib-python/2.7. I'm always looking for a chance to delete one (i.e. use the common file).]

    @bitdancer
    Copy link
    Member

    The second test looks correct to me. I don't understand what tset_request_line_trimming is testing (I haven't investigated), so I don't know if that one is correct, but it is certainly plausible.

    @jeff5
    Copy link
    Mannequin Author

    jeff5 mannequin commented Mar 4, 2014

    I worked out that the essence of the test is to insert an extra \n at the end of a GET request line. The request is syntactically invalid for HTTP. The \n\r\n appears like two blank lines, implying no headers, but the headers then follow where no data should be. The server is supposed to respond with status 501, because it does not, in fact, define a GET operation.

    To find the replacement test plausible you have to accept that, with a server that doesn't define GET, the verb may as well be XYZBOGUS. Since the security filter doesn't understand that verb either (unlike GET), it doesn't interfere in the test.

    @bitdancer
    Copy link
    Member

    Ah, OK. I was thinking that BaseHTTPRequestHandler defined GET, but I see that it doesn't.

    So yes, this patch looks fine to me.

    @PCManticore
    Copy link
    Mannequin

    PCManticore mannequin commented Jun 24, 2014

    It would be nice if this could be committed. It's cumbersome to always have to deactivate the AV solution when running the tests on Windows, in order to avoid a failure of test_httpservers.

    @python-dev
    Copy link
    Mannequin

    python-dev mannequin commented Jun 24, 2014

    New changeset ffdd2d0b0049 by R David Murray in branch '3.4':
    bpo-20155: use fake HTTP method names so windows doesn't hang the tests.
    http://hg.python.org/cpython/rev/ffdd2d0b0049

    New changeset e67ad57eed26 by R David Murray in branch 'default':
    merge: bpo-20155: use fake HTTP method names so windows doesn't hang the tests.
    http://hg.python.org/cpython/rev/e67ad57eed26

    New changeset b0526da56c54 by R David Murray in branch '2.7':
    bpo-20155: use fake HTTP method names so windows doesn't hang the tests.
    http://hg.python.org/cpython/rev/b0526da56c54

    @bitdancer
    Copy link
    Member

    Done. Thanks Jeff.

    @terryjreedy
    Copy link
    Member

    After updating and rebuilding (32 bit VC express, on 64 bit Win 7), I get the same error as in msg210926, with or without -uall, even after turning my antivirus off.

    File "F:\Python\dev\4\py34\lib\test\test_httpservers.py", line 310, in test_invalid_requests
    self.check_status_and_reason(response, 501)
    File "F:\Python\dev\4\py34\lib\test\test_httpservers.py", line 265, in check_status_and_reason
    self.assertEqual(response.status, status)
    AssertionError: 200 != 501

    Is this a failure of the patch or a different issue?

    @bitdancer
    Copy link
    Member

    If deactivating the AV/firewall doesn't change the behavior, it is presumably a different problem. But let's see what Claudiu has to say, since he was able to reproduce and then circumvent the original problem.

    @PCManticore
    Copy link
    Mannequin

    PCManticore mannequin commented Jun 29, 2014

    Terry is right, this patch doesn't completely work. But with this fix the problem is solved:

    diff -r 394e6bda5a70 Lib/test/test_httpservers.py
    --- a/Lib/test/test_httpservers.py      Sun Jun 29 15:56:21 2014 +0300
    +++ b/Lib/test/test_httpservers.py      Sun Jun 29 19:27:16 2014 +0300
    @@ -306,7 +306,7 @@
             response = self.request('/', method='FOO')
             self.check_status_and_reason(response, 501)
             # requests must be case sensitive,so this should fail too
    -        response = self.request('/', method='get')
    +        response = self.request('/', method='gets')
             self.check_status_and_reason(response, 501)
             response = self.request('/', method='GETs')
             self.check_status_and_reason(response, 501)

    @PCManticore
    Copy link
    Mannequin

    PCManticore mannequin commented Jun 29, 2014

    Although it is incorrect, because the test specifically tests case sensitivity.

    @bitdancer
    Copy link
    Member

    What if you use 'custom' instead?

    @bitdancer bitdancer reopened this Jun 29, 2014
    @PCManticore
    Copy link
    Mannequin

    PCManticore mannequin commented Jun 29, 2014

    It works with 'custom'.

    @terryjreedy
    Copy link
    Member

    Both 'gets' and 'custom' work on my machine.

    @jeff5
    Copy link
    Mannequin Author

    jeff5 mannequin commented Jun 29, 2014

    Disabling the AV/firewall did not stop the symptoms when I was investigating originally. In order to get the unmodified test to pass, I had to stop the BFE (base filtering engine), which I think may have been given new rules or behaviours as a result of installing the AV solution ... or maybe it was a Windows upgrade that did it.

    I did wonder if this might be a moving target, as the test deliberately includes server abuse, while the products want to stop that.

    If I try test_httpservers.py as amended (http://hg.python.org/cpython/file/ffdd2d0b0049/Lib/test/test_httpservers.py) on my machine with CPython 3.4.1, I do not get the error Terry reports. (test_urlquote_decoding_in_cgi_check fails but it should.)

    @terryjreedy
    Copy link
    Member

    If Python itself works correctly on a machine, it is a bug for a test to say that is it not, by failing. Should we change

    •    response = self.request('/', method='get')
      

    to
    + response = self.request('/', method='gets')
    or
    + response = self.request('/', method='custom')

    or conditionally, on type(status), change the test from assertEqual to (after substitutions) assertIn(response.status, (200,501))? David, do you have a preference? or should I flip a coin?

    @bitdancer
    Copy link
    Member

    It should be 'custom', since that will actually test case sensitivity, which 'gets' would not.

    @python-dev
    Copy link
    Mannequin

    python-dev mannequin commented Oct 18, 2014

    New changeset 3ffa43e8ab47 by Terry Jan Reedy in branch '2.7':
    Issue bpo-20155: Fix non-buildbot test failure on Windows. Patch by Claudiu Popa,
    https://hg.python.org/cpython/rev/3ffa43e8ab47

    New changeset 5c0f17063fb8 by Terry Jan Reedy in branch '3.4':
    Issue bpo-20155: Fix non-buildbot test failure on Windows. Patch by Claudiu Popa,
    https://hg.python.org/cpython/rev/5c0f17063fb8

    @terryjreedy
    Copy link
    Member

    Patch fixes failures for me. Let's hope that change does not fail on other systems or buildbots (starting at 21:17 bb time). I will check bbs later.

    There seems to be a 2.7 speed issue which looks like a hang until running with -v. On my machine, 2.7 runs 34 tests 40 seconds (consistently), with test_get taking 6 seconds. 3.4 runs 45 tests in under 3 seconds, a 15x speedup. I attached difflib.Differ output. David, can you see anything responsible for the slowdown. Should this be ignored?

    @terryjreedy
    Copy link
    Member

    I copied the example profile code at the top and bottom of test_get and nearly all (7.155 / 7.210) of the time is in _socket.socket.connect, which takes .5 sec per call.
    14 7.155 0.511 7.155 0.511 {method 'connect' of _socket.socket' objects}
    I am guessing that socket, and thence _socket, is imported in HTTPServer. I tested whether this diff in class TestServerThread is responsible

    •     self.server = HTTPServer(('', 0), self.request_handler)
      

    + self.server = HTTPServer(('localhost', 0), self.request_handler)
    but it is not. Anyone else have other ideas?

    @bitdancer
    Copy link
    Member

    Terry: the slowdown might be IPV6 handling, which was a bit broken on windows.

    In any case, I think the slowdown should be treated as a separate issue if you care about it, so I'm going to close this. (I think it would be great if someone would do a review of the test suite looking for things to speed up...maybe we could add that to the devguide as a what-can-I-work-on project...)

    @ezio-melotti ezio-melotti transferred this issue from another repository Apr 10, 2022
    Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
    Labels
    OS-windows stdlib Python modules in the Lib dir tests Tests in the Lib/test dir type-bug An unexpected behavior, bug, or error
    Projects
    None yet
    Development

    No branches or pull requests

    2 participants