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
