See <https://ci.trafficserver.apache.org/job/tsqa-master/950/changes>
Changes:
[Leif Hedstrom] TS-3969 Fixes Debug() output to use the right event check
------------------------------------------
[...truncated 491 lines...]
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes
are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-10-26 20:48:24,675 - Environment prefix is /tmp/tsqa.env.eg54Ks
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-10-26 20:50:08,332 - Environment prefix is /tmp/tsqa.env.8tAK9b
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-10-26 20:50:39,344 - Starting build
(1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None,
'enable-ccache': None, 'enable-experimental-plugins': None,
'enable-example-plugins': None, 'enable-test-tools': None,
'disable-dependency-tracking': None}
INFO 2015-10-26 20:51:34,470 - Build completed
(1b6c9541459e058351cd39ec55dcb772): configure {'enable-spdy': None,
'enable-ccache': None, 'enable-experimental-plugins': None,
'enable-example-plugins': None, 'enable-test-tools': None,
'disable-dependency-tracking': None}
INFO 2015-10-26 20:51:34,539 - Environment prefix is /tmp/tsqa.env.5AFiFQ
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-10-26 20:51:38,072 - Environment prefix is /tmp/tsqa.env.ifk9iD
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - -
[26/Oct/2015 20:51:41] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-10-26 20:51:41,596 - Environment prefix is /tmp/tsqa.env.xAd8ZO
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-10-26 20:51:55,227 - Environment prefix is /tmp/tsqa.env.o0hdQl
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - -
[26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:51:58] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:51:58,804 - Environment prefix is /tmp/tsqa.env.zP_6k5
INFO 2015-10-26 20:51:58,806 - map / http://127.0.0.1:37875/
test_head_request_without_timout
(test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-10-26
20:52:02,102 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:02 GMT
Age: 0
Connection: close
INFO 2015-10-26 20:52:02,103 - head request with case(TE) costs 0.061108
seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 20:52:02,107 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:02 GMT
Age: 0
Connection: close
INFO 2015-10-26 20:52:02,107 - head request with case(CL) costs 0.004120
seconds while the timout is 5.000000 seconds.
INFO 2015-10-26 20:52:02,124 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 26 Oct 2015 20:52:02 GMT
Age: 0
Connection: close
INFO 2015-10-26 20:52:02,125 - head request with case() costs 0.017589 seconds
while the timout is 5.000000 seconds.
ok
INFO 2015-10-26 20:52:02,315 - Environment prefix is /tmp/tsqa.env.LNDRSo
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-10-26 20:52:05,853 - Environment prefix is /tmp/tsqa.env.pqfoEO
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-10-26 20:52:11,309 - Environment prefix is /tmp/tsqa.env.8TGft1
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-10-26 20:52:20,907 - Environment prefix is /tmp/tsqa.env.TUnnIk
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.TUnnIk
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP:
Cannot import hyper, skipping tests for HTTP/2
INFO 2015-10-26 20:52:20,992 - Environment prefix is /tmp/tsqa.env.TA2It6
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.TA2It6
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 20:52:21,098 - Environment prefix is /tmp/tsqa.env.l4Uecf
test_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestECDSA) ... ok
test_intermediate_ca_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestECDSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-26 20:52:24,791 - Environment prefix is /tmp/tsqa.env.UZQt9t
test_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_ecdsa (test_https.TestMix) ... ok
test_intermediate_ca_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestMix) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-26 20:52:28,419 - Environment prefix is /tmp/tsqa.env.9k45ar
test_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_ecdsa (test_https.TestRSA) ... ok
test_intermediate_ca_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
test_rsa (test_https.TestRSA) ... ok
We should be served the first match, since we aren't sending SNI headers ... ok
Make sure we get the certificate we asked for if we pass in SNI headers ... ok
INFO 2015-10-26 20:52:32,093 - Environment prefix is /tmp/tsqa.env.JnCQ9j
test_error_path (test_keepalive.TestKeepAliveInHTTP) ... ok
Ensure that sending a request with a body doesn't break the keepalive session
... ok
test_working_path (test_keepalive.TestKeepAliveInHTTP) ... 127.0.0.1 - -
[26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:52:35] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:52:35,532 - Environment prefix is /tmp/tsqa.env.h6i2E0
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-10-26 20:52:42,065 - Environment prefix is /tmp/tsqa.env.d9Ndnl
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-10-26 20:52:48,605 - Environment prefix is /tmp/tsqa.env.E5gGLu
Test that the origin does in fact support keepalive via http. ... ok
Tests that keepalive works through ATS to origin via http. ... ok
Tests that origin does not timeout using keepalive via http. ... ok
Tests that keepalive timeout is honored through ATS to origin via http. ... ok
INFO 2015-10-26 20:53:10,281 - Environment prefix is /tmp/tsqa.env.ae_u_E
Test that the origin does in fact support keepalive via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
Tests that origin does not timeout using keepalive via https. ... ok
Tests that keepalive timeout is honored through ATS to origin via https. ... ok
INFO 2015-10-26 20:53:31,919 - Environment prefix is /tmp/tsqa.env.R0iw5i
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-26 20:53:35,374 - Environment prefix is /tmp/tsqa.env.FufbKv
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-10-26 20:53:38,856 - Environment prefix is /tmp/tsqa.env.sLi_zW
INFO 2015-10-26 20:53:38,858 - socket_server_port = 51033
INFO 2015-10-26 20:53:38,858 - starting the socket server
test_origin_min_connection
(test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections)
... INFO 2015-10-26 20:53:42,125 - Sending data back to the client:
a4f7bd5fed054056afea398ea9ae5f1c
INFO 2015-10-26 20:53:42,126 - a4f7bd5fed054056afea398ea9ae5f1c
INFO 2015-10-26 20:53:47,132 - Sending data back to the client:
a4f7bd5fed054056afea398ea9ae5f1c
INFO 2015-10-26 20:53:47,132 - a4f7bd5fed054056afea398ea9ae5f1c
ok
INFO 2015-10-26 20:53:47,315 - Environment prefix is /tmp/tsqa.env.hCouFC
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - -
[26/Oct/2015 20:53:50] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /get HTTP/1.1" 200 391
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [26/Oct/2015 20:53:50] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-10-26 20:53:50,981 - Environment prefix is /tmp/tsqa.env.SdCohJ
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-10-26 20:56:25,972 - Starting build
(d133993325226bee52737bbab4e1cbc1): configure {'enable-ccache': None,
'enable-experimental-plugins': None, 'enable-example-plugins': None,
'enable-test-tools': None, 'enable-linux-native-aio': None,
'disable-dependency-tracking': None}
SKIP: Error 1 running:
['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',>
'--prefix=/', '--enable-ccache', '--enable-experimental-plugins',
'--enable-example-plugins', '--enable-test-tools', '--enable-linux-native-aio',
'--disable-dependency-tracking']
configure: error: Linux native AIO requires libaio.h
-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (d133993325226bee52737bbab4e1cbc1):
configure {'enable-ccache': None, 'enable-experimental-plugins': None,
'enable-example-plugins': None, 'enable-test-tools': None,
'enable-linux-native-aio': None, 'disable-dependency-tracking': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-10-26 20:56:35,246 - Environment prefix is /tmp/tsqa.env.NA3_AO
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [26/Oct/2015
20:56:38] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:38] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:38] "GET / HTTP/1.1" 200 5
ok
INFO 2015-10-26 20:56:38,789 - Environment prefix is /tmp/tsqa.env.0KmNc5
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [26/Oct/2015
20:56:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:42] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [26/Oct/2015 20:56:42] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-10-26 20:56:42,345 - Environment prefix is /tmp/tsqa.env.uS_lup
INFO 2015-10-26 20:56:42,347 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 20:56:42,347 - spdy server port = 40167
INFO 2015-10-26 20:56:42,347 - http server port = 37992
Test that the origin does in fact support spdy 3 ... INFO 2015-10-26
20:56:45,588 - full args = ['/usr/local/bin/spdycat',
'https://localhost:40167/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 20:56:46,962 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:37992/', '-nv',
'--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 20:56:47,185 - Environment prefix is /tmp/tsqa.env.Sc5q51
INFO 2015-10-26 20:56:47,186 - spdycat path = /usr/local/bin/spdycat
INFO 2015-10-26 20:56:47,187 - spdy server port = 49464
INFO 2015-10-26 20:56:47,187 - http server port = 47921
Test that the origin does in fact support spdy 3.1 ... INFO 2015-10-26
20:56:50,446 - full args = ['/usr/local/bin/spdycat',
'https://localhost:49464/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-10-26 20:56:52,355 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:47921/', '-nv',
'--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-10-26 20:56:52,574 - Environment prefix is /tmp/tsqa.env.cvjliX
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok
======================================================================
ERROR: test_chunked_bad_close (test_chunked.TestChunked)
----------------------------------------------------------------------
Traceback (most recent call last):
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_chunked.py";,>
line 198, in test_chunked_bad_close
requests.get(url, proxies=self.proxies, timeout=2)
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py";,>
line 69, in get
return request('get', url, params=params, **kwargs)
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/api.py";,>
line 50, in request
response = session.request(method=method, url=url, **kwargs)
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py";,>
line 468, in request
resp = self.send(prep, **send_kwargs)
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/sessions.py";,>
line 608, in send
r.content
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py";,>
line 734, in content
self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or
bytes()
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/requests/models.py";,>
line 664, in generate
raise ConnectionError(e)
ConnectionError: HTTPConnectionPool(host='127.0.0.1', port=39306): Read timed
out.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.JAmAl3
test_chunked: INFO: sending data back to the client
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML:
<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 941.468s
FAILED (SKIP=8, errors=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
