You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@trafficserver.apache.org by je...@ci.trafficserver.apache.org on 2015/10/22 00:16:53 UTC

Build failed in Jenkins: tsqa-master #943

See <https://ci.trafficserver.apache.org/job/tsqa-master/943/>

------------------------------------------
[...truncated 493 lines...]
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-21 22:06:57,333 - Environment prefix is /tmp/tsqa.env.JuwliH
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-21 22:08:41,013 - Environment prefix is /tmp/tsqa.env.tHc_Os
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-21 22:09:12,841 - Starting build (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-21 22:11:08,416 - Build completed (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-21 22:11:08,476 - Environment prefix is /tmp/tsqa.env.EBEjjz
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-21 22:11:11,984 - Environment prefix is /tmp/tsqa.env.8QHtnU
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [21/Oct/2015 22:11:15] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-21 22:11:15,412 - Environment prefix is /tmp/tsqa.env.pydA64
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-21 22:11:28,911 - Environment prefix is /tmp/tsqa.env.YeeDty
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:11:32] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-21 22:11:32,366 - Environment prefix is /tmp/tsqa.env.eY1epD
INFO 2015-10-21 22:11:32,368 - map / http://127.0.0.1:34987/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-21 22:11:35,708 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 21 Oct 2015 22:11:35 GMT
Age: 0
Connection: close


INFO 2015-10-21 22:11:35,709 - head request with case(TE) costs 0.001864 seconds while the timout is 5.000000 seconds.
INFO 2015-10-21 22:11:35,710 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Wed, 21 Oct 2015 22:11:35 GMT
Age: 0
Connection: close


INFO 2015-10-21 22:11:35,710 - head request with case(CL) costs 0.001076 seconds while the timout is 5.000000 seconds.
INFO 2015-10-21 22:11:35,726 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 21 Oct 2015 22:11:35 GMT
Age: 0
Connection: close


INFO 2015-10-21 22:11:35,726 - head request with case() costs 0.016130 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-21 22:11:35,908 - Environment prefix is /tmp/tsqa.env.pZkRbe
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-21 22:11:39,363 - Environment prefix is /tmp/tsqa.env.hnfTIV
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-21 22:11:44,892 - Environment prefix is /tmp/tsqa.env.mOJ4zE
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-21 22:11:54,380 - Environment prefix is /tmp/tsqa.env.x2RXPk
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.x2RXPk
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP: Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-21 22:11:54,459 - Environment prefix is /tmp/tsqa.env.rhDLFm
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.rhDLFm
--------------------- >> end captured logging << ---------------------
INFO 2015-10-21 22:11:54,551 - Environment prefix is /tmp/tsqa.env.v1qm0X
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-21 22:11:58,174 - Environment prefix is /tmp/tsqa.env.rXjW61
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-21 22:12:01,853 - Environment prefix is /tmp/tsqa.env.EbM25w
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-21 22:12:05,405 - Environment prefix is /tmp/tsqa.env.5ZOX2Y
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session ... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:12:08] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-21 22:12:08,830 - Environment prefix is /tmp/tsqa.env.6doPFQ
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-21 22:12:15,304 - Environment prefix is /tmp/tsqa.env.qYH0l1
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-21 22:12:21,748 - Environment prefix is /tmp/tsqa.env.xe15WE
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-21 22:12:43,291 - Environment prefix is /tmp/tsqa.env.IXBANd
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-21 22:13:04,847 - Environment prefix is /tmp/tsqa.env.cgmq3l
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-21 22:13:08,338 - Environment prefix is /tmp/tsqa.env.BkyMVV
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-21 22:13:11,848 - Environment prefix is /tmp/tsqa.env.zC0Yrb
INFO 2015-10-21 22:13:11,850 - socket_server_port = 33400
INFO 2015-10-21 22:13:11,850 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-21 22:13:15,144 - Sending data back to the client: 29f9fae5ac5d4c139217218f4d88fb34
INFO 2015-10-21 22:13:15,145 - 29f9fae5ac5d4c139217218f4d88fb34
INFO 2015-10-21 22:13:20,148 - Sending data back to the client: 29f9fae5ac5d4c139217218f4d88fb34
INFO 2015-10-21 22:13:20,149 - 29f9fae5ac5d4c139217218f4d88fb34
ok
INFO 2015-10-21 22:13:20,337 - Environment prefix is /tmp/tsqa.env.hffHG2
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [21/Oct/2015 22:13:23] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-21 22:13:23,805 - Environment prefix is /tmp/tsqa.env.TuxH6L
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-21 22:15:58,906 - Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-ccache', '--enable-experimental-plugins', '--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio', '--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-21 22:16:08,930 - Environment prefix is /tmp/tsqa.env.TLB1qz
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [21/Oct/2015 22:16:12] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:16:12] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:16:12] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-21 22:16:12,466 - Environment prefix is /tmp/tsqa.env.V3ME0H
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [21/Oct/2015 22:16:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:16:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Oct/2015 22:16:15] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-21 22:16:16,018 - Environment prefix is /tmp/tsqa.env.aIOK91
INFO 2015-10-21 22:16:16,020 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-21 22:16:16,020 - spdy server port = 55514
INFO 2015-10-21 22:16:16,020 - http server port = 44298
Test that the origin does in fact support spdy 3 ... INFO 2015-10-21 22:16:19,250 - full args = ['/usr/local/bin/spdycat', 'https://localhost:55514/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-21 22:16:19,692 - full args = ['/usr/local/bin/spdycat', 'https://localhost:44298/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-21 22:16:19,895 - Environment prefix is /tmp/tsqa.env.A1_3J0
INFO 2015-10-21 22:16:19,897 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-21 22:16:19,897 - spdy server port = 52064
INFO 2015-10-21 22:16:19,897 - http server port = 59963
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-21 22:16:23,138 - full args = ['/usr/local/bin/spdycat', 'https://localhost:52064/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-21 22:16:23,504 - full args = ['/usr/local/bin/spdycat', 'https://localhost:59963/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-21 22:16:23,703 - Environment prefix is /tmp/tsqa.env.AzZz9X
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok

======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py",> line 198, in test_chunked_bad_close
    requests.get(url, proxies=self.proxies, timeout=2)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 69, in get
    return request('get', url, params=params, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 50, in request
    response = session.request(method=method, url=url, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 468, in request
    resp = self.send(prep, **send_kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 608, in send
    r.content
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 734, in content
    self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes()
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 664, in generate
    raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=45759): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.hv_B2y
test_chunked: INFO: sending data back to the client
test_chunked: INFO: Client disconnected
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 1243.232s

FAILED (SKIP=8, errors=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results

Build failed in Jenkins: tsqa-master #948

Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/948/changes>

Changes:

[James Peach] TS-3982: ESI logs debug output using TSError

[Bryan Call] TS-3961: Open source Yahoo's ats-multiplexer plug-in

------------------------------------------
[...truncated 498 lines...]
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-26 18:06:53,297 - Environment prefix is /tmp/tsqa.env.UnM0RM
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 18:08:36,845 - Environment prefix is /tmp/tsqa.env.FgVZtc
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-26 18:09:07,504 - Starting build (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-26 18:10:03,151 - Build completed (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-26 18:10:03,220 - Environment prefix is /tmp/tsqa.env.Q0OcEG
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 18:10:06,630 - Environment prefix is /tmp/tsqa.env.Hz5ru_
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [26/Oct/2015 18:10:09] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 18:10:10,059 - Environment prefix is /tmp/tsqa.env.DD0hPj
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 18:10:23,544 - Environment prefix is /tmp/tsqa.env.s46841
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:10:26] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 18:10:26,991 - Environment prefix is /tmp/tsqa.env.c4NAZC
INFO 2015-10-26 18:10:26,993 - map / http://127.0.0.1:50687/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26 18:10:30,218 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 18:10:30 GMT
Age: 0
Connection: close


INFO 2015-10-26 18:10:30,219 - head request with case(TE) costs 0.001767 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 18:10:30,220 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 18:10:30 GMT
Age: 0
Connection: close


INFO 2015-10-26 18:10:30,220 - head request with case(CL) costs 0.001105 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 18:10:30,221 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 18:10:30 GMT
Age: 0
Connection: close


INFO 2015-10-26 18:10:30,221 - head request with case() costs 0.000945 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 18:10:30,406 - Environment prefix is /tmp/tsqa.env.C5dXYZ
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 18:10:33,864 - Environment prefix is /tmp/tsqa.env.tFZTAX
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 18:10:39,294 - Environment prefix is /tmp/tsqa.env.RhSDci
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 18:10:48,774 - Environment prefix is /tmp/tsqa.env.jBS141
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.jBS141
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP: Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-26 18:10:48,862 - Environment prefix is /tmp/tsqa.env.qGcdbH
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.qGcdbH
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 18:10:48,966 - Environment prefix is /tmp/tsqa.env.DJg7ng
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-26 18:10:52,482 - Environment prefix is /tmp/tsqa.env.mIiXAH
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-26 18:10:56,032 - Environment prefix is /tmp/tsqa.env.50Fos0
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-26 18:10:59,587 - Environment prefix is /tmp/tsqa.env.UM0VUz
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session ... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:11:02] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 18:11:03,063 - Environment prefix is /tmp/tsqa.env.7DJ2y6
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 18:11:09,519 - Environment prefix is /tmp/tsqa.env.HNP2SO
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 18:11:15,952 - Environment prefix is /tmp/tsqa.env.EeiWRd
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-26 18:11:37,453 - Environment prefix is /tmp/tsqa.env.0X0Gu7
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-26 18:11:58,964 - Environment prefix is /tmp/tsqa.env.LumYzv
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-26 18:12:02,425 - Environment prefix is /tmp/tsqa.env.MJ84uI
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-26 18:12:05,934 - Environment prefix is /tmp/tsqa.env.hEZ50s
INFO 2015-10-26 18:12:05,936 - socket_server_port = 33833
INFO 2015-10-26 18:12:05,936 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-26 18:12:09,174 - Sending data back to the client: 74954215ab93450caec3d8720f4e8a75
INFO 2015-10-26 18:12:09,174 - 74954215ab93450caec3d8720f4e8a75
INFO 2015-10-26 18:12:14,180 - Sending data back to the client: 74954215ab93450caec3d8720f4e8a75
INFO 2015-10-26 18:12:14,180 - 74954215ab93450caec3d8720f4e8a75
ok
INFO 2015-10-26 18:12:14,364 - Environment prefix is /tmp/tsqa.env.itv2hB
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 18:12:17] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 18:12:17,923 - Environment prefix is /tmp/tsqa.env.KZrtUG
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 18:14:52,012 - Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-ccache', '--enable-experimental-plugins', '--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio', '--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 18:15:01,511 - Environment prefix is /tmp/tsqa.env.gJD3_f
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015 18:15:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:15:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:15:04] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 18:15:05,005 - Environment prefix is /tmp/tsqa.env.Z8HCsY
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015 18:15:08] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:15:08] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 18:15:08] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 18:15:08,470 - Environment prefix is /tmp/tsqa.env.3tGDEj
INFO 2015-10-26 18:15:08,472 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 18:15:08,472 - spdy server port = 59205
INFO 2015-10-26 18:15:08,472 - http server port = 60921
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26 18:15:11,708 - full args = ['/usr/local/bin/spdycat', 'https://localhost:59205/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 18:15:13,315 - full args = ['/usr/local/bin/spdycat', 'https://localhost:60921/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 18:15:13,520 - Environment prefix is /tmp/tsqa.env.dRE8x8
INFO 2015-10-26 18:15:13,522 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 18:15:13,522 - spdy server port = 35241
INFO 2015-10-26 18:15:13,523 - http server port = 59702
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26 18:15:16,759 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35241/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 18:15:17,765 - full args = ['/usr/local/bin/spdycat', 'https://localhost:59702/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 18:15:17,971 - Environment prefix is /tmp/tsqa.env.zxZNAy
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok

======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py",> line 198, in test_chunked_bad_close
    requests.get(url, proxies=self.proxies, timeout=2)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 69, in get
    return request('get', url, params=params, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 50, in request
    response = session.request(method=method, url=url, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 468, in request
    resp = self.send(prep, **send_kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 608, in send
    r.content
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 734, in content
    self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes()
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 664, in generate
    raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=35259): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.oooivQ
test_chunked: INFO: sending data back to the client
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 938.491s

FAILED (SKIP=8, errors=1)
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results

Build failed in Jenkins: tsqa-master #947

Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/947/changes>

Changes:

[James Peach] format

------------------------------------------
[...truncated 495 lines...]
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-25 17:06:22,159 - Environment prefix is /tmp/tsqa.env.oq1sKS
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-25 17:08:05,687 - Environment prefix is /tmp/tsqa.env._dsXya
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-25 17:08:36,119 - Starting build (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-25 17:09:30,075 - Build completed (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-25 17:09:30,144 - Environment prefix is /tmp/tsqa.env.xZqvhC
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-25 17:09:33,550 - Environment prefix is /tmp/tsqa.env.qpCbOz
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [25/Oct/2015 17:09:36] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-25 17:09:36,973 - Environment prefix is /tmp/tsqa.env.OMpLy3
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-25 17:09:50,435 - Environment prefix is /tmp/tsqa.env.ncmFNl
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:09:53] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-25 17:09:53,919 - Environment prefix is /tmp/tsqa.env.TQtiuY
INFO 2015-10-25 17:09:53,921 - map / http://127.0.0.1:57917/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-25 17:09:57,153 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Sun, 25 Oct 2015 17:09:57 GMT
Age: 0
Connection: close


INFO 2015-10-25 17:09:57,154 - head request with case(TE) costs 0.007537 seconds while the timout is 5.000000 seconds.
INFO 2015-10-25 17:09:57,155 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Sun, 25 Oct 2015 17:09:57 GMT
Age: 0
Connection: close


INFO 2015-10-25 17:09:57,155 - head request with case(CL) costs 0.001277 seconds while the timout is 5.000000 seconds.
INFO 2015-10-25 17:09:57,173 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Sun, 25 Oct 2015 17:09:57 GMT
Age: 0
Connection: close


INFO 2015-10-25 17:09:57,174 - head request with case() costs 0.018263 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-25 17:09:57,357 - Environment prefix is /tmp/tsqa.env.bKiWYk
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-25 17:10:00,820 - Environment prefix is /tmp/tsqa.env.8w10jV
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-25 17:10:06,373 - Environment prefix is /tmp/tsqa.env.6WyhA_
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-25 17:10:15,861 - Environment prefix is /tmp/tsqa.env.VE_lOn
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.VE_lOn
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP: Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-25 17:10:15,952 - Environment prefix is /tmp/tsqa.env.GqwKW3
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.GqwKW3
--------------------- >> end captured logging << ---------------------
INFO 2015-10-25 17:10:16,056 - Environment prefix is /tmp/tsqa.env.oyl6rQ
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-25 17:10:19,691 - Environment prefix is /tmp/tsqa.env.9mHov7
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-25 17:10:23,344 - Environment prefix is /tmp/tsqa.env.S3jKJw
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-25 17:10:26,881 - Environment prefix is /tmp/tsqa.env.jFwvto
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session ... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:10:30] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-25 17:10:30,312 - Environment prefix is /tmp/tsqa.env.5yqwVh
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-25 17:10:36,799 - Environment prefix is /tmp/tsqa.env.nadr5e
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-25 17:10:43,237 - Environment prefix is /tmp/tsqa.env.fNNAnp
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-25 17:11:04,726 - Environment prefix is /tmp/tsqa.env.8ZHwPq
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-25 17:11:26,312 - Environment prefix is /tmp/tsqa.env.3iydvx
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-25 17:11:29,810 - Environment prefix is /tmp/tsqa.env.D5Pmv9
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-25 17:11:33,261 - Environment prefix is /tmp/tsqa.env.x4D09j
INFO 2015-10-25 17:11:33,263 - socket_server_port = 43828
INFO 2015-10-25 17:11:33,263 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-25 17:11:36,489 - Sending data back to the client: 7c9f34fc1da743518383a3c978975dab
INFO 2015-10-25 17:11:36,490 - 7c9f34fc1da743518383a3c978975dab
INFO 2015-10-25 17:11:41,495 - Sending data back to the client: 7c9f34fc1da743518383a3c978975dab
INFO 2015-10-25 17:11:41,496 - 7c9f34fc1da743518383a3c978975dab
ok
INFO 2015-10-25 17:11:41,682 - Environment prefix is /tmp/tsqa.env.7OvrOV
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [25/Oct/2015 17:11:45] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-25 17:11:45,265 - Environment prefix is /tmp/tsqa.env.hlcpni
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-25 17:14:18,991 - Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-ccache', '--enable-experimental-plugins', '--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio', '--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-25 17:14:28,421 - Environment prefix is /tmp/tsqa.env.pRgtuN
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [25/Oct/2015 17:14:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:14:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:14:31] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-25 17:14:31,959 - Environment prefix is /tmp/tsqa.env.30fnxu
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [25/Oct/2015 17:14:35] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:14:35] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [25/Oct/2015 17:14:35] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-25 17:14:35,435 - Environment prefix is /tmp/tsqa.env.TdFGWv
INFO 2015-10-25 17:14:35,437 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-25 17:14:35,437 - spdy server port = 56998
INFO 2015-10-25 17:14:35,437 - http server port = 60654
Test that the origin does in fact support spdy 3 ... INFO 2015-10-25 17:14:38,664 - full args = ['/usr/local/bin/spdycat', 'https://localhost:56998/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-25 17:14:39,908 - full args = ['/usr/local/bin/spdycat', 'https://localhost:60654/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-25 17:14:40,112 - Environment prefix is /tmp/tsqa.env.xS4tmF
INFO 2015-10-25 17:14:40,114 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-25 17:14:40,114 - spdy server port = 37891
INFO 2015-10-25 17:14:40,114 - http server port = 57643
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-25 17:14:43,341 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37891/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-25 17:14:44,687 - full args = ['/usr/local/bin/spdycat', 'https://localhost:57643/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-25 17:14:44,891 - Environment prefix is /tmp/tsqa.env.a2t5Zo
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok

======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py",> line 198, in test_chunked_bad_close
    requests.get(url, proxies=self.proxies, timeout=2)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 69, in get
    return request('get', url, params=params, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 50, in request
    response = session.request(method=method, url=url, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 468, in request
    resp = self.send(prep, **send_kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 608, in send
    r.content
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 734, in content
    self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes()
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 664, in generate
    raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=57703): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.dIAGth
test_chunked: INFO: sending data back to the client
test_chunked: INFO: Client disconnected
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 931.022s

FAILED (SKIP=8, errors=1)
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results

Build failed in Jenkins: tsqa-master #946

Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/946/>

------------------------------------------
[...truncated 496 lines...]
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-24 18:50:03,622 - Environment prefix is /tmp/tsqa.env.sN7aoP
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-24 18:51:47,267 - Environment prefix is /tmp/tsqa.env.a3RO6D
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-24 18:52:18,653 - Starting build (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-24 18:54:37,494 - Build completed (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-24 18:54:37,556 - Environment prefix is /tmp/tsqa.env.lFIUXe
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-24 18:54:41,074 - Environment prefix is /tmp/tsqa.env.YwiI4d
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [24/Oct/2015 18:54:44] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-24 18:54:44,617 - Environment prefix is /tmp/tsqa.env.Dy37Gp
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-24 18:54:58,184 - Environment prefix is /tmp/tsqa.env.2lPDTQ
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:01] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-24 18:55:01,736 - Environment prefix is /tmp/tsqa.env.9legie
INFO 2015-10-24 18:55:01,738 - map / http://127.0.0.1:53367/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-24 18:55:04,975 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Sat, 24 Oct 2015 18:55:04 GMT
Age: 0
Connection: close


INFO 2015-10-24 18:55:04,976 - head request with case(TE) costs 0.010762 seconds while the timout is 5.000000 seconds.
INFO 2015-10-24 18:55:04,988 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Sat, 24 Oct 2015 18:55:04 GMT
Age: 0
Connection: close


INFO 2015-10-24 18:55:04,989 - head request with case(CL) costs 0.012886 seconds while the timout is 5.000000 seconds.
INFO 2015-10-24 18:55:04,990 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Sat, 24 Oct 2015 18:55:04 GMT
Age: 0
Connection: close


INFO 2015-10-24 18:55:04,990 - head request with case() costs 0.001312 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-24 18:55:05,173 - Environment prefix is /tmp/tsqa.env.SBXTyS
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-24 18:55:08,743 - Environment prefix is /tmp/tsqa.env.avMLE6
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-24 18:55:14,286 - Environment prefix is /tmp/tsqa.env.HfXDz8
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-24 18:55:23,876 - Environment prefix is /tmp/tsqa.env.6gL2Ts
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.6gL2Ts
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP: Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-24 18:55:23,958 - Environment prefix is /tmp/tsqa.env.aeuir6
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.aeuir6
--------------------- >> end captured logging << ---------------------
INFO 2015-10-24 18:55:24,065 - Environment prefix is /tmp/tsqa.env.DNjTd6
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-24 18:55:27,696 - Environment prefix is /tmp/tsqa.env.CbwCgO
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-24 18:55:31,363 - Environment prefix is /tmp/tsqa.env.avYv2y
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-24 18:55:35,008 - Environment prefix is /tmp/tsqa.env.p2Va2q
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session ... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:55:38] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-24 18:55:38,465 - Environment prefix is /tmp/tsqa.env.98PiOt
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-24 18:55:44,956 - Environment prefix is /tmp/tsqa.env.7EVfyt
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-24 18:55:51,461 - Environment prefix is /tmp/tsqa.env.gBaERY
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-24 18:56:12,961 - Environment prefix is /tmp/tsqa.env.zyoCRI
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-24 18:56:34,552 - Environment prefix is /tmp/tsqa.env.Ny1Bo4
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-24 18:56:38,046 - Environment prefix is /tmp/tsqa.env.Yn4x20
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-24 18:56:41,616 - Environment prefix is /tmp/tsqa.env.WQu4y4
INFO 2015-10-24 18:56:41,618 - socket_server_port = 33229
INFO 2015-10-24 18:56:41,619 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-24 18:56:44,957 - Sending data back to the client: 19b1436fad9148949ffc5703159c1cac
INFO 2015-10-24 18:56:44,958 - 19b1436fad9148949ffc5703159c1cac
INFO 2015-10-24 18:56:49,964 - Sending data back to the client: 19b1436fad9148949ffc5703159c1cac
INFO 2015-10-24 18:56:49,965 - 19b1436fad9148949ffc5703159c1cac
ok
INFO 2015-10-24 18:56:50,145 - Environment prefix is /tmp/tsqa.env._WFiis
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:53] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-24 18:56:53,771 - Environment prefix is /tmp/tsqa.env.bBkkuv
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-24 18:59:30,898 - Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-ccache', '--enable-experimental-plugins', '--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio', '--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-24 18:59:40,892 - Environment prefix is /tmp/tsqa.env.eXfXoO
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [24/Oct/2015 18:59:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:44] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-24 18:59:44,365 - Environment prefix is /tmp/tsqa.env.stpdH5
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [24/Oct/2015 18:59:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:47] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-24 18:59:47,844 - Environment prefix is /tmp/tsqa.env.LVow8i
INFO 2015-10-24 18:59:47,846 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-24 18:59:47,846 - spdy server port = 45066
INFO 2015-10-24 18:59:47,846 - http server port = 54606
Test that the origin does in fact support spdy 3 ... INFO 2015-10-24 18:59:51,078 - full args = ['/usr/local/bin/spdycat', 'https://localhost:45066/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-24 18:59:51,503 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54606/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-24 18:59:51,718 - Environment prefix is /tmp/tsqa.env.7hFJC_
INFO 2015-10-24 18:59:51,720 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-24 18:59:51,720 - spdy server port = 57077
INFO 2015-10-24 18:59:51,720 - http server port = 55880
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-24 18:59:54,964 - full args = ['/usr/local/bin/spdycat', 'https://localhost:57077/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-24 18:59:55,355 - full args = ['/usr/local/bin/spdycat', 'https://localhost:55880/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-24 18:59:55,558 - Environment prefix is /tmp/tsqa.env.PIHTJM
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok

======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py",> line 198, in test_chunked_bad_close
    requests.get(url, proxies=self.proxies, timeout=2)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 69, in get
    return request('get', url, params=params, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 50, in request
    response = session.request(method=method, url=url, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 468, in request
    resp = self.send(prep, **send_kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 608, in send
    r.content
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 734, in content
    self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes()
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 664, in generate
    raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=49609): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.ZewF5T
test_chunked: INFO: sending data back to the client
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 1360.653s

FAILED (SKIP=8, errors=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results

Build failed in Jenkins: tsqa-master #945

Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/945/changes>

Changes:

[Sudheer Vinukonda] [TS-3979] Deprecate proxy.config.http.cache.allow_empty_doc.

[James Peach] mgmt: use mgmt_fatal rather than a raw assert()

[James Peach] ssl: consistent certificate expiry error messages

[James Peach] ssl: don't trigger a reload on proxy.config.ssl.server.multicert.exit_on_load_fail changes

------------------------------------------
[...truncated 491 lines...]
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-24 18:49:15,732 - Environment prefix is /tmp/tsqa.env.f1q00E
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-24 18:50:59,289 - Environment prefix is /tmp/tsqa.env.nx7_Dp
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-24 18:51:29,899 - Starting build (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-24 18:53:46,452 - Build completed (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-24 18:53:46,521 - Environment prefix is /tmp/tsqa.env.aI6V93
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-24 18:53:49,932 - Environment prefix is /tmp/tsqa.env.v2xpr2
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [24/Oct/2015 18:53:53] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-24 18:53:53,352 - Environment prefix is /tmp/tsqa.env.vIXKOT
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-24 18:54:06,936 - Environment prefix is /tmp/tsqa.env.Dzazlx
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:10] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-24 18:54:10,442 - Environment prefix is /tmp/tsqa.env.UmfaWN
INFO 2015-10-24 18:54:10,444 - map / http://127.0.0.1:44914/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-24 18:54:13,669 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Sat, 24 Oct 2015 18:54:13 GMT
Age: 0
Connection: close


INFO 2015-10-24 18:54:13,669 - head request with case(TE) costs 0.002296 seconds while the timout is 5.000000 seconds.
INFO 2015-10-24 18:54:13,687 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Sat, 24 Oct 2015 18:54:13 GMT
Age: 0
Connection: close


INFO 2015-10-24 18:54:13,688 - head request with case(CL) costs 0.018173 seconds while the timout is 5.000000 seconds.
INFO 2015-10-24 18:54:13,689 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Sat, 24 Oct 2015 18:54:13 GMT
Age: 0
Connection: close


INFO 2015-10-24 18:54:13,690 - head request with case() costs 0.001640 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-24 18:54:13,881 - Environment prefix is /tmp/tsqa.env.2dyv92
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-24 18:54:17,355 - Environment prefix is /tmp/tsqa.env.P19Ho_
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-24 18:54:22,798 - Environment prefix is /tmp/tsqa.env.J7oqM9
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-24 18:54:32,299 - Environment prefix is /tmp/tsqa.env.6_5iJ8
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.6_5iJ8
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP: Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-24 18:54:32,390 - Environment prefix is /tmp/tsqa.env.QR2WMn
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.QR2WMn
--------------------- >> end captured logging << ---------------------
INFO 2015-10-24 18:54:32,501 - Environment prefix is /tmp/tsqa.env.P1jcpx
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-24 18:54:36,047 - Environment prefix is /tmp/tsqa.env.gDdVi5
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-24 18:54:39,713 - Environment prefix is /tmp/tsqa.env.0EFOHP
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-24 18:54:43,240 - Environment prefix is /tmp/tsqa.env.JPkdZh
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session ... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:54:46] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-24 18:54:46,670 - Environment prefix is /tmp/tsqa.env.mI80jW
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-24 18:54:53,100 - Environment prefix is /tmp/tsqa.env.NjPMOt
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-24 18:54:59,547 - Environment prefix is /tmp/tsqa.env.E0Hb9x
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-24 18:55:21,087 - Environment prefix is /tmp/tsqa.env.2DTDg7
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-24 18:55:42,610 - Environment prefix is /tmp/tsqa.env.4IwgLc
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-24 18:55:46,124 - Environment prefix is /tmp/tsqa.env.nTQqrD
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-24 18:55:49,702 - Environment prefix is /tmp/tsqa.env.THVv8_
INFO 2015-10-24 18:55:49,704 - socket_server_port = 36258
INFO 2015-10-24 18:55:49,704 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-24 18:55:52,970 - Sending data back to the client: 1fdbffe3e5864b31bf626451944d52a9
INFO 2015-10-24 18:55:52,973 - 1fdbffe3e5864b31bf626451944d52a9
INFO 2015-10-24 18:55:57,984 - Sending data back to the client: 1fdbffe3e5864b31bf626451944d52a9
INFO 2015-10-24 18:55:57,985 - 1fdbffe3e5864b31bf626451944d52a9
ok
INFO 2015-10-24 18:55:58,169 - Environment prefix is /tmp/tsqa.env.e3ppT5
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [24/Oct/2015 18:56:01] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-24 18:56:01,707 - Environment prefix is /tmp/tsqa.env.PmHjkT
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-24 18:58:56,242 - Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-ccache', '--enable-experimental-plugins', '--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio', '--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-24 18:59:06,374 - Environment prefix is /tmp/tsqa.env.fAVyUS
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [24/Oct/2015 18:59:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:09] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-24 18:59:09,913 - Environment prefix is /tmp/tsqa.env.oMSFhY
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [24/Oct/2015 18:59:13] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:13] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [24/Oct/2015 18:59:13] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-24 18:59:13,472 - Environment prefix is /tmp/tsqa.env.Bqjet0
INFO 2015-10-24 18:59:13,475 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-24 18:59:13,475 - spdy server port = 37298
INFO 2015-10-24 18:59:13,475 - http server port = 38754
Test that the origin does in fact support spdy 3 ... INFO 2015-10-24 18:59:16,701 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37298/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-24 18:59:17,929 - full args = ['/usr/local/bin/spdycat', 'https://localhost:38754/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-24 18:59:18,138 - Environment prefix is /tmp/tsqa.env.EcfjRM
INFO 2015-10-24 18:59:18,140 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-24 18:59:18,140 - spdy server port = 33245
INFO 2015-10-24 18:59:18,140 - http server port = 54739
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-24 18:59:21,371 - full args = ['/usr/local/bin/spdycat', 'https://localhost:33245/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-24 18:59:22,519 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54739/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-24 18:59:22,730 - Environment prefix is /tmp/tsqa.env._lYL3i
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok

======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py",> line 198, in test_chunked_bad_close
    requests.get(url, proxies=self.proxies, timeout=2)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 69, in get
    return request('get', url, params=params, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 50, in request
    response = session.request(method=method, url=url, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 468, in request
    resp = self.send(prep, **send_kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 608, in send
    r.content
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 734, in content
    self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes()
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 664, in generate
    raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=60387): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.KNcrAm
test_chunked: INFO: sending data back to the client
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 1370.265s

FAILED (SKIP=8, errors=1)
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results

Build failed in Jenkins: tsqa-master #944

Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/944/changes>

Changes:

[skawano] correct docs for read-while-writer

[Bryan Call] TS-315: Add switch to disable config file generation/runtime behavior changing

------------------------------------------
[...truncated 495 lines...]
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-22 23:46:44,307 - Environment prefix is /tmp/tsqa.env.1IdqOF
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-22 23:48:27,856 - Environment prefix is /tmp/tsqa.env.kp_NAw
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-22 23:48:58,098 - Starting build (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-22 23:49:52,407 - Build completed (1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-10-22 23:49:52,472 - Environment prefix is /tmp/tsqa.env.eQNCzb
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-22 23:49:55,883 - Environment prefix is /tmp/tsqa.env.aGSFid
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [22/Oct/2015 23:49:59] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-22 23:49:59,303 - Environment prefix is /tmp/tsqa.env.dUhGFN
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-22 23:50:12,872 - Environment prefix is /tmp/tsqa.env.qK29uw
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:16] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-22 23:50:16,322 - Environment prefix is /tmp/tsqa.env.t2mWjO
INFO 2015-10-22 23:50:16,325 - map / http://127.0.0.1:52871/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-22 23:50:19,551 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 22 Oct 2015 23:50:19 GMT
Age: 0
Connection: close


INFO 2015-10-22 23:50:19,552 - head request with case(TE) costs 0.002024 seconds while the timout is 5.000000 seconds.
INFO 2015-10-22 23:50:19,553 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Thu, 22 Oct 2015 23:50:19 GMT
Age: 0
Connection: close


INFO 2015-10-22 23:50:19,553 - head request with case(CL) costs 0.001214 seconds while the timout is 5.000000 seconds.
INFO 2015-10-22 23:50:19,569 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 22 Oct 2015 23:50:19 GMT
Age: 0
Connection: close


INFO 2015-10-22 23:50:19,570 - head request with case() costs 0.016459 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-22 23:50:19,754 - Environment prefix is /tmp/tsqa.env.LItVAp
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-22 23:50:23,218 - Environment prefix is /tmp/tsqa.env.dAtJuq
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-22 23:50:28,648 - Environment prefix is /tmp/tsqa.env.fADDWm
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-22 23:50:38,144 - Environment prefix is /tmp/tsqa.env.umcQGm
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.umcQGm
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP: Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-22 23:50:38,230 - Environment prefix is /tmp/tsqa.env.hFR1wT
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.hFR1wT
--------------------- >> end captured logging << ---------------------
INFO 2015-10-22 23:50:38,330 - Environment prefix is /tmp/tsqa.env.kKcvqz
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-22 23:50:41,836 - Environment prefix is /tmp/tsqa.env.IamRDI
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-22 23:50:45,563 - Environment prefix is /tmp/tsqa.env.iMhMSF
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-22 23:50:49,166 - Environment prefix is /tmp/tsqa.env.rjos8p
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session ... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:50:52] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-22 23:50:52,635 - Environment prefix is /tmp/tsqa.env.gSmXOJ
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-22 23:50:59,190 - Environment prefix is /tmp/tsqa.env.U3PTCk
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-22 23:51:05,666 - Environment prefix is /tmp/tsqa.env.x4Q13C
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-22 23:51:27,143 - Environment prefix is /tmp/tsqa.env.RnBGXU
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-22 23:51:48,646 - Environment prefix is /tmp/tsqa.env.aFB4Zu
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-22 23:51:52,086 - Environment prefix is /tmp/tsqa.env.jcZPrT
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-22 23:51:55,516 - Environment prefix is /tmp/tsqa.env.yLQL87
INFO 2015-10-22 23:51:55,518 - socket_server_port = 57623
INFO 2015-10-22 23:51:55,518 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-22 23:51:58,764 - Sending data back to the client: 117542dc45794c94b9595a27e44d7ac9
INFO 2015-10-22 23:51:58,765 - 117542dc45794c94b9595a27e44d7ac9
INFO 2015-10-22 23:52:03,773 - Sending data back to the client: 117542dc45794c94b9595a27e44d7ac9
INFO 2015-10-22 23:52:03,773 - 117542dc45794c94b9595a27e44d7ac9
ok
INFO 2015-10-22 23:52:03,961 - Environment prefix is /tmp/tsqa.env.NpZl2L
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [22/Oct/2015 23:52:07] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-22 23:52:07,433 - Environment prefix is /tmp/tsqa.env.CL6qhq
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-22 23:54:41,331 - Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-ccache', '--enable-experimental-plugins', '--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio', '--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-22 23:54:50,759 - Environment prefix is /tmp/tsqa.env.sCkNuY
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [22/Oct/2015 23:54:54] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:54:54] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:54:54] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-22 23:54:54,206 - Environment prefix is /tmp/tsqa.env.XquwnY
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [22/Oct/2015 23:54:57] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:54:57] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [22/Oct/2015 23:54:57] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-22 23:54:57,649 - Environment prefix is /tmp/tsqa.env.qKZJIZ
INFO 2015-10-22 23:54:57,651 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-22 23:54:57,651 - spdy server port = 44431
INFO 2015-10-22 23:54:57,651 - http server port = 33070
Test that the origin does in fact support spdy 3 ... INFO 2015-10-22 23:55:00,876 - full args = ['/usr/local/bin/spdycat', 'https://localhost:44431/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-22 23:55:02,177 - full args = ['/usr/local/bin/spdycat', 'https://localhost:33070/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-22 23:55:02,384 - Environment prefix is /tmp/tsqa.env.WSLzqD
INFO 2015-10-22 23:55:02,387 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-22 23:55:02,387 - spdy server port = 58611
INFO 2015-10-22 23:55:02,387 - http server port = 35472
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-22 23:55:05,621 - full args = ['/usr/local/bin/spdycat', 'https://localhost:58611/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-22 23:55:06,747 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35472/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-22 23:55:06,954 - Environment prefix is /tmp/tsqa.env.zXbAE6
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok

======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py",> line 198, in test_chunked_bad_close
    requests.get(url, proxies=self.proxies, timeout=2)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 69, in get
    return request('get', url, params=params, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py",> line 50, in request
    response = session.request(method=method, url=url, **kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 468, in request
    resp = self.send(prep, **send_kwargs)
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py",> line 608, in send
    r.content
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 734, in content
    self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes()
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py",> line 664, in generate
    raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=32830): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.SG73MM
test_chunked: INFO: sending data back to the client
test_chunked: INFO: Client disconnected
--------------------- >> end captured logging << ---------------------

----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 942.552s

FAILED (SKIP=8, errors=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results