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 23:13:10 UTC
Build failed in Jenkins: tsqa-master #954
See <https://ci.trafficserver.apache.org/job/tsqa-master/954/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 22:04:12,558 - Environment prefix is /tmp/tsqa.env.9YFc0Q
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 22:05:56,330 - Environment prefix is /tmp/tsqa.env.FKCNCy
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 22:06:26,974 - 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 22:07:19,575 - 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 22:07:19,642 - Environment prefix is /tmp/tsqa.env.B1LAr4
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 22:07:23,076 - Environment prefix is /tmp/tsqa.env.EK0vSr
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [26/Oct/2015 22:07:26] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 22:07:26,534 - Environment prefix is /tmp/tsqa.env.a3N7vt
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 22:07:40,134 - Environment prefix is /tmp/tsqa.env.oTZ5HS
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:07:43] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 22:07:43,628 - Environment prefix is /tmp/tsqa.env.HDtoZy
INFO 2015-10-26 22:07:43,630 - map / http://127.0.0.1:49874/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26 22:07:46,976 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 22:07:46 GMT
Age: 0
Connection: close
INFO 2015-10-26 22:07:46,976 - head request with case(TE) costs 0.002012 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 22:07:46,977 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 22:07:46 GMT
Age: 0
Connection: close
INFO 2015-10-26 22:07:46,978 - head request with case(CL) costs 0.001068 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 22:07:46,993 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 22:07:46 GMT
Age: 0
Connection: close
INFO 2015-10-26 22:07:46,994 - head request with case() costs 0.015849 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 22:07:47,184 - Environment prefix is /tmp/tsqa.env.gW96MY
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 22:07:50,774 - Environment prefix is /tmp/tsqa.env.QlLpnE
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 22:07:56,405 - Environment prefix is /tmp/tsqa.env.Qas45B
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 22:08:06,041 - Environment prefix is /tmp/tsqa.env.wTXZaC
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.wTXZaC
--------------------- >> 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 22:08:06,127 - Environment prefix is /tmp/tsqa.env.GPf2fP
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.GPf2fP
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 22:08:06,229 - Environment prefix is /tmp/tsqa.env.2g0Ujs
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 22:08:09,890 - Environment prefix is /tmp/tsqa.env.lfQHwn
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 22:08:13,657 - Environment prefix is /tmp/tsqa.env.r6osCL
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 22:08:17,341 - Environment prefix is /tmp/tsqa.env.bjv9m7
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 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:20] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 22:08:20,823 - Environment prefix is /tmp/tsqa.env.NaIaCq
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 22:08:27,314 - Environment prefix is /tmp/tsqa.env.6bw8t4
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 22:08:33,835 - Environment prefix is /tmp/tsqa.env._Y9elv
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 22:08:55,410 - Environment prefix is /tmp/tsqa.env.Qr5vgN
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 22:09:17,041 - Environment prefix is /tmp/tsqa.env.MAhVGp
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 22:09:20,531 - Environment prefix is /tmp/tsqa.env.GSjwde
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 22:09:24,087 - Environment prefix is /tmp/tsqa.env.vdASG0
INFO 2015-10-26 22:09:24,089 - socket_server_port = 37751
INFO 2015-10-26 22:09:24,089 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-26 22:09:27,567 - Sending data back to the client: f81186a024cc49f795e0e98ee00a4a6f
INFO 2015-10-26 22:09:27,575 - f81186a024cc49f795e0e98ee00a4a6f
INFO 2015-10-26 22:09:32,586 - Sending data back to the client: f81186a024cc49f795e0e98ee00a4a6f
INFO 2015-10-26 22:09:32,586 - f81186a024cc49f795e0e98ee00a4a6f
ok
INFO 2015-10-26 22:09:32,772 - Environment prefix is /tmp/tsqa.env.Msd44j
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:09:36] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 22:09:36,343 - Environment prefix is /tmp/tsqa.env.u1E8vO
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 22:12:09,631 - 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 22:12:19,018 - Environment prefix is /tmp/tsqa.env.f0vxhr
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015 22:12:22] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:12:22] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:12:22] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 22:12:22,571 - Environment prefix is /tmp/tsqa.env.DiBe8j
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015 22:12:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:12:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:12:25] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 22:12:26,133 - Environment prefix is /tmp/tsqa.env.5_gfiT
INFO 2015-10-26 22:12:26,135 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 22:12:26,135 - spdy server port = 54841
INFO 2015-10-26 22:12:26,135 - http server port = 41058
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26 22:12:29,375 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54841/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 22:12:30,528 - full args = ['/usr/local/bin/spdycat', 'https://localhost:41058/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 22:12:30,750 - Environment prefix is /tmp/tsqa.env.m5eYHd
INFO 2015-10-26 22:12:30,751 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 22:12:30,752 - spdy server port = 59957
INFO 2015-10-26 22:12:30,752 - http server port = 48672
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26 22:12:33,925 - full args = ['/usr/local/bin/spdycat', 'https://localhost:59957/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 22:12:35,140 - full args = ['/usr/local/bin/spdycat', 'https://localhost:48672/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 22:12:35,368 - Environment prefix is /tmp/tsqa.env.eRNfTH
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=42380): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.y62jFQ
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 921.520s
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 #964
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/964/changes>
Changes:
[Bryan Call] TS-3984: CID 1328817: Resource leaks (CTOR_DTOR_LEAK) in multiplexer plugin
------------------------------------------
[...truncated 495 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-29 22:20:42,117 - Environment prefix is /tmp/tsqa.env.Lli_Jp
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-29 22:22:25,764 - Environment prefix is /tmp/tsqa.env.e8lSxs
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-29 22:22:55,699 - 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-29 22:23:46,019 - 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-29 22:23:46,081 - Environment prefix is /tmp/tsqa.env.yaIYVU
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-29 22:23:49,478 - Environment prefix is /tmp/tsqa.env.2DLLpb
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [29/Oct/2015 22:23:52] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-29 22:23:52,993 - Environment prefix is /tmp/tsqa.env.OJdihR
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-29 22:24:06,472 - Environment prefix is /tmp/tsqa.env.4WPbwn
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:09] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:24:10,015 - Environment prefix is /tmp/tsqa.env.FEzbPi
INFO 2015-10-29 22:24:10,018 - map / http://127.0.0.1:60351/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-29 22:24:13,354 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:24:13 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:24:13,354 - head request with case(TE) costs 0.002179 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 22:24:13,365 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:24:13 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:24:13,365 - head request with case(CL) costs 0.010533 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 22:24:13,376 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:24:13 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:24:13,376 - head request with case() costs 0.010835 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-29 22:24:13,557 - Environment prefix is /tmp/tsqa.env.LfXgdC
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-29 22:24:17,096 - Environment prefix is /tmp/tsqa.env.Ll1YFs
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-29 22:24:22,590 - Environment prefix is /tmp/tsqa.env.i61A6y
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-29 22:24:32,178 - Environment prefix is /tmp/tsqa.env.NzRr4k
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.NzRr4k
--------------------- >> 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-29 22:24:32,259 - Environment prefix is /tmp/tsqa.env.OOj3qJ
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.OOj3qJ
--------------------- >> end captured logging << ---------------------
INFO 2015-10-29 22:24:32,361 - Environment prefix is /tmp/tsqa.env.JXK7aG
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-29 22:24:35,861 - Environment prefix is /tmp/tsqa.env.YcYNrw
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-29 22:24:39,425 - Environment prefix is /tmp/tsqa.env.jCo_IP
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-29 22:24:43,065 - Environment prefix is /tmp/tsqa.env.R0XADT
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 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:24:46] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:24:46,605 - Environment prefix is /tmp/tsqa.env.IC0hoM
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-29 22:24:53,337 - Environment prefix is /tmp/tsqa.env.v0UL4w
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-29 22:24:59,787 - Environment prefix is /tmp/tsqa.env.AVDAd1
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-29 22:25:21,314 - Environment prefix is /tmp/tsqa.env.MZ0nLt
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-29 22:25:42,890 - Environment prefix is /tmp/tsqa.env.RS4TKT
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-29 22:25:48,716 - Environment prefix is /tmp/tsqa.env.tw2617
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-29 22:25:52,174 - Environment prefix is /tmp/tsqa.env.XWsoRI
INFO 2015-10-29 22:25:52,175 - socket_server_port = 48435
INFO 2015-10-29 22:25:52,176 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-29 22:25:55,434 - Sending data back to the client: 743ebee749d340dbb054e7a6df8a5289
INFO 2015-10-29 22:25:55,439 - 743ebee749d340dbb054e7a6df8a5289
INFO 2015-10-29 22:26:00,445 - Sending data back to the client: 743ebee749d340dbb054e7a6df8a5289
INFO 2015-10-29 22:26:00,446 - 743ebee749d340dbb054e7a6df8a5289
ok
INFO 2015-10-29 22:26:00,622 - Environment prefix is /tmp/tsqa.env.R00bnP
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:26:03] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-29 22:26:04,081 - Environment prefix is /tmp/tsqa.env.Fy2FZJ
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-29 22:28:37,741 - 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-29 22:28:46,609 - Environment prefix is /tmp/tsqa.env.N9hzy2
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [29/Oct/2015 22:28:49] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:28:49] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:28:49] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:28:50,031 - Environment prefix is /tmp/tsqa.env.P43qsM
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [29/Oct/2015 22:28:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:28:53] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:28:53] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-29 22:28:53,463 - Environment prefix is /tmp/tsqa.env.K2Rnxt
INFO 2015-10-29 22:28:53,465 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 22:28:53,465 - spdy server port = 53311
INFO 2015-10-29 22:28:53,465 - http server port = 60157
Test that the origin does in fact support spdy 3 ... INFO 2015-10-29 22:28:56,688 - full args = ['/usr/local/bin/spdycat', 'https://localhost:53311/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 22:28:58,209 - full args = ['/usr/local/bin/spdycat', 'https://localhost:60157/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 22:28:58,409 - Environment prefix is /tmp/tsqa.env.6ptztJ
INFO 2015-10-29 22:28:58,411 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 22:28:58,411 - spdy server port = 52654
INFO 2015-10-29 22:28:58,411 - http server port = 36214
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-29 22:29:01,639 - full args = ['/usr/local/bin/spdycat', 'https://localhost:52654/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 22:29:02,661 - full args = ['/usr/local/bin/spdycat', 'https://localhost:36214/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 22:29:02,857 - Environment prefix is /tmp/tsqa.env.jro9n8
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=41371): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.eO2o7M
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 916.787s
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 #963
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/963/>
------------------------------------------
[...truncated 517 lines...]
127.0.0.1 - - [29/Oct/2015 22:07:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:15] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:07:15,613 - Environment prefix is /tmp/tsqa.env.5aqbwf
INFO 2015-10-29 22:07:15,616 - map / http://127.0.0.1:43709/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-29 22:07:18,873 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:07:18 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:07:18,874 - head request with case(TE) costs 0.016334 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 22:07:18,892 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:07:18 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:07:18,892 - head request with case(CL) costs 0.018365 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 22:07:18,914 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:07:18 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:07:18,914 - head request with case() costs 0.021862 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-29 22:07:19,101 - Environment prefix is /tmp/tsqa.env.cEmSEq
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-29 22:07:22,553 - Environment prefix is /tmp/tsqa.env._p0z8R
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-29 22:07:28,095 - Environment prefix is /tmp/tsqa.env.Z4jVQW
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-29 22:07:37,593 - Environment prefix is /tmp/tsqa.env.o4MPmc
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.o4MPmc
--------------------- >> 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-29 22:07:37,672 - Environment prefix is /tmp/tsqa.env.lmwXs0
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.lmwXs0
--------------------- >> end captured logging << ---------------------
INFO 2015-10-29 22:07:37,767 - Environment prefix is /tmp/tsqa.env.3tpTpx
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-29 22:07:41,395 - Environment prefix is /tmp/tsqa.env.EURCIn
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-29 22:07:45,051 - Environment prefix is /tmp/tsqa.env.JX9q2v
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-29 22:07:48,738 - Environment prefix is /tmp/tsqa.env.SNbdAS
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 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:07:52] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:07:53,079 - Environment prefix is /tmp/tsqa.env.mQp0Me
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-29 22:07:59,580 - Environment prefix is /tmp/tsqa.env.7o_cUw
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-29 22:08:06,143 - Environment prefix is /tmp/tsqa.env.jOpv5P
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-29 22:08:27,708 - Environment prefix is /tmp/tsqa.env.w7FsbK
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-29 22:08:49,322 - Environment prefix is /tmp/tsqa.env.RywnlK
ERROR
INFO 2015-10-29 22:08:54,545 - Environment prefix is /tmp/tsqa.env.KNdKBN
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-29 22:08:58,018 - Environment prefix is /tmp/tsqa.env.Lhnn1N
INFO 2015-10-29 22:08:58,019 - socket_server_port = 41851
INFO 2015-10-29 22:08:58,020 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-29 22:09:01,374 - Sending data back to the client: 31a4c4fc872e4b16b0dc6a0034b5c76a
INFO 2015-10-29 22:09:01,387 - 31a4c4fc872e4b16b0dc6a0034b5c76a
INFO 2015-10-29 22:09:06,394 - Sending data back to the client: 31a4c4fc872e4b16b0dc6a0034b5c76a
INFO 2015-10-29 22:09:06,394 - 31a4c4fc872e4b16b0dc6a0034b5c76a
ok
INFO 2015-10-29 22:09:06,585 - Environment prefix is /tmp/tsqa.env.FhWVVd
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:09] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:09:10] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-29 22:09:10,302 - Environment prefix is /tmp/tsqa.env.FuU4C1
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-29 22:11:48,275 - 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-29 22:11:58,191 - Environment prefix is /tmp/tsqa.env.Pco_g8
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [29/Oct/2015 22:12:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:12:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:12:01] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:12:01,844 - Environment prefix is /tmp/tsqa.env.CuDHwm
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [29/Oct/2015 22:12:05] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:12:05] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:12:05] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-29 22:12:05,398 - Environment prefix is /tmp/tsqa.env.RYJPGE
INFO 2015-10-29 22:12:05,400 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 22:12:05,401 - spdy server port = 56676
INFO 2015-10-29 22:12:05,401 - http server port = 36710
Test that the origin does in fact support spdy 3 ... INFO 2015-10-29 22:12:08,742 - full args = ['/usr/local/bin/spdycat', 'https://localhost:56676/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 22:12:09,331 - full args = ['/usr/local/bin/spdycat', 'https://localhost:36710/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 22:12:09,539 - Environment prefix is /tmp/tsqa.env.ZF_NNB
INFO 2015-10-29 22:12:09,541 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 22:12:09,541 - spdy server port = 34484
INFO 2015-10-29 22:12:09,541 - http server port = 33273
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-29 22:12:12,790 - full args = ['/usr/local/bin/spdycat', 'https://localhost:34484/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 22:12:14,597 - full args = ['/usr/local/bin/spdycat', 'https://localhost:33273/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 22:12:14,817 - Environment prefix is /tmp/tsqa.env.Mq4MNI
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=51968): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.b7pkFN
test_chunked: INFO: sending data back to the client
test_chunked: INFO: Client disconnected
--------------------- >> end captured logging << ---------------------
======================================================================
ERROR: test suite for <class 'test_keepalive.TestKeepAlive_Authorization_no_private'>
----------------------------------------------------------------------
Traceback (most recent call last):
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 209, in run
self.setUp()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 292, in setUp
self.setupContext(ancestor)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 315, in setupContext
try_run(context, names)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/util.py",> line 471, in try_run
return func()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/test_cases.py",> line 86, in setUpClass
cls.environment.start()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 450, in start
self.__exec_cop()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 297, in __exec_cop
tsqa.utils.poll_interfaces(self.hostports)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/utils.py",> line 73, in poll_interfaces
reduce(lambda x, y: str(x) + ',' + str(y), hostports)))
Exception: Timeout waiting for interfaces: ('127.0.0.1', 60835)
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.RywnlK
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 81 tests in 978.355s
FAILED (SKIP=8, errors=2)
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
Build failed in Jenkins: tsqa-master #962
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/962/changes>
Changes:
[Leif Hedstrom] Reverse proxy mode - no need to set browser proxy
[Leif Hedstrom] Tiny edit to close the PR
[Bryan Call] Revert "TS-3984: CID 1328817: Resource leaks (CTOR_DTOR_LEAK) in multiplexer plugin"
------------------------------------------
[...truncated 527 lines...]
Age: 0
Connection: close
INFO 2015-10-29 22:04:31,388 - head request with case(TE) costs 0.049655 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 22:04:31,389 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:04:31 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:04:31,390 - head request with case(CL) costs 0.001105 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 22:04:31,407 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 22:04:31 GMT
Age: 0
Connection: close
INFO 2015-10-29 22:04:31,407 - head request with case() costs 0.017176 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-29 22:04:31,586 - Environment prefix is /tmp/tsqa.env.ceQT1P
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-29 22:04:35,131 - Environment prefix is /tmp/tsqa.env.Mu9vi1
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-29 22:04:40,685 - Environment prefix is /tmp/tsqa.env.MId8Te
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-29 22:04:50,201 - Environment prefix is /tmp/tsqa.env.VJhToC
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.VJhToC
--------------------- >> 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-29 22:04:50,282 - Environment prefix is /tmp/tsqa.env.5C5L8w
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.5C5L8w
--------------------- >> end captured logging << ---------------------
INFO 2015-10-29 22:04:50,377 - Environment prefix is /tmp/tsqa.env.6bMBEx
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-29 22:04:53,989 - Environment prefix is /tmp/tsqa.env.3W491h
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-29 22:04:57,566 - Environment prefix is /tmp/tsqa.env.tUEsuW
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-29 22:05:01,120 - Environment prefix is /tmp/tsqa.env.cxBZNV
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 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:05:04] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:05:04,591 - Environment prefix is /tmp/tsqa.env.bt1lMX
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-29 22:05:11,035 - Environment prefix is /tmp/tsqa.env.bh26pG
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-29 22:05:17,485 - Environment prefix is /tmp/tsqa.env.m6bWhE
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-29 22:05:38,991 - Environment prefix is /tmp/tsqa.env._oAb12
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-29 22:06:00,545 - Environment prefix is /tmp/tsqa.env.jYzcfl
ERROR
INFO 2015-10-29 22:06:05,756 - Environment prefix is /tmp/tsqa.env.oTsOvn
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-29 22:06:09,163 - Environment prefix is /tmp/tsqa.env.Y95ObF
INFO 2015-10-29 22:06:09,164 - socket_server_port = 44100
INFO 2015-10-29 22:06:09,165 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-29 22:06:12,494 - Sending data back to the client: d8d465fab42e4877ae90ea64163b6fff
INFO 2015-10-29 22:06:12,494 - d8d465fab42e4877ae90ea64163b6fff
INFO 2015-10-29 22:06:17,495 - Sending data back to the client: d8d465fab42e4877ae90ea64163b6fff
INFO 2015-10-29 22:06:17,496 - d8d465fab42e4877ae90ea64163b6fff
ok
INFO 2015-10-29 22:06:17,671 - Environment prefix is /tmp/tsqa.env.QNRb2i
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 22:06:21] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-29 22:06:21,258 - Environment prefix is /tmp/tsqa.env.nuhHAY
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-29 22:08:53,344 - 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-29 22:09:02,156 - Environment prefix is /tmp/tsqa.env.hbPs2s
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [29/Oct/2015 22:09:05] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:09:05] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:09:05] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 22:09:05,641 - Environment prefix is /tmp/tsqa.env.RrU1rK
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [29/Oct/2015 22:09:08] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:09:08] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 22:09:08] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-29 22:09:09,115 - Environment prefix is /tmp/tsqa.env.d1Dmf6
INFO 2015-10-29 22:09:09,116 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 22:09:09,116 - spdy server port = 54127
INFO 2015-10-29 22:09:09,117 - http server port = 57247
Test that the origin does in fact support spdy 3 ... INFO 2015-10-29 22:09:12,341 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54127/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 22:09:13,699 - full args = ['/usr/local/bin/spdycat', 'https://localhost:57247/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 22:09:13,893 - Environment prefix is /tmp/tsqa.env.unZdXX
INFO 2015-10-29 22:09:13,895 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 22:09:13,895 - spdy server port = 54754
INFO 2015-10-29 22:09:13,895 - http server port = 39566
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-29 22:09:17,122 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54754/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 22:09:18,500 - full args = ['/usr/local/bin/spdycat', 'https://localhost:39566/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 22:09:18,700 - Environment prefix is /tmp/tsqa.env.NRJLQM
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=41159): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.a2pr9F
test_chunked: INFO: sending data back to the client
test_chunked: INFO: Client disconnected
--------------------- >> end captured logging << ---------------------
======================================================================
ERROR: test suite for <class 'test_keepalive.TestKeepAlive_Authorization_no_private'>
----------------------------------------------------------------------
Traceback (most recent call last):
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 209, in run
self.setUp()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 292, in setUp
self.setupContext(ancestor)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 315, in setupContext
try_run(context, names)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/util.py",> line 471, in try_run
return func()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/test_cases.py",> line 86, in setUpClass
cls.environment.start()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 450, in start
self.__exec_cop()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 297, in __exec_cop
tsqa.utils.poll_interfaces(self.hostports)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/utils.py",> line 73, in poll_interfaces
reduce(lambda x, y: str(x) + ',' + str(y), hostports)))
Exception: Timeout waiting for interfaces: ('127.0.0.1', 59302)
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.jYzcfl
--------------------- >> end captured logging << ---------------------
======================================================================
FAIL: test_log_field (test_custom_log.TestCustomLogField)
----------------------------------------------------------------------
Traceback (most recent call last):
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_custom_log.py",> line 70, in test_log_field
self.assertEqual(line, expected_line)
AssertionError: '127.0.0.1 7F000001\n' != '127.140.237.124 7F8CED7C\n'
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.OuTQKB
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 81 tests in 826.205s
FAILED (SKIP=8, errors=2, failures=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
Build failed in Jenkins: tsqa-master #961
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/961/changes>
Changes:
[Bryan Call] TS-3984: CID 1328817: Resource leaks (CTOR_DTOR_LEAK) in multiplexer plugin
------------------------------------------
[...truncated 532 lines...]
Connection: close
INFO 2015-10-29 17:10:24,632 - head request with case(TE) costs 0.016439 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 17:10:24,669 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 17:10:24 GMT
Age: 0
Connection: close
INFO 2015-10-29 17:10:24,669 - head request with case(CL) costs 0.037390 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 17:10:24,680 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 17:10:24 GMT
Age: 0
Connection: close
INFO 2015-10-29 17:10:24,680 - head request with case() costs 0.010684 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-29 17:10:24,864 - Environment prefix is /tmp/tsqa.env.94mxG_
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-29 17:10:28,436 - Environment prefix is /tmp/tsqa.env.u49d7P
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-29 17:10:33,877 - Environment prefix is /tmp/tsqa.env.BHzg14
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-29 17:10:43,398 - Environment prefix is /tmp/tsqa.env.bP6qA7
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.bP6qA7
--------------------- >> 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-29 17:10:43,498 - Environment prefix is /tmp/tsqa.env.m5koEH
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.m5koEH
--------------------- >> end captured logging << ---------------------
INFO 2015-10-29 17:10:43,615 - Environment prefix is /tmp/tsqa.env.oKbecQ
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-29 17:10:47,215 - Environment prefix is /tmp/tsqa.env.hrB421
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-29 17:10:50,893 - Environment prefix is /tmp/tsqa.env.3hubqe
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-29 17:10:54,582 - Environment prefix is /tmp/tsqa.env.YsxNNR
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 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:10:57] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-29 17:10:58,100 - Environment prefix is /tmp/tsqa.env.9tDQtX
ERROR
INFO 2015-10-29 17:11:03,323 - Environment prefix is /tmp/tsqa.env.kgf5DM
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-29 17:11:10,623 - Environment prefix is /tmp/tsqa.env.LGZsOK
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-29 17:11:32,221 - Environment prefix is /tmp/tsqa.env.yVMv85
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-29 17:11:53,802 - Environment prefix is /tmp/tsqa.env.atS1k0
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-29 17:11:57,757 - Environment prefix is /tmp/tsqa.env.t79v7U
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-29 17:12:08,405 - Environment prefix is /tmp/tsqa.env.oY7ECx
INFO 2015-10-29 17:12:08,407 - socket_server_port = 34920
INFO 2015-10-29 17:12:08,407 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-29 17:12:11,748 - Sending data back to the client: 46cdef4b64c24e049ddba1eed8d27402
INFO 2015-10-29 17:12:11,749 - 46cdef4b64c24e049ddba1eed8d27402
INFO 2015-10-29 17:12:16,762 - Sending data back to the client: 46cdef4b64c24e049ddba1eed8d27402
INFO 2015-10-29 17:12:16,762 - 46cdef4b64c24e049ddba1eed8d27402
ok
INFO 2015-10-29 17:12:16,955 - Environment prefix is /tmp/tsqa.env.ePOppO
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:12:20] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-29 17:12:20,612 - Environment prefix is /tmp/tsqa.env.SHCMkW
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-29 17:14:57,957 - 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-29 17:15:08,205 - Environment prefix is /tmp/tsqa.env.8hoFtM
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [29/Oct/2015 17:15:11] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:15:11] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:15:11] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 17:15:11,821 - Environment prefix is /tmp/tsqa.env.lg7Mpy
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [29/Oct/2015 17:15:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:15:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:15:15] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-29 17:15:15,419 - Environment prefix is /tmp/tsqa.env.SqXsEW
INFO 2015-10-29 17:15:15,422 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 17:15:15,422 - spdy server port = 54462
INFO 2015-10-29 17:15:15,422 - http server port = 54200
Test that the origin does in fact support spdy 3 ... INFO 2015-10-29 17:15:19,456 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54462/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 17:15:20,190 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54200/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 17:15:20,397 - Environment prefix is /tmp/tsqa.env.Z1wWC9
INFO 2015-10-29 17:15:20,400 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 17:15:20,400 - spdy server port = 51043
INFO 2015-10-29 17:15:20,401 - http server port = 52269
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-29 17:15:23,630 - full args = ['/usr/local/bin/spdycat', 'https://localhost:51043/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 17:15:24,119 - full args = ['/usr/local/bin/spdycat', 'https://localhost:52269/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 17:15:24,333 - Environment prefix is /tmp/tsqa.env.8YhMM0
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=32936): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.PwfHZZ
test_chunked: INFO: sending data back to the client
test_chunked: INFO: Client disconnected
--------------------- >> end captured logging << ---------------------
======================================================================
ERROR: test suite for <class 'test_keepalive.TestKeepAliveOriginConnOutHTTP'>
----------------------------------------------------------------------
Traceback (most recent call last):
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 209, in run
self.setUp()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 292, in setUp
self.setupContext(ancestor)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 315, in setupContext
try_run(context, names)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/util.py",> line 471, in try_run
return func()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/test_cases.py",> line 86, in setUpClass
cls.environment.start()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 450, in start
self.__exec_cop()
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 297, in __exec_cop
tsqa.utils.poll_interfaces(self.hostports)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/utils.py",> line 73, in poll_interfaces
reduce(lambda x, y: str(x) + ',' + str(y), hostports)))
Exception: Timeout waiting for interfaces: ('127.0.0.1', 45534)
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.9tDQtX
--------------------- >> end captured logging << ---------------------
======================================================================
FAIL: test_log_field (test_custom_log.TestCustomLogField)
----------------------------------------------------------------------
Traceback (most recent call last):
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_custom_log.py",> line 70, in test_log_field
self.assertEqual(line, expected_line)
AssertionError: '127.0.0.1 7F000001\n' != '127.161.30.66 7FA11E42\n'
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.KgDWTJ
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 82 tests in 1302.009s
FAILED (SKIP=8, errors=2, failures=1)
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
Build failed in Jenkins: tsqa-master #960
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/960/changes>
Changes:
[Phil Sorber] TS-3989: Set affinity for ET_NET 0
[Phil Sorber] TS-3883: Fix madvise
------------------------------------------
[...truncated 516 lines...]
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-29 16:58:36,122 - Environment prefix is /tmp/tsqa.env.ogll4R
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [29/Oct/2015 16:58:39] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-29 16:58:39,644 - Environment prefix is /tmp/tsqa.env.KRwvIP
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-29 16:58:53,187 - Environment prefix is /tmp/tsqa.env.nx1NNW
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:58:56] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 16:58:56,728 - Environment prefix is /tmp/tsqa.env.tAkAEV
INFO 2015-10-29 16:58:56,731 - map / http://127.0.0.1:51281/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-29 16:59:00,051 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 16:59:00 GMT
Age: 0
Connection: close
INFO 2015-10-29 16:59:00,052 - head request with case(TE) costs 0.001805 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 16:59:00,053 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 16:59:00 GMT
Age: 0
Connection: close
INFO 2015-10-29 16:59:00,053 - head request with case(CL) costs 0.001160 seconds while the timout is 5.000000 seconds.
INFO 2015-10-29 16:59:00,073 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Thu, 29 Oct 2015 16:59:00 GMT
Age: 0
Connection: close
INFO 2015-10-29 16:59:00,073 - head request with case() costs 0.019855 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-29 16:59:00,248 - Environment prefix is /tmp/tsqa.env.sh3qvd
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-29 16:59:03,818 - Environment prefix is /tmp/tsqa.env.bg9KpX
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-29 16:59:09,348 - Environment prefix is /tmp/tsqa.env.471TGk
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-29 16:59:18,971 - Environment prefix is /tmp/tsqa.env.GLheMj
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.GLheMj
--------------------- >> 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-29 16:59:19,048 - Environment prefix is /tmp/tsqa.env.DRivFE
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.DRivFE
--------------------- >> end captured logging << ---------------------
INFO 2015-10-29 16:59:19,139 - Environment prefix is /tmp/tsqa.env.ZNg8Dy
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-29 16:59:22,656 - Environment prefix is /tmp/tsqa.env.kG4GfX
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-29 16:59:26,244 - Environment prefix is /tmp/tsqa.env.3LoKib
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-29 16:59:29,791 - Environment prefix is /tmp/tsqa.env.LCytJB
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 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 16:59:33] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-29 16:59:33,286 - Environment prefix is /tmp/tsqa.env.ipdZxP
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-29 16:59:39,737 - Environment prefix is /tmp/tsqa.env.zpdKDQ
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-29 16:59:47,365 - Environment prefix is /tmp/tsqa.env.WISaDw
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-29 17:00:11,294 - Environment prefix is /tmp/tsqa.env.CLntQs
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-29 17:00:32,810 - Environment prefix is /tmp/tsqa.env.UW9Lba
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-29 17:00:36,318 - Environment prefix is /tmp/tsqa.env.QtIxhi
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-29 17:00:39,798 - Environment prefix is /tmp/tsqa.env.Hiqk5Q
INFO 2015-10-29 17:00:39,800 - socket_server_port = 32962
INFO 2015-10-29 17:00:39,800 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-29 17:00:43,108 - Sending data back to the client: 2d235521a8c643c78b5fef60c9d3fa2a
INFO 2015-10-29 17:00:43,108 - 2d235521a8c643c78b5fef60c9d3fa2a
INFO 2015-10-29 17:00:48,114 - Sending data back to the client: 2d235521a8c643c78b5fef60c9d3fa2a
INFO 2015-10-29 17:00:48,115 - 2d235521a8c643c78b5fef60c9d3fa2a
ok
INFO 2015-10-29 17:00:48,293 - Environment prefix is /tmp/tsqa.env.ulDZQm
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [29/Oct/2015 17:00:51] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-29 17:00:51,766 - Environment prefix is /tmp/tsqa.env.6uEY1d
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-29 17:03:25,124 - 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-29 17:03:33,876 - Environment prefix is /tmp/tsqa.env.WuFduf
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [29/Oct/2015 17:03:37] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:03:37] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:03:37] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-29 17:03:37,412 - Environment prefix is /tmp/tsqa.env.pIwIkP
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [29/Oct/2015 17:03:40] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:03:40] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [29/Oct/2015 17:03:40] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-29 17:03:40,887 - Environment prefix is /tmp/tsqa.env.XPk4nt
INFO 2015-10-29 17:03:40,889 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 17:03:40,889 - spdy server port = 37521
INFO 2015-10-29 17:03:40,889 - http server port = 36356
Test that the origin does in fact support spdy 3 ... INFO 2015-10-29 17:03:44,124 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37521/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 17:03:45,462 - full args = ['/usr/local/bin/spdycat', 'https://localhost:36356/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 17:03:45,657 - Environment prefix is /tmp/tsqa.env.utGuiS
INFO 2015-10-29 17:03:45,659 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-29 17:03:45,659 - spdy server port = 58077
INFO 2015-10-29 17:03:45,659 - http server port = 48310
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-29 17:03:48,892 - full args = ['/usr/local/bin/spdycat', 'https://localhost:58077/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-29 17:03:50,705 - full args = ['/usr/local/bin/spdycat', 'https://localhost:48310/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-29 17:03:50,903 - Environment prefix is /tmp/tsqa.env.Xkk_oj
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 576, in send
r = adapter.send(request, **kwargs)
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/adapters.py",> line 435, in send
raise ReadTimeout(e, request=request)
ReadTimeout: HTTPConnectionPool(host='127.0.0.1', port=60818): Read timed out. (read timeout=2)
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.gAdrGw
--------------------- >> end captured logging << ---------------------
======================================================================
FAIL: test_log_field (test_custom_log.TestCustomLogField)
----------------------------------------------------------------------
Traceback (most recent call last):
File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_custom_log.py",> line 70, in test_log_field
self.assertEqual(line, expected_line)
AssertionError: '127.0.0.1 7F000001\n' != '127.178.11.96 7FB20B60\n'
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.h_Q7ek
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 861.398s
FAILED (SKIP=8, errors=1, failures=1)
----------------------------------------
Exception happened during processing of request from ('127.0.0.1', 55709)
----------------------------------------
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
Build failed in Jenkins: tsqa-master #959
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/959/changes>
Changes:
[Bryan Call] TS-3963: Response headers are not completely transferred
------------------------------------------
[...truncated 494 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-28 22:31:41,990 - Environment prefix is /tmp/tsqa.env.fw0Lct
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-28 22:33:25,522 - Environment prefix is /tmp/tsqa.env.8EJ09m
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-28 22:33:55,638 - 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-28 22:36:01,771 - 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-28 22:36:01,836 - Environment prefix is /tmp/tsqa.env.9wNPjg
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-28 22:36:05,239 - Environment prefix is /tmp/tsqa.env.zUDOin
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [28/Oct/2015 22:36:08] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-28 22:36:08,641 - Environment prefix is /tmp/tsqa.env.LDjaEA
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-28 22:36:22,107 - Environment prefix is /tmp/tsqa.env.zvknlg
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:36:25] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 22:36:25,561 - Environment prefix is /tmp/tsqa.env.kWrlqY
INFO 2015-10-28 22:36:25,564 - map / http://127.0.0.1:33166/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-28 22:36:28,811 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 22:36:28 GMT
Age: 0
Connection: close
INFO 2015-10-28 22:36:28,811 - head request with case(TE) costs 0.026237 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 22:36:28,812 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 22:36:28 GMT
Age: 0
Connection: close
INFO 2015-10-28 22:36:28,813 - head request with case(CL) costs 0.001225 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 22:36:28,832 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 22:36:28 GMT
Age: 0
Connection: close
INFO 2015-10-28 22:36:28,832 - head request with case() costs 0.019257 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-28 22:36:29,013 - Environment prefix is /tmp/tsqa.env.KUHlTC
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-28 22:36:32,452 - Environment prefix is /tmp/tsqa.env.3BYjkX
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-28 22:36:37,878 - Environment prefix is /tmp/tsqa.env.aaq7wV
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-28 22:36:47,359 - Environment prefix is /tmp/tsqa.env.sCPugi
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.sCPugi
--------------------- >> 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-28 22:36:47,437 - Environment prefix is /tmp/tsqa.env.s62FCS
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.s62FCS
--------------------- >> end captured logging << ---------------------
INFO 2015-10-28 22:36:47,529 - Environment prefix is /tmp/tsqa.env.cIe5bU
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-28 22:36:51,036 - Environment prefix is /tmp/tsqa.env.icce_u
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-28 22:36:54,662 - Environment prefix is /tmp/tsqa.env.wMkq1s
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-28 22:36:58,203 - Environment prefix is /tmp/tsqa.env.xwisPF
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 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:37:01] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-28 22:37:01,616 - Environment prefix is /tmp/tsqa.env.iTszFr
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-28 22:37:08,025 - Environment prefix is /tmp/tsqa.env.sllubJ
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-28 22:37:14,495 - Environment prefix is /tmp/tsqa.env.pXWsmO
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-28 22:37:35,991 - Environment prefix is /tmp/tsqa.env.mDXcpE
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-28 22:37:57,618 - Environment prefix is /tmp/tsqa.env.dslPwQ
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-28 22:38:01,111 - Environment prefix is /tmp/tsqa.env.j7c134
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-28 22:38:04,569 - Environment prefix is /tmp/tsqa.env.53rQeC
INFO 2015-10-28 22:38:04,571 - socket_server_port = 48742
INFO 2015-10-28 22:38:04,571 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-28 22:38:07,899 - Sending data back to the client: 918b89b87c794875b807952d23640665
INFO 2015-10-28 22:38:07,900 - 918b89b87c794875b807952d23640665
INFO 2015-10-28 22:38:12,904 - Sending data back to the client: 918b89b87c794875b807952d23640665
INFO 2015-10-28 22:38:12,904 - 918b89b87c794875b807952d23640665
ok
INFO 2015-10-28 22:38:13,079 - Environment prefix is /tmp/tsqa.env.QoO_cB
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 22:38:16] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-28 22:38:16,538 - Environment prefix is /tmp/tsqa.env.I4XAEg
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-28 22:40:49,804 - 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-28 22:40:58,645 - Environment prefix is /tmp/tsqa.env.07a6cg
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [28/Oct/2015 22:41:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:41:01] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:41:01] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 22:41:02,074 - Environment prefix is /tmp/tsqa.env.Ss8YrL
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [28/Oct/2015 22:41:05] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:41:05] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 22:41:05] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-28 22:41:05,536 - Environment prefix is /tmp/tsqa.env.m6Tcpn
INFO 2015-10-28 22:41:05,538 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 22:41:05,538 - spdy server port = 33736
INFO 2015-10-28 22:41:05,538 - http server port = 35435
Test that the origin does in fact support spdy 3 ... INFO 2015-10-28 22:41:08,763 - full args = ['/usr/local/bin/spdycat', 'https://localhost:33736/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 22:41:10,159 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35435/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 22:41:10,351 - Environment prefix is /tmp/tsqa.env.gozrXS
INFO 2015-10-28 22:41:10,353 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 22:41:10,353 - spdy server port = 35762
INFO 2015-10-28 22:41:10,353 - http server port = 43229
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-28 22:41:13,577 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35762/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 22:41:15,361 - full args = ['/usr/local/bin/spdycat', 'https://localhost:43229/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 22:41:15,559 - Environment prefix is /tmp/tsqa.env.WhjJGX
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=53167): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.1maEnt
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 1289.428s
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 #958
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/958/changes>
Changes:
[kichan] TS-3876: add unit test framework info for lua scripts for ts_lua plugin
------------------------------------------
[...truncated 494 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-28 06:38:35,023 - Environment prefix is /tmp/tsqa.env.gcAX6m
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-28 06:40:18,716 - Environment prefix is /tmp/tsqa.env.JEQhZY
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-28 06:40:49,553 - 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-28 06:41:40,879 - 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-28 06:41:40,947 - Environment prefix is /tmp/tsqa.env.7PkmIM
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-28 06:41:44,361 - Environment prefix is /tmp/tsqa.env.Hmjg87
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [28/Oct/2015 06:41:47] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-28 06:41:47,793 - Environment prefix is /tmp/tsqa.env.hN3Z1i
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-28 06:42:01,370 - Environment prefix is /tmp/tsqa.env.w1Jeft
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:04] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 06:42:04,824 - Environment prefix is /tmp/tsqa.env.IZmWLH
INFO 2015-10-28 06:42:04,827 - map / http://127.0.0.1:39018/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-28 06:42:08,069 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 06:42:08 GMT
Age: 0
Connection: close
INFO 2015-10-28 06:42:08,069 - head request with case(TE) costs 0.016514 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 06:42:08,071 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 06:42:08 GMT
Age: 0
Connection: close
INFO 2015-10-28 06:42:08,071 - head request with case(CL) costs 0.001660 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 06:42:08,072 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 06:42:08 GMT
Age: 0
Connection: close
INFO 2015-10-28 06:42:08,072 - head request with case() costs 0.001160 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-28 06:42:08,263 - Environment prefix is /tmp/tsqa.env.k71iiK
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-28 06:42:11,720 - Environment prefix is /tmp/tsqa.env.mVX9Dn
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-28 06:42:17,260 - Environment prefix is /tmp/tsqa.env.hR9XUm
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-28 06:42:26,847 - Environment prefix is /tmp/tsqa.env.95Masd
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.95Masd
--------------------- >> 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-28 06:42:26,934 - Environment prefix is /tmp/tsqa.env.onIGki
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.onIGki
--------------------- >> end captured logging << ---------------------
INFO 2015-10-28 06:42:27,033 - Environment prefix is /tmp/tsqa.env.SEYI_M
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-28 06:42:30,550 - Environment prefix is /tmp/tsqa.env.bfh9ip
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-28 06:42:34,076 - Environment prefix is /tmp/tsqa.env.guSNPn
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-28 06:42:37,716 - Environment prefix is /tmp/tsqa.env.91jqxJ
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 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:42:40] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-28 06:42:41,145 - Environment prefix is /tmp/tsqa.env.FrOuWE
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-28 06:42:47,620 - Environment prefix is /tmp/tsqa.env.r3ydDJ
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-28 06:42:54,084 - Environment prefix is /tmp/tsqa.env.yQ1BB_
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-28 06:43:15,574 - Environment prefix is /tmp/tsqa.env.nXDYzq
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-28 06:43:37,077 - Environment prefix is /tmp/tsqa.env.mkCv8r
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-28 06:43:40,577 - Environment prefix is /tmp/tsqa.env.MoHoCE
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-28 06:43:44,063 - Environment prefix is /tmp/tsqa.env.g0qO4A
INFO 2015-10-28 06:43:44,065 - socket_server_port = 34437
INFO 2015-10-28 06:43:44,065 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-28 06:43:47,302 - Sending data back to the client: 545f0899cfbe43e591cc615e908a45ab
INFO 2015-10-28 06:43:47,303 - 545f0899cfbe43e591cc615e908a45ab
INFO 2015-10-28 06:43:52,312 - Sending data back to the client: 545f0899cfbe43e591cc615e908a45ab
INFO 2015-10-28 06:43:52,312 - 545f0899cfbe43e591cc615e908a45ab
ok
INFO 2015-10-28 06:43:52,504 - Environment prefix is /tmp/tsqa.env.SWblXj
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 06:43:55] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-28 06:43:55,981 - Environment prefix is /tmp/tsqa.env.ffqStm
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-28 06:46:30,159 - 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-28 06:46:39,662 - Environment prefix is /tmp/tsqa.env.2FwDRQ
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [28/Oct/2015 06:46:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:46:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:46:42] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 06:46:43,177 - Environment prefix is /tmp/tsqa.env.IZHAaW
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [28/Oct/2015 06:46:46] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:46:46] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 06:46:46] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-28 06:46:46,672 - Environment prefix is /tmp/tsqa.env.JpzS6V
INFO 2015-10-28 06:46:46,674 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 06:46:46,674 - spdy server port = 48915
INFO 2015-10-28 06:46:46,675 - http server port = 35557
Test that the origin does in fact support spdy 3 ... INFO 2015-10-28 06:46:49,908 - full args = ['/usr/local/bin/spdycat', 'https://localhost:48915/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 06:46:50,986 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35557/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 06:46:51,194 - Environment prefix is /tmp/tsqa.env.1ziVtO
INFO 2015-10-28 06:46:51,196 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 06:46:51,196 - spdy server port = 46626
INFO 2015-10-28 06:46:51,197 - http server port = 53383
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-28 06:46:54,439 - full args = ['/usr/local/bin/spdycat', 'https://localhost:46626/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 06:46:55,486 - full args = ['/usr/local/bin/spdycat', 'https://localhost:53383/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 06:46:55,701 - Environment prefix is /tmp/tsqa.env.wsr9W6
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=59059): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.PqXTyD
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 922.107s
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 #957
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/957/>
------------------------------------------
[...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-28 02:28:01,637 - Environment prefix is /tmp/tsqa.env.poCbhU
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-28 02:29:45,193 - Environment prefix is /tmp/tsqa.env.PPdttS
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-28 02:30:17,093 - 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-28 02:31:21,044 - 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-28 02:31:21,112 - Environment prefix is /tmp/tsqa.env.ZyEwTx
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-28 02:31:24,524 - Environment prefix is /tmp/tsqa.env.YwLUGT
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [28/Oct/2015 02:31:27] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-28 02:31:27,993 - Environment prefix is /tmp/tsqa.env.rYGsRr
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-28 02:31:41,494 - Environment prefix is /tmp/tsqa.env.B3KN6F
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:44] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 02:31:45,001 - Environment prefix is /tmp/tsqa.env.rw8aK6
INFO 2015-10-28 02:31:45,004 - map / http://127.0.0.1:45845/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-28 02:31:48,296 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 02:31:48 GMT
Age: 0
Connection: close
INFO 2015-10-28 02:31:48,296 - head request with case(TE) costs 0.058668 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 02:31:48,307 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 02:31:48 GMT
Age: 0
Connection: close
INFO 2015-10-28 02:31:48,308 - head request with case(CL) costs 0.011185 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 02:31:48,323 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 02:31:48 GMT
Age: 0
Connection: close
INFO 2015-10-28 02:31:48,323 - head request with case() costs 0.015593 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-28 02:31:48,506 - Environment prefix is /tmp/tsqa.env.IY7xwD
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-28 02:31:51,958 - Environment prefix is /tmp/tsqa.env.L4QeRV
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-28 02:31:57,457 - Environment prefix is /tmp/tsqa.env.b54UAK
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-28 02:32:06,942 - Environment prefix is /tmp/tsqa.env.JUIKzF
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.JUIKzF
--------------------- >> 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-28 02:32:07,029 - Environment prefix is /tmp/tsqa.env.NKXM1T
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.NKXM1T
--------------------- >> end captured logging << ---------------------
INFO 2015-10-28 02:32:07,124 - Environment prefix is /tmp/tsqa.env.rUVD6D
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-28 02:32:10,741 - Environment prefix is /tmp/tsqa.env.vW9WvS
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-28 02:32:14,371 - Environment prefix is /tmp/tsqa.env.gMw2QL
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-28 02:32:18,013 - Environment prefix is /tmp/tsqa.env.LNggFK
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 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:32:21] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-28 02:32:21,438 - Environment prefix is /tmp/tsqa.env.1C_mqA
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-28 02:32:27,967 - Environment prefix is /tmp/tsqa.env.dF3Olc
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-28 02:32:34,593 - Environment prefix is /tmp/tsqa.env.CpOaVH
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-28 02:32:56,134 - Environment prefix is /tmp/tsqa.env.QM_O0B
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-28 02:33:17,733 - Environment prefix is /tmp/tsqa.env.O9bbI4
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-28 02:33:21,381 - Environment prefix is /tmp/tsqa.env.CO7Seb
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-28 02:33:24,848 - Environment prefix is /tmp/tsqa.env.JH44IY
INFO 2015-10-28 02:33:24,850 - socket_server_port = 53361
INFO 2015-10-28 02:33:24,850 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-28 02:33:28,099 - Sending data back to the client: 6b1faed58bad4fa6aad12ed796093f8b
INFO 2015-10-28 02:33:28,107 - 6b1faed58bad4fa6aad12ed796093f8b
INFO 2015-10-28 02:33:33,112 - Sending data back to the client: 6b1faed58bad4fa6aad12ed796093f8b
INFO 2015-10-28 02:33:33,113 - 6b1faed58bad4fa6aad12ed796093f8b
ok
INFO 2015-10-28 02:33:33,302 - Environment prefix is /tmp/tsqa.env.DK7d3N
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:33:36] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-28 02:33:36,868 - Environment prefix is /tmp/tsqa.env._PZjkH
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-28 02:36:11,061 - 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-28 02:36:20,782 - Environment prefix is /tmp/tsqa.env.Bn36EE
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [28/Oct/2015 02:36:24] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:36:24] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:36:24] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 02:36:24,307 - Environment prefix is /tmp/tsqa.env.zyqRdP
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [28/Oct/2015 02:36:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:36:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:36:27] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-28 02:36:27,861 - Environment prefix is /tmp/tsqa.env.8G6k6k
INFO 2015-10-28 02:36:27,863 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 02:36:27,863 - spdy server port = 47313
INFO 2015-10-28 02:36:27,863 - http server port = 38403
Test that the origin does in fact support spdy 3 ... INFO 2015-10-28 02:36:31,090 - full args = ['/usr/local/bin/spdycat', 'https://localhost:47313/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 02:36:31,570 - full args = ['/usr/local/bin/spdycat', 'https://localhost:38403/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 02:36:31,767 - Environment prefix is /tmp/tsqa.env.N78rlx
INFO 2015-10-28 02:36:31,769 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 02:36:31,769 - spdy server port = 35689
INFO 2015-10-28 02:36:31,769 - http server port = 45165
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-28 02:36:34,994 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35689/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 02:36:35,529 - full args = ['/usr/local/bin/spdycat', 'https://localhost:45165/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 02:36:35,732 - Environment prefix is /tmp/tsqa.env.8waxAP
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=53880): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.kazGcf
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 976.744s
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 #956
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/956/changes>
Changes:
[James Peach] s/traffic_line/traffic_ctl/
[James Peach] TS-3827: Cleanup comments related to HTTP/2
[James Peach] TS-3827: Cleanup comments related to HPACK
------------------------------------------
[...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-28 02:27:21,856 - Environment prefix is /tmp/tsqa.env.cu87xT
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-28 02:29:05,445 - Environment prefix is /tmp/tsqa.env.dpWYWT
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-28 02:29:36,092 - 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-28 02:30:34,709 - 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-28 02:30:34,777 - Environment prefix is /tmp/tsqa.env.5NHbhl
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-28 02:30:38,186 - Environment prefix is /tmp/tsqa.env.pVWs4B
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [28/Oct/2015 02:30:41] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-28 02:30:41,600 - Environment prefix is /tmp/tsqa.env.475lNE
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-28 02:30:55,098 - Environment prefix is /tmp/tsqa.env.rpQseK
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:30:58] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 02:30:58,561 - Environment prefix is /tmp/tsqa.env.v3GZQn
INFO 2015-10-28 02:30:58,563 - map / http://127.0.0.1:52276/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-28 02:31:01,804 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 02:31:01 GMT
Age: 0
Connection: close
INFO 2015-10-28 02:31:01,804 - head request with case(TE) costs 0.014988 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 02:31:01,808 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 02:31:01 GMT
Age: 0
Connection: close
INFO 2015-10-28 02:31:01,808 - head request with case(CL) costs 0.004120 seconds while the timout is 5.000000 seconds.
INFO 2015-10-28 02:31:01,821 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Wed, 28 Oct 2015 02:31:01 GMT
Age: 0
Connection: close
INFO 2015-10-28 02:31:01,822 - head request with case() costs 0.013454 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-28 02:31:02,012 - Environment prefix is /tmp/tsqa.env.WmxxIW
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-28 02:31:05,504 - Environment prefix is /tmp/tsqa.env.t1fb4U
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-28 02:31:10,932 - Environment prefix is /tmp/tsqa.env.owgApa
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-28 02:31:20,451 - Environment prefix is /tmp/tsqa.env.bbJBga
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.bbJBga
--------------------- >> 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-28 02:31:20,542 - Environment prefix is /tmp/tsqa.env.qKAGZg
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.qKAGZg
--------------------- >> end captured logging << ---------------------
INFO 2015-10-28 02:31:20,649 - Environment prefix is /tmp/tsqa.env.Clomec
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-28 02:31:24,289 - Environment prefix is /tmp/tsqa.env.LSspUM
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-28 02:31:27,883 - Environment prefix is /tmp/tsqa.env.NUmBfd
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-28 02:31:31,431 - Environment prefix is /tmp/tsqa.env.TfLOlx
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 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:31:34] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-28 02:31:34,863 - Environment prefix is /tmp/tsqa.env.XZe61k
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-28 02:31:41,357 - Environment prefix is /tmp/tsqa.env.Bnjv7d
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-28 02:31:47,791 - Environment prefix is /tmp/tsqa.env.MjTTKe
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-28 02:32:09,246 - Environment prefix is /tmp/tsqa.env.TjrNag
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-28 02:32:30,782 - Environment prefix is /tmp/tsqa.env.SNGbfD
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-28 02:32:34,233 - Environment prefix is /tmp/tsqa.env.1JdTo2
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-28 02:32:37,662 - Environment prefix is /tmp/tsqa.env.z6OoIy
INFO 2015-10-28 02:32:37,664 - socket_server_port = 52607
INFO 2015-10-28 02:32:37,664 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-28 02:32:40,904 - Sending data back to the client: d2d3282a2a9c44b39d144d4169affdb8
INFO 2015-10-28 02:32:40,904 - d2d3282a2a9c44b39d144d4169affdb8
INFO 2015-10-28 02:32:45,906 - Sending data back to the client: d2d3282a2a9c44b39d144d4169affdb8
INFO 2015-10-28 02:32:45,906 - d2d3282a2a9c44b39d144d4169affdb8
ok
INFO 2015-10-28 02:32:46,090 - Environment prefix is /tmp/tsqa.env.2MdRoi
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [28/Oct/2015 02:32:49] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-28 02:32:49,551 - Environment prefix is /tmp/tsqa.env.ymDeAS
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-28 02:35:23,576 - 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-28 02:35:32,872 - Environment prefix is /tmp/tsqa.env.Rm9N3S
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [28/Oct/2015 02:35:36] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:35:36] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:35:36] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-28 02:35:36,404 - Environment prefix is /tmp/tsqa.env.qbg6Jq
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [28/Oct/2015 02:35:39] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:35:39] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [28/Oct/2015 02:35:39] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-28 02:35:39,842 - Environment prefix is /tmp/tsqa.env.AlaZks
INFO 2015-10-28 02:35:39,844 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 02:35:39,844 - spdy server port = 51959
INFO 2015-10-28 02:35:39,844 - http server port = 39696
Test that the origin does in fact support spdy 3 ... INFO 2015-10-28 02:35:43,070 - full args = ['/usr/local/bin/spdycat', 'https://localhost:51959/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 02:35:44,199 - full args = ['/usr/local/bin/spdycat', 'https://localhost:39696/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 02:35:44,401 - Environment prefix is /tmp/tsqa.env.v86_KP
INFO 2015-10-28 02:35:44,403 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-28 02:35:44,403 - spdy server port = 37716
INFO 2015-10-28 02:35:44,403 - http server port = 51490
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-28 02:35:47,630 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37716/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-28 02:35:48,960 - full args = ['/usr/local/bin/spdycat', 'https://localhost:51490/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-28 02:35:49,165 - Environment prefix is /tmp/tsqa.env.ERjNhT
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=60158): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.AUs9uc
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 957.021s
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 #955
Posted by je...@ci.trafficserver.apache.org.
See <https://ci.trafficserver.apache.org/job/tsqa-master/955/>
------------------------------------------
[...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 22:05:04,631 - Environment prefix is /tmp/tsqa.env.VzGrOh
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 22:06:48,289 - Environment prefix is /tmp/tsqa.env.fq__B2
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 22:07:20,779 - 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 22:08:18,723 - 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 22:08:18,787 - Environment prefix is /tmp/tsqa.env.1z4PF3
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 22:08:22,304 - Environment prefix is /tmp/tsqa.env.h1Hsva
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [26/Oct/2015 22:08:25] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 22:08:25,868 - Environment prefix is /tmp/tsqa.env.wzQBzo
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 22:08:39,429 - Environment prefix is /tmp/tsqa.env.cH8YMs
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:08:42] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 22:08:42,968 - Environment prefix is /tmp/tsqa.env.RxgMUl
INFO 2015-10-26 22:08:42,970 - map / http://127.0.0.1:60181/
test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26 22:08:46,259 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 22:08:46 GMT
Age: 0
Connection: close
INFO 2015-10-26 22:08:46,259 - head request with case(TE) costs 0.062911 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 22:08:46,277 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 22:08:46 GMT
Age: 0
Connection: close
INFO 2015-10-26 22:08:46,277 - head request with case(CL) costs 0.017816 seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 22:08:46,278 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 22:08:46 GMT
Age: 0
Connection: close
INFO 2015-10-26 22:08:46,278 - head request with case() costs 0.001113 seconds while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 22:08:46,462 - Environment prefix is /tmp/tsqa.env.EQzVd2
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 22:08:50,566 - Environment prefix is /tmp/tsqa.env.0QysFa
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 22:08:56,011 - Environment prefix is /tmp/tsqa.env.v4l_Fi
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 22:09:05,591 - Environment prefix is /tmp/tsqa.env.1nKIcV
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.1nKIcV
--------------------- >> 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 22:09:05,673 - Environment prefix is /tmp/tsqa.env.yim5Ym
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.yim5Ym
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 22:09:05,771 - Environment prefix is /tmp/tsqa.env.LgenMd
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 22:09:09,354 - Environment prefix is /tmp/tsqa.env.xQ69JJ
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 22:09:13,001 - Environment prefix is /tmp/tsqa.env.kf3RSa
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 22:09:16,737 - Environment prefix is /tmp/tsqa.env.F3W4hU
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 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:09:20] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 22:09:20,265 - Environment prefix is /tmp/tsqa.env.zJz7P2
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 22:09:26,776 - Environment prefix is /tmp/tsqa.env.QQCmtL
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 22:09:33,269 - Environment prefix is /tmp/tsqa.env.VU6SJx
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 22:09:54,801 - Environment prefix is /tmp/tsqa.env.MBVfmp
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 22:10:16,422 - Environment prefix is /tmp/tsqa.env.5BeFET
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 22:10:20,030 - Environment prefix is /tmp/tsqa.env.Rd1Oh9
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 22:10:23,592 - Environment prefix is /tmp/tsqa.env.l_BXlg
INFO 2015-10-26 22:10:23,595 - socket_server_port = 40154
INFO 2015-10-26 22:10:23,595 - starting the socket server
test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-10-26 22:10:26,974 - Sending data back to the client: a31c042eb4c34cdd931ec16597539ffa
INFO 2015-10-26 22:10:26,976 - a31c042eb4c34cdd931ec16597539ffa
INFO 2015-10-26 22:10:31,983 - Sending data back to the client: a31c042eb4c34cdd931ec16597539ffa
INFO 2015-10-26 22:10:31,983 - a31c042eb4c34cdd931ec16597539ffa
ok
INFO 2015-10-26 22:10:32,166 - Environment prefix is /tmp/tsqa.env.1nLzhG
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 22:10:35] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 22:10:35,772 - Environment prefix is /tmp/tsqa.env.6aETjY
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 22:13:12,370 - 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 22:13:22,554 - Environment prefix is /tmp/tsqa.env.p6wwka
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015 22:13:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:13:25] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:13:25] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 22:13:26,115 - Environment prefix is /tmp/tsqa.env.DoE916
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015 22:13:29] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:13:29] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 22:13:29] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 22:13:29,768 - Environment prefix is /tmp/tsqa.env.I31ypc
INFO 2015-10-26 22:13:29,770 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 22:13:29,771 - spdy server port = 55367
INFO 2015-10-26 22:13:29,771 - http server port = 32986
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26 22:13:33,002 - full args = ['/usr/local/bin/spdycat', 'https://localhost:55367/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 22:13:33,527 - full args = ['/usr/local/bin/spdycat', 'https://localhost:32986/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 22:13:33,733 - Environment prefix is /tmp/tsqa.env.b19Z_R
INFO 2015-10-26 22:13:33,736 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 22:13:33,736 - spdy server port = 46967
INFO 2015-10-26 22:13:33,736 - http server port = 48404
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26 22:13:36,971 - full args = ['/usr/local/bin/spdycat', 'https://localhost:46967/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 22:13:37,462 - full args = ['/usr/local/bin/spdycat', 'https://localhost:48404/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 22:13:37,667 - Environment prefix is /tmp/tsqa.env.AqGs6E
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=50047): Read timed out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.oSJp_7
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 963.192s
FAILED (SKIP=8, errors=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results