Issue #1308 new

testsuite failures X ~5; test_file_stream and 4 more in test_logging.py under pypy

idella5
created an issue

The first appears to fail for all python versions.

====================================================================
FAIL: test_file_stream (cherrypy.test.test_static.StaticTest)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-python2_7/cherrypy/test/test_static.py", line 259, in test_file_stream
    expected)
AssertionError: The file should have advanced to position 65536, but has already advanced to the end of the file. It may not be streamed as intended, or at the wrong chunk size (64k)
-------------------- >> begin captured logging << --------------------
cherrypy.access.22126352: INFO: 127.0.0.1 - - [30/Mar/2014:17:38:40] "GET /bigfile HTTP/1.1" 200 1048576 "" ""
cherrypy.access.22126352: INFO: 127.0.0.1 - - [30/Mar/2014:17:38:40] "GET /tell HTTP/1.1" 200 - "" ""
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
Ran 219 tests in 62.558s

FAILED (SKIP=2, failures=1)

The second only for py3.2, however it's also intermittent.

===================================================================
ERROR: test_HTTP11_pipelining (cherrypy.test.test_conn.PipelineTests)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-python3_2/cherrypy/test/test_conn.py", line 428, in test_HTTP11_pipelining
    response.begin()
  File "/usr/lib64/python3.2/http/client.py", line 346, in begin
    version, status, reason = self._read_status()
  File "/usr/lib64/python3.2/http/client.py", line 328, in _read_status
    raise BadStatusLine(line)
nose.proxy.BadStatusLine: Hello, world!
-------------------- >> begin captured logging << --------------------
cherrypy.access.140312555645200: INFO: 127.0.0.1 - - [30/Mar/2014:18:04:35] "GET /hello HTTP/1.1" 200 13 "" ""
cherrypy.access.140312555645200: INFO: 127.0.0.1 - - [30/Mar/2014:18:04:35] "GET /hello HTTP/1.1" 200 13 "" ""
cherrypy.access.140312555645200: INFO: 127.0.0.1 - - [30/Mar/2014:18:04:35] "GET /hello HTTP/1.1" 200 13 "" ""
cherrypy.access.140312555645200: INFO: 127.0.0.1 - - [30/Mar/2014:18:04:35] "GET /hello HTTP/1.1" 200 13 "" ""
cherrypy.access.140312555645200: INFO: 127.0.0.1 - - [30/Mar/2014:18:04:35] "GET /hello HTTP/1.1" 200 13 "" ""
cherrypy.access.140312555645200: INFO: 127.0.0.1 - - [30/Mar/2014:18:04:35] "GET /hello HTTP/1.1" 200 13 "" ""
--------------------- >> end captured logging << ---------------------

-----------------------------------------------------------
Ran 217 tests in 66.882s

FAILED (SKIP=2, errors=1)

These 4 occur under a run by pypy (2.0.1).

ERROR: testEscapedOutput (cherrypy.test.test_logging.AccessLogTests)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/test_logging.py", line 118, in testEscapedOutput
    self.assertLog(-1, repr(tartaros.encode('utf8'))[1:-1])
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/logtest.py", line 170, in assertLog
    if lines not in data[sliceargs]:
IndexError: list index out of range
-------------------- >> begin captured logging << --------------------
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Bus STOPPING
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('127.0.0.1', 54583)) shut down
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Stopped thread '_TimeoutMonitor'.
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Bus STOPPED
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Bus EXITING
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Bus EXITED
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Listening for SIGHUP.
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Listening for SIGTERM.
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Listening for SIGUSR1.
cherrypy.test.helper: INFO: Python version used to run this test script: 2.7.3
cherrypy.test.helper: INFO: CherryPy version: 3.2.2
cherrypy.test.helper: INFO: HTTP server version: HTTP/1.1
cherrypy.test.helper: INFO: PID: 25481
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Bus STARTING
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Started monitor thread '_TimeoutMonitor'.
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Serving on 127.0.0.1:54583
cherrypy.error: INFO: [30/Mar/2014:18:19:08] ENGINE Bus STARTED
cherrypy.access.76572952: INFO: \xce\x88\xcf\x81\xce\xb5\xce\xb2\xce\xbf\xcf\x82.com - \xce\xa4\xe1\xbd\xb1\xcf\x81\xcf\x84\xce\xb1\xcf\x81\xce\xbf\xcf\x82 [30/Mar/2014:18:19:08] "GET /uni_code HTTP/1.1" 200 - "" ""
--------------------- >> end captured logging << ---------------------

======================================================================
ERROR: testNormalReturn (cherrypy.test.test_logging.AccessLogTests)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/test_logging.py", line 82, in testNormalReturn
    self.assertLog(-1, intro)
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/logtest.py", line 170, in assertLog
    if lines not in data[sliceargs]:
IndexError: list index out of range
-------------------- >> begin captured logging << --------------------
cherrypy.access.76572952: INFO: 127.0.0.1 - - [30/Mar/2014:18:19:08] "GET /as_string HTTP/1.1" 200 7 "http://www.cherrypy.org/" "Mozilla/5.0"
--------------------- >> end captured logging << ---------------------

======================================================================
ERROR: testNormalYield (cherrypy.test.test_logging.AccessLogTests)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/test_logging.py", line 101, in testNormalYield
    self.assertLog(-1, intro)
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/logtest.py", line 170, in assertLog
    if lines not in data[sliceargs]:
IndexError: list index out of range
-------------------- >> begin captured logging << --------------------
cherrypy.access.76572952: INFO: 127.0.0.1 - - [30/Mar/2014:18:19:09] "GET /as_yield HTTP/1.1" 200 7 "" ""
--------------------- >> end captured logging << ---------------------

======================================================================
ERROR: testTracebacks (cherrypy.test.test_logging.ErrorLogTests)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/test_logging.py", line 153, in testTracebacks
    self.assertLog(0, 'HTTP Traceback (most recent call last):')
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/logtest.py", line 170, in assertLog
    if lines not in data[sliceargs]:
IndexError: list index out of range
-------------------- >> begin captured logging << --------------------
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Bus STOPPING
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('127.0.0.1', 54583)) shut down
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Stopped thread '_TimeoutMonitor'.
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Bus STOPPED
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Bus EXITING
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Bus EXITED
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Listening for SIGHUP.
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Listening for SIGTERM.
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Listening for SIGUSR1.
cherrypy.test.helper: INFO: Python version used to run this test script: 2.7.3
cherrypy.test.helper: INFO: CherryPy version: 3.2.2
cherrypy.test.helper: INFO: HTTP server version: HTTP/1.1
cherrypy.test.helper: INFO: PID: 25481
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Bus STARTING
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Started monitor thread '_TimeoutMonitor'.
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Serving on 127.0.0.1:54583
cherrypy.error: INFO: [30/Mar/2014:18:19:09] ENGINE Bus STARTED
cherrypy.error.68958480: ERROR: [30/Mar/2014:18:19:09] HTTP Traceback (most recent call last):
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/_cprequest.py", line 656, in respond
    response.body = self.handler()
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/lib/encoding.py", line 188, in __call__
    self.body = self.oldhandler(*args, **kwargs)
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/_cpdispatch.py", line 34, in __call__
    return self.callable(*self.args, **self.kwargs)
  File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.2.2-r1/work/CherryPy-3.2.2-pypy2_0/cherrypy/test/test_logging.py", line 51, in error
    raise ValueError()
ValueError

cherrypy.access.68958480: INFO: 127.0.0.1 - - [30/Mar/2014:18:19:09] "GET /error HTTP/1.1" 500 1562 "" ""
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
Ran 217 tests in 88.060s

FAILED (SKIP=2, errors=4)

Do you requite anything further?

Comments (2)

  1. idella5 reporter

    time moved on.

    cherrypy version moved onto -3.3.0.

    nose moved onto 1.3.3

    testsuite error list moved on.

    ======================================================================
    ERROR: testEscapedOutput (cherrypy.test.test_logging.AccessLogTests)
    ----------------------------------------------------------------------
    Traceback (most recent call last):
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/test_logging.py", line 117, in testEscapedOutput
        self.assertLog(-1, repr(tartaros.encode('utf8'))[1:-1])
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/logtest.py", line 177, in assertLog
        if lines not in data[sliceargs]:
    IndexError: list index out of range
    -------------------- >> begin captured logging << --------------------
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Bus STOPPING
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('127.0.0.1', 54583)) shut down
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Stopped thread '_TimeoutMonitor'.
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Bus STOPPED
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Bus EXITING
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Bus EXITED
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Listening for SIGHUP.
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Listening for SIGTERM.
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Listening for SIGUSR1.
    cherrypy.test.helper: INFO: Python version used to run this test script: 2.7.3
    cherrypy.test.helper: INFO: CherryPy version: 3.3.0
    cherrypy.test.helper: INFO: HTTP server version: HTTP/1.1
    cherrypy.test.helper: INFO: PID: 1494
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Bus STARTING
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Started monitor thread '_TimeoutMonitor'.
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Serving on http://127.0.0.1:54583
    cherrypy.error: INFO: [16/May/2014:19:53:12] ENGINE Bus STARTED
    cherrypy.access.61391608: INFO: \xce\x88\xcf\x81\xce\xb5\xce\xb2\xce\xbf\xcf\x82.com - \xce\xa4\xe1\xbd\xb1\xcf\x81\xcf\x84\xce\xb1\xcf\x81\xce\xbf\xcf\x82 [16/May/2014:19:53:12] "GET /uni_code HTTP/1.1" 200 - "" ""
    --------------------- >> end captured logging << ---------------------
    
    ======================================================================
    ERROR: testNormalReturn (cherrypy.test.test_logging.AccessLogTests)
    ----------------------------------------------------------------------
    Traceback (most recent call last):
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/test_logging.py", line 81, in testNormalReturn
        self.assertLog(-1, intro)
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/logtest.py", line 177, in assertLog
        if lines not in data[sliceargs]:
    IndexError: list index out of range
    -------------------- >> begin captured logging << --------------------
    cherrypy.access.61391608: INFO: 127.0.0.1 - - [16/May/2014:19:53:12] "GET /as_string HTTP/1.1" 200 7 "http://www.cherrypy.org/" "Mozilla/5.0"
    --------------------- >> end captured logging << ---------------------
    
    ======================================================================
    ERROR: testNormalYield (cherrypy.test.test_logging.AccessLogTests)
    ----------------------------------------------------------------------
    Traceback (most recent call last):
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/test_logging.py", line 100, in testNormalYield
        self.assertLog(-1, intro)
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/logtest.py", line 177, in assertLog
        if lines not in data[sliceargs]:
    IndexError: list index out of range
    -------------------- >> begin captured logging << --------------------
    cherrypy.access.61391608: INFO: 127.0.0.1 - - [16/May/2014:19:53:12] "GET /as_yield HTTP/1.1" 200 7 "" ""
    --------------------- >> end captured logging << ---------------------
    
    ======================================================================
    ERROR: testTracebacks (cherrypy.test.test_logging.ErrorLogTests)
    ----------------------------------------------------------------------
    Traceback (most recent call last):
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/test_logging.py", line 152, in testTracebacks
        self.assertLog(0, 'HTTP Traceback (most recent call last):')
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/logtest.py", line 177, in assertLog
        if lines not in data[sliceargs]:
    IndexError: list index out of range
    -------------------- >> begin captured logging << --------------------
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Bus STOPPING
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('127.0.0.1', 54583)) shut down
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Stopped thread '_TimeoutMonitor'.
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Bus STOPPED
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Bus EXITING
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Bus EXITED
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Listening for SIGHUP.
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Listening for SIGTERM.
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Listening for SIGUSR1.
    cherrypy.test.helper: INFO: Python version used to run this test script: 2.7.3
    cherrypy.test.helper: INFO: CherryPy version: 3.3.0
    cherrypy.test.helper: INFO: HTTP server version: HTTP/1.1
    cherrypy.test.helper: INFO: PID: 1494
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Bus STARTING
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Started monitor thread '_TimeoutMonitor'.
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Serving on http://127.0.0.1:54583
    cherrypy.error: INFO: [16/May/2014:19:53:13] ENGINE Bus STARTED
    cherrypy.error.60900368: ERROR: [16/May/2014:19:53:13] HTTP Traceback (most recent call last):
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/_cprequest.py", line 670, in respond
        response.body = self.handler()
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/lib/encoding.py", line 212, in __call__
        self.body = self.oldhandler(*args, **kwargs)
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/_cpdispatch.py", line 61, in __call__
        return self.callable(*self.args, **self.kwargs)
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/test_logging.py", line 51, in error
        raise ValueError()
    ValueError
    
    cherrypy.access.60900368: INFO: 127.0.0.1 - - [16/May/2014:19:53:13] "GET /error HTTP/1.1" 500 1556 "" ""
    --------------------- >> end captured logging << ---------------------
    
    ======================================================================
    FAIL: testStatic (cherrypy.test.test_static.StaticTest)
    ----------------------------------------------------------------------
    Traceback (most recent call last):
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/test_static.py", line 133, in testStatic
        self.assertBody('Hello, world\r\n')
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/webtest.py", line 404, in assertBody
        self._handlewebError(msg)
      File "/mnt/gen2/TmpDir/portage/dev-python/cherrypy-3.3.0/work/CherryPy-3.3.0-pypy/cherrypy/test/webtest.py", line 275, in _handlewebError
        raise self.failureException(msg)
    AssertionError: expected body:
    'Hello, world\r\n'
    
    actual body:
    ''
    -------------------- >> begin captured logging << --------------------
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Listening for SIGHUP.
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Listening for SIGTERM.
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Listening for SIGUSR1.
    cherrypy.test.helper: INFO: Python version used to run this test script: 2.7.3
    cherrypy.test.helper: INFO: CherryPy version: 3.3.0
    cherrypy.test.helper: INFO: HTTP server version: HTTP/1.1
    cherrypy.test.helper: INFO: PID: 1494
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Bus STARTING
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Started monitor thread '_TimeoutMonitor'.
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Serving on http://127.0.0.1:54583
    cherrypy.error: INFO: [16/May/2014:19:53:58] ENGINE Bus STARTED
    cherrypy.access.83588496: INFO: 127.0.0.1 - - [16/May/2014:19:53:58] "GET /static/index.html HTTP/1.1" 200 14 "" ""
    cherrypy.access.83588496: INFO: 127.0.0.1 - - [16/May/2014:19:53:58] "GET /docroot/index.html HTTP/1.1" 200 14 "" ""
    cherrypy.access.83588496: INFO: 127.0.0.1 - - [16/May/2014:19:53:58] "GET /static/has space.html HTTP/1.1" 200 - "" ""
    --------------------- >> end captured logging << ---------------------
    
    ----------------------------------------------------------------------
    Ran 222 tests in 95.372s
    
    FAILED (SKIP=2, errors=4, failures=1)
    
        ERROR: expected body:
    'Hello, world\r\n'
    
    actual body:
    ''
    
  2. Log in to comment