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/26 21:57:26 UTC

Build failed in Jenkins: tsqa-master #950

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

Changes:

[Leif Hedstrom] TS-3969 Fixes Debug() output to use the right event check

------------------------------------------
[...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-26 20:48:24,675 - Environment prefix is /tmp/tsqa.env.eg54Ks
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 20:50:08,332 - Environment prefix is /tmp/tsqa.env.8tAK9b
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 20:50:39,344 - 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 20:51:34,470 - 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 20:51:34,539 - Environment prefix is /tmp/tsqa.env.5AFiFQ
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 20:51:38,072 - Environment prefix is /tmp/tsqa.env.ifk9iD
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [26/Oct/2015 20:51:41] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 20:51:41,596 - Environment prefix is /tmp/tsqa.env.xAd8ZO
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 20:51:55,227 - Environment prefix is /tmp/tsqa.env.o0hdQl
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:51:58,804 - Environment prefix is /tmp/tsqa.env.zP_6k5
INFO 2015-10-26 20:51:58,806 - map / http://127.0.0.1:37875/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26 20:52:02,102 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:02 GMT
Age: 0
Connection: close


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


INFO 2015-10-26 20:52:02,107 - head request with case(CL) costs 0.004120 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 20:52:02,124 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:02 GMT
Age: 0
Connection: close


INFO 2015-10-26 20:52:02,125 - head request with case() costs 0.017589 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 20:52:02,315 - Environment prefix is /tmp/tsqa.env.LNDRSo
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 20:52:05,853 - Environment prefix is /tmp/tsqa.env.pqfoEO
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 20:52:11,309 - Environment prefix is /tmp/tsqa.env.8TGft1
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 20:52:20,907 - Environment prefix is /tmp/tsqa.env.TUnnIk
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.TUnnIk
--------------------- >> 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 20:52:20,992 - Environment prefix is /tmp/tsqa.env.TA2It6
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.TA2It6
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 20:52:21,098 - Environment prefix is /tmp/tsqa.env.l4Uecf
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 20:52:24,791 - Environment prefix is /tmp/tsqa.env.UZQt9t
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 20:52:28,419 - Environment prefix is /tmp/tsqa.env.9k45ar
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 20:52:32,093 - Environment prefix is /tmp/tsqa.env.JnCQ9j
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 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:52:35,532 - Environment prefix is /tmp/tsqa.env.h6i2E0
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 20:52:42,065 - Environment prefix is /tmp/tsqa.env.d9Ndnl
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 20:52:48,605 - Environment prefix is /tmp/tsqa.env.E5gGLu
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 20:53:10,281 - Environment prefix is /tmp/tsqa.env.ae_u_E
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 20:53:31,919 - Environment prefix is /tmp/tsqa.env.R0iw5i
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 20:53:35,374 - Environment prefix is /tmp/tsqa.env.FufbKv
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 20:53:38,856 - Environment prefix is /tmp/tsqa.env.sLi_zW
INFO 2015-10-26 20:53:38,858 - socket_server_port = 51033
INFO 2015-10-26 20:53:38,858 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-26 20:53:42,125 - Sending data back to the client: a4f7bd5fed054056afea398ea9ae5f1c
INFO 2015-10-26 20:53:42,126 - a4f7bd5fed054056afea398ea9ae5f1c
INFO 2015-10-26 20:53:47,132 - Sending data back to the client: a4f7bd5fed054056afea398ea9ae5f1c
INFO 2015-10-26 20:53:47,132 - a4f7bd5fed054056afea398ea9ae5f1c
ok
INFO 2015-10-26 20:53:47,315 - Environment prefix is /tmp/tsqa.env.hCouFC
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 20:53:50,981 - Environment prefix is /tmp/tsqa.env.SdCohJ
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 20:56:25,972 - 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 20:56:35,246 - Environment prefix is /tmp/tsqa.env.NA3_AO
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015 20:56:38] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:38] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:38] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:56:38,789 - Environment prefix is /tmp/tsqa.env.0KmNc5
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015 20:56:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:42] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 20:56:42,345 - Environment prefix is /tmp/tsqa.env.uS_lup
INFO 2015-10-26 20:56:42,347 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 20:56:42,347 - spdy server port = 40167
INFO 2015-10-26 20:56:42,347 - http server port = 37992
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26 20:56:45,588 - full args = ['/usr/local/bin/spdycat', 'https://localhost:40167/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 20:56:46,962 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37992/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 20:56:47,185 - Environment prefix is /tmp/tsqa.env.Sc5q51
INFO 2015-10-26 20:56:47,186 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 20:56:47,187 - spdy server port = 49464
INFO 2015-10-26 20:56:47,187 - http server port = 47921
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26 20:56:50,446 - full args = ['/usr/local/bin/spdycat', 'https://localhost:49464/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 20:56:52,355 - full args = ['/usr/local/bin/spdycat', 'https://localhost:47921/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 20:56:52,574 - Environment prefix is /tmp/tsqa.env.cvjliX
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=39306): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.JAmAl3
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 941.468s

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 #952

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

Changes:

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

------------------------------------------
[...truncated 492 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-26 21:32:19,608 - Environment prefix is /tmp/tsqa.env.q1jnw6
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 21:34:03,181 - Environment prefix is /tmp/tsqa.env.zRUr7K
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 21:34:33,586 - 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 21:35:23,578 - 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 21:35:23,648 - Environment prefix is /tmp/tsqa.env.FJdUp6
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 21:35:27,069 - Environment prefix is /tmp/tsqa.env.DV_ZQp
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [26/Oct/2015 21:35:30] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 21:35:30,481 - Environment prefix is /tmp/tsqa.env.W1i5ta
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 21:35:43,951 - Environment prefix is /tmp/tsqa.env.r5N1YP
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:35:47] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 21:35:47,419 - Environment prefix is /tmp/tsqa.env.9ApjAv
INFO 2015-10-26 21:35:47,422 - map / http://127.0.0.1:54932/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26 21:35:50,654 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 21:35:50 GMT
Age: 0
Connection: close


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


INFO 2015-10-26 21:35:50,668 - head request with case(CL) costs 0.014416 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 21:35:50,669 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 21:35:50 GMT
Age: 0
Connection: close


INFO 2015-10-26 21:35:50,670 - head request with case() costs 0.000920 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 21:35:50,856 - Environment prefix is /tmp/tsqa.env.MC_IWu
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 21:35:54,324 - Environment prefix is /tmp/tsqa.env.EzHe2x
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 21:35:59,865 - Environment prefix is /tmp/tsqa.env.VuVkwk
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 21:36:09,403 - Environment prefix is /tmp/tsqa.env._MFxgU
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env._MFxgU
--------------------- >> 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 21:36:09,491 - Environment prefix is /tmp/tsqa.env._s2dcL
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env._s2dcL
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 21:36:09,595 - Environment prefix is /tmp/tsqa.env.brLUrz
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 21:36:13,160 - Environment prefix is /tmp/tsqa.env.mH_wkF
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 21:36:16,757 - Environment prefix is /tmp/tsqa.env.ODgx9h
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 21:36:20,355 - Environment prefix is /tmp/tsqa.env.mYGR3G
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 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:36:23] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 21:36:23,782 - Environment prefix is /tmp/tsqa.env.OL4Rh8
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 21:36:30,213 - Environment prefix is /tmp/tsqa.env.TGGpv0
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 21:36:36,709 - Environment prefix is /tmp/tsqa.env.L49zUG
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 21:36:58,214 - Environment prefix is /tmp/tsqa.env.3MXQTV
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 21:37:19,736 - Environment prefix is /tmp/tsqa.env.yDGCXE
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 21:37:23,190 - Environment prefix is /tmp/tsqa.env.ICUISb
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 21:37:26,614 - Environment prefix is /tmp/tsqa.env.pQ5jzk
INFO 2015-10-26 21:37:26,616 - socket_server_port = 56686
INFO 2015-10-26 21:37:26,616 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-26 21:37:29,845 - Sending data back to the client: 7ab1c54484df4b35a25c2ee72b1c28dd
INFO 2015-10-26 21:37:29,845 - 7ab1c54484df4b35a25c2ee72b1c28dd
INFO 2015-10-26 21:37:34,852 - Sending data back to the client: 7ab1c54484df4b35a25c2ee72b1c28dd
INFO 2015-10-26 21:37:34,853 - 7ab1c54484df4b35a25c2ee72b1c28dd
ok
INFO 2015-10-26 21:37:35,040 - Environment prefix is /tmp/tsqa.env.jtEOe0
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 21:37:38] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 21:37:38,509 - Environment prefix is /tmp/tsqa.env.Y5p_kk
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 21:40:12,738 - 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 21:40:22,118 - Environment prefix is /tmp/tsqa.env.qtRTNs
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015 21:40:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:40:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:40:25] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 21:40:25,649 - Environment prefix is /tmp/tsqa.env.CCvs2S
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015 21:40:28] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:40:28] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 21:40:28] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 21:40:29,091 - Environment prefix is /tmp/tsqa.env.G8O4kD
INFO 2015-10-26 21:40:29,093 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 21:40:29,093 - spdy server port = 34136
INFO 2015-10-26 21:40:29,093 - http server port = 55115
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26 21:40:32,317 - full args = ['/usr/local/bin/spdycat', 'https://localhost:34136/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 21:40:33,653 - full args = ['/usr/local/bin/spdycat', 'https://localhost:55115/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 21:40:33,859 - Environment prefix is /tmp/tsqa.env.oX3KWD
INFO 2015-10-26 21:40:33,860 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 21:40:33,861 - spdy server port = 42789
INFO 2015-10-26 21:40:33,861 - http server port = 35252
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26 21:40:37,091 - full args = ['/usr/local/bin/spdycat', 'https://localhost:42789/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 21:40:38,456 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35252/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 21:40:38,663 - Environment prefix is /tmp/tsqa.env.ytbMWN
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=57440): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.WhMn7F
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 926.812s

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 #951

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

------------------------------------------
[...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-26 20:48:56,173 - Environment prefix is /tmp/tsqa.env.7H4ahD
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 20:50:39,730 - Environment prefix is /tmp/tsqa.env.SfoDPQ
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 20:51:11,365 - 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 20:52:07,522 - 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 20:52:07,589 - Environment prefix is /tmp/tsqa.env.fsex7Y
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 20:52:11,100 - Environment prefix is /tmp/tsqa.env.vODu58
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [26/Oct/2015 20:52:14] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 20:52:14,642 - Environment prefix is /tmp/tsqa.env.l0EQeZ
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 20:52:28,202 - Environment prefix is /tmp/tsqa.env.6v9IKy
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:31] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:52:31,770 - Environment prefix is /tmp/tsqa.env.lba6n1
INFO 2015-10-26 20:52:31,773 - map / http://127.0.0.1:43507/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26 20:52:35,077 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:35 GMT
Age: 0
Connection: close


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


INFO 2015-10-26 20:52:35,095 - head request with case(CL) costs 0.017406 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 20:52:35,109 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:35 GMT
Age: 0
Connection: close


INFO 2015-10-26 20:52:35,109 - head request with case() costs 0.013963 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 20:52:35,290 - Environment prefix is /tmp/tsqa.env.7xQG2c
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 20:52:38,743 - Environment prefix is /tmp/tsqa.env.gc2vVs
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 20:52:44,187 - Environment prefix is /tmp/tsqa.env.w4XuwE
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 20:52:53,808 - Environment prefix is /tmp/tsqa.env.UmH_JX
SKIP: 
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.UmH_JX
--------------------- >> 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 20:52:53,895 - Environment prefix is /tmp/tsqa.env.LKUZie
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.LKUZie
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 20:52:53,992 - Environment prefix is /tmp/tsqa.env.d1jnIM
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 20:52:57,684 - Environment prefix is /tmp/tsqa.env.UtACRE
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 20:53:01,343 - Environment prefix is /tmp/tsqa.env.vFsIqc
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 20:53:04,989 - Environment prefix is /tmp/tsqa.env.g0WHSa
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 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:53:08] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:53:08,495 - Environment prefix is /tmp/tsqa.env.JJVurb
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 20:53:15,027 - Environment prefix is /tmp/tsqa.env.kL8f93
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 20:53:21,557 - Environment prefix is /tmp/tsqa.env.r4FFgY
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 20:53:43,158 - Environment prefix is /tmp/tsqa.env.27Ax9g
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 20:54:04,737 - Environment prefix is /tmp/tsqa.env.Rk5e1m
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 20:54:08,310 - Environment prefix is /tmp/tsqa.env.RAJ673
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 20:54:11,824 - Environment prefix is /tmp/tsqa.env.MLkAdO
INFO 2015-10-26 20:54:11,826 - socket_server_port = 60706
INFO 2015-10-26 20:54:11,826 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-26 20:54:15,146 - Sending data back to the client: 53459d8aca22427b9a80956b9d19e765
INFO 2015-10-26 20:54:15,146 - 53459d8aca22427b9a80956b9d19e765
INFO 2015-10-26 20:54:20,154 - Sending data back to the client: 53459d8aca22427b9a80956b9d19e765
INFO 2015-10-26 20:54:20,154 - 53459d8aca22427b9a80956b9d19e765
ok
INFO 2015-10-26 20:54:20,342 - Environment prefix is /tmp/tsqa.env.N23cd4
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:54:23] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 20:54:23,946 - Environment prefix is /tmp/tsqa.env.eJISBd
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 20:56:59,469 - 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 20:57:09,438 - Environment prefix is /tmp/tsqa.env.MM2UHZ
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015 20:57:12] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:57:12] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:57:12] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:57:12,967 - Environment prefix is /tmp/tsqa.env.vI3X7x
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015 20:57:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:57:16] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:57:16] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 20:57:16,594 - Environment prefix is /tmp/tsqa.env.Rv7t45
INFO 2015-10-26 20:57:16,596 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 20:57:16,596 - spdy server port = 38968
INFO 2015-10-26 20:57:16,596 - http server port = 55313
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26 20:57:19,830 - full args = ['/usr/local/bin/spdycat', 'https://localhost:38968/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 20:57:20,314 - full args = ['/usr/local/bin/spdycat', 'https://localhost:55313/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 20:57:20,518 - Environment prefix is /tmp/tsqa.env.VB5hmB
INFO 2015-10-26 20:57:20,520 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 20:57:20,520 - spdy server port = 53276
INFO 2015-10-26 20:57:20,520 - http server port = 33086
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26 20:57:23,747 - full args = ['/usr/local/bin/spdycat', 'https://localhost:53276/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 20:57:24,429 - full args = ['/usr/local/bin/spdycat', 'https://localhost:33086/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 20:57:24,636 - Environment prefix is /tmp/tsqa.env.fnk9rd
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=42814): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.DL3Fhj
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 952.385s

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