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/05/09 07:16:01 UTC

Build failed in Jenkins: tsqa-master #383

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

Changes:

[Phil Sorber] TS-3592: Set cacheurl default config location to config directory

------------------------------------------
[...truncated 421 lines...]
    warning: no previously-included files matching 'lextab.*' found under directory 'tests'
    warning: no previously-included files matching 'yacctab.*' found under directory 'examples'
    warning: no previously-included files matching 'lextab.*' found under directory 'examples'
    Build the lexing/parsing tables
Successfully installed pyyaml pyOpenSSL nose unittest2 requests flask httpbin tsqa cryptography six argparse traceback2 Werkzeug Jinja2 itsdangerous MarkupSafe decorator pyasn1 enum34 cffi linecache2 pycparser
Cleaning up...
Virtualenv ready!
virtualenv/bin/nosetests --with-xunit -sv --logging-level=INFO
INFO 2015-05-09 05:04:03,526 - Starting build (3375ae446536ef83975ccaf91d67fcdc): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-09 05:04:48,583 - Build completed (3375ae446536ef83975ccaf91d67fcdc): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-09 05:04:48,665 - Environment prefix is /tmp/tsqa.env.OU2XyV
test_buildoption (test_buildoptions.TestBuildOption) ... ok
INFO 2015-05-09 05:05:18,411 - Starting build (e127c1a05c3ef1c71d1fc780ec9f804c): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'disable-diags': None, 'enable-test-tools': None}
INFO 2015-05-09 05:06:04,200 - Build completed (e127c1a05c3ef1c71d1fc780ec9f804c): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'disable-diags': None, 'enable-test-tools': None}
INFO 2015-05-09 05:06:04,284 - Environment prefix is /tmp/tsqa.env.Bblmjs
test_buildoption (test_buildoptions.TestBuildOptionDisableDiags) ... ok
INFO 2015-05-09 05:06:34,267 - Starting build (fcf436ddaf8d96eb997c6f84f34ee815): configure {'disable-tests': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-09 05:07:19,577 - Build completed (fcf436ddaf8d96eb997c6f84f34ee815): configure {'disable-tests': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-09 05:07:19,659 - Environment prefix is /tmp/tsqa.env.EIbcDi
test_buildoption (test_buildoptions.TestBuildOptionDisableTests) ... ok
SKIP: Skip until atscppapi supports out of tree builds
SKIP: Skip until TS-3577 is resolved
INFO 2015-05-09 05:07:49,283 - Starting build (790e5816af185c4fe8093b72e4794a0d): configure {'enable-fast-sdk': None, 'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-09 05:08:35,457 - Build completed (790e5816af185c4fe8093b72e4794a0d): configure {'enable-fast-sdk': None, 'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-09 05:08:35,540 - Environment prefix is /tmp/tsqa.env.bW8jXc
test_buildoption (test_buildoptions.TestBuildOptionFastSDK) ... ok
INFO 2015-05-09 05:08:38,984 - Environment prefix is /tmp/tsqa.env.doufCl
test_chunked_bad_close (test_chunked.TestChunked) ... INFO 2015-05-09 05:08:42,254 - sending data back to the client
okINFO 2015-05-09 05:08:44,758 - Client disconnected

test_chunked_basic (test_chunked.TestChunked) ... INFO 2015-05-09 05:08:44,762 - sending data back to the client
ok
test_chunked_keepalive_client (test_chunked.TestChunked) ... INFO 2015-05-09 05:08:47,767 - sending data back to the client
INFO 2015-05-09 05:08:50,772 - sending data back to the client
INFO 2015-05-09 05:08:53,777 - sending data back to the client
INFO 2015-05-09 05:08:56,179 - sending data back to the client
INFO 2015-05-09 05:08:58,581 - sending data back to the client
INFO 2015-05-09 05:09:02,586 - sending data back to the client
ok
test_chunked_keepalive_server (test_chunked.TestChunked) ... INFO 2015-05-09 05:09:06,591 - sending data back to the client
INFO 2015-05-09 05:09:09,595 - sending data back to the client
ok
Test that the origin does in fact support keepalive ... INFO 2015-05-09 05:09:10,600 - sending data back to the client
INFO 2015-05-09 05:09:13,605 - sending data back to the client
INFO 2015-05-09 05:09:14,608 - sending data back to the client
INFO 2015-05-09 05:09:16,608 - Client disconnected
INFO 2015-05-09 05:09:17,011 - sending data back to the client
INFO 2015-05-09 05:09:17,413 - sending data back to the client
INFO 2015-05-09 05:09:19,413 - Client disconnected
INFO 2015-05-09 05:09:21,416 - sending data back to the client
ok
INFO 2015-05-09 05:09:23,426 - Client disconnected
INFO 2015-05-09 05:09:23,645 - Environment prefix is /tmp/tsqa.env.7SxK04
INFO 2015-05-09 05:09:25,421 - Client disconnected
Verify that we get 502s from an origin which just did a bind ... ok
Verify that we get 200s from origins that delayed_accept_after_connect ... ok
Verify that we get 504s from origins that die_on_connect ... ok
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... /usr/lib64/python2.7/unittest/case.py:380: RuntimeWarning: TestResult has no addExpectedFailure method, reporting as passes
  RuntimeWarning)
ok
Verify that we get 200s from origins that reset_after_accept ... ok
INFO 2015-05-09 05:09:33,987 - Environment prefix is /tmp/tsqa.env.0BF41o
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-05-09 05:10:03,648 - Starting build (a1de67cffb8c345a8296170a52ca4784): configure {'enable-spdy': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-09 05:10:50,152 - Build completed (a1de67cffb8c345a8296170a52ca4784): configure {'enable-spdy': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-09 05:10:50,236 - Environment prefix is /tmp/tsqa.env.9P9yTI
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-05-09 05:10:53,685 - Environment prefix is /tmp/tsqa.env.mIzSJP
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [09/May/2015 05:10:56] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-05-09 05:10:57,136 - Environment prefix is /tmp/tsqa.env.3j2XWH
test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [09/May/2015 05:11:00] "GET / HTTP/1.1" 404 0
ok
INFO 2015-05-09 05:11:10,623 - Environment prefix is /tmp/tsqa.env.FdOe2V
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-05-09 05:11:24,100 - Environment prefix is /tmp/tsqa.env.4skEdF
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:27] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-09 05:11:27,589 - Environment prefix is /tmp/tsqa.env.Iwm9YW
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-05-09 05:11:33,051 - Environment prefix is /tmp/tsqa.env.TwrVtZ
test_working (test_hostdb.TestHostDBPartiallyFailedDNS) ... FAIL
INFO 2015-05-09 05:11:40,242 - Environment prefix is /tmp/tsqa.env.nfov37
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-05-09 05:11:44,103 - Environment prefix is /tmp/tsqa.env.TSQCWw
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-05-09 05:11:47,962 - Environment prefix is /tmp/tsqa.env.Hl7hEY
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-05-09 05:11:51,819 - Environment prefix is /tmp/tsqa.env.PaOxDs
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 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:11:55] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-05-09 05:11:55,262 - Environment prefix is /tmp/tsqa.env.QWLEXu
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-05-09 05:12:01,699 - Environment prefix is /tmp/tsqa.env.Qcyk26
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-05-09 05:12:08,140 - Environment prefix is /tmp/tsqa.env.vxPxpL
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-05-09 05:12:29,636 - Environment prefix is /tmp/tsqa.env.NTSIkD
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-05-09 05:12:51,138 - Environment prefix is /tmp/tsqa.env.GdmL2m
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-09 05:12:54,628 - Environment prefix is /tmp/tsqa.env.XPqfgC
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-09 05:12:58,109 - Environment prefix is /tmp/tsqa.env.wETkLw
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/1 HTTP/1.1" 302 215
127.0.0.1 - - [09/May/2015 05:13:01] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/10 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [09/May/2015 05:13:01] "GET /redirect/1 HTTP/1.1" 302 215
ok
INFO 2015-05-09 05:13:01,601 - Environment prefix is /tmp/tsqa.env.LLNe10
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-05-09 05:15:34,454 - Starting build (481f22ae002c99c89179cba0bbc2ef76): configure {'enable-linux-native-aio': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-linux-native-aio', '--disable-dependency-tracking', '--enable-ccache', '--enable-test-tools', '--enable-example-plugins']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (481f22ae002c99c89179cba0bbc2ef76): configure {'enable-linux-native-aio': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-05-09 05:15:43,857 - Environment prefix is /tmp/tsqa.env.E4bKs2
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [09/May/2015 05:15:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:15:47] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:15:47] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-09 05:15:47,323 - Environment prefix is /tmp/tsqa.env.XoVWBV
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [09/May/2015 05:15:50] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:15:50] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [09/May/2015 05:15:50] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-05-09 05:15:50,792 - Environment prefix is /tmp/tsqa.env.OiU5Am
INFO 2015-05-09 05:15:50,794 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-09 05:15:50,794 - spdy server port = 48203
INFO 2015-05-09 05:15:50,794 - http server port = 35801
Test that the origin does in fact support spdy 3 ... INFO 2015-05-09 05:15:54,016 - full args = ['/usr/local/bin/spdycat', 'https://localhost:48203/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-09 05:15:55,140 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35801/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-05-09 05:15:55,359 - Environment prefix is /tmp/tsqa.env.G819Yi
INFO 2015-05-09 05:15:55,361 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-09 05:15:55,361 - spdy server port = 37876
INFO 2015-05-09 05:15:55,362 - http server port = 59565
Test that the origin does in fact support spdy 3.1 ... INFO 2015-05-09 05:15:58,586 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37876/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-09 05:15:59,713 - full args = ['/usr/local/bin/spdycat', 'https://localhost:59565/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok

======================================================================
FAIL: test_working (test_hostdb.TestHostDBPartiallyFailedDNS)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_hostdb.py",> line 53, in test_working
    self.assertLess(time.time() - start, self.configs['records.config']['CONFIG']['proxy.config.hostdb.lookup_timeout'])
AssertionError: 2.951301097869873 not less than 2
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.TwrVtZ
--------------------- >> end captured logging << ---------------------

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

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

Build failed in Jenkins: tsqa-master #386

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

Changes:

[James Peach] TSQA: build experimental plugins by default

------------------------------------------
[...truncated 431 lines...]
    warning: no previously-included files matching 'lextab.*' found under directory 'tests'
    warning: no previously-included files matching 'yacctab.*' found under directory 'examples'
    warning: no previously-included files matching 'lextab.*' found under directory 'examples'
    Build the lexing/parsing tables
Successfully installed pyyaml pyOpenSSL nose unittest2 requests flask httpbin tsqa cryptography six argparse traceback2 Werkzeug Jinja2 itsdangerous MarkupSafe decorator pyasn1 setuptools enum34 cffi linecache2 pycparser
Cleaning up...
make[1]: Leaving directory `<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa'>
Virtualenv ready!
INFO 2015-05-11 00:19:59,448 - Starting build (f68245519d973929c01dffe38ea8f62a): configure {'enable-experimental-plugins': None, 'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-11 00:23:11,185 - Build completed (f68245519d973929c01dffe38ea8f62a): configure {'enable-experimental-plugins': None, 'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-11 00:23:11,295 - Environment prefix is /tmp/tsqa.env.kCVrKC
test_buildoption (test_buildoptions.TestBuildOption) ... ok
INFO 2015-05-11 00:23:41,150 - Starting build (d34f00cc7156df088bb81f4b5be68966): configure {'disable-diags': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-05-11 00:26:45,329 - Build completed (d34f00cc7156df088bb81f4b5be68966): configure {'disable-diags': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-05-11 00:26:45,439 - Environment prefix is /tmp/tsqa.env.ciROIz
test_buildoption (test_buildoptions.TestBuildOptionDisableDiags) ... ok
INFO 2015-05-11 00:27:15,407 - Starting build (5bb5dacd61493d207ad21c85d6add0fe): configure {'disable-tests': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-05-11 00:30:16,820 - Build completed (5bb5dacd61493d207ad21c85d6add0fe): configure {'disable-tests': None, 'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-05-11 00:30:16,927 - Environment prefix is /tmp/tsqa.env.nOTXgh
test_buildoption (test_buildoptions.TestBuildOptionDisableTests) ... ok
SKIP: Skip until atscppapi supports out of tree builds
SKIP: Skip until TS-3577 is resolved
INFO 2015-05-11 00:30:46,648 - Starting build (abb21087983b7f9b09675db99285b6ed): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-fast-sdk': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-05-11 00:33:49,567 - Build completed (abb21087983b7f9b09675db99285b6ed): configure {'enable-ccache': None, 'enable-experimental-plugins': None, 'enable-example-plugins': None, 'enable-fast-sdk': None, 'enable-test-tools': None, 'disable-dependency-tracking': None}
INFO 2015-05-11 00:33:49,671 - Environment prefix is /tmp/tsqa.env.akNjo9
test_buildoption (test_buildoptions.TestBuildOptionFastSDK) ... ok
INFO 2015-05-11 00:33:53,144 - Environment prefix is /tmp/tsqa.env.VkzJiZ
test_chunked_bad_close (test_chunked.TestChunked) ... INFO 2015-05-11 00:33:56,384 - sending data back to the client
okINFO 2015-05-11 00:33:58,888 - Client disconnected

test_chunked_basic (test_chunked.TestChunked) ... INFO 2015-05-11 00:33:58,892 - sending data back to the client
ok
test_chunked_keepalive_client (test_chunked.TestChunked) ... INFO 2015-05-11 00:34:01,897 - sending data back to the client
INFO 2015-05-11 00:34:04,901 - sending data back to the client
INFO 2015-05-11 00:34:07,905 - sending data back to the client
INFO 2015-05-11 00:34:10,308 - sending data back to the client
INFO 2015-05-11 00:34:12,712 - sending data back to the client
INFO 2015-05-11 00:34:16,716 - sending data back to the client
ok
test_chunked_keepalive_server (test_chunked.TestChunked) ... INFO 2015-05-11 00:34:20,721 - sending data back to the client
INFO 2015-05-11 00:34:23,725 - sending data back to the client
ok
Test that the origin does in fact support keepalive ... INFO 2015-05-11 00:34:24,729 - sending data back to the client
INFO 2015-05-11 00:34:27,734 - sending data back to the client
INFO 2015-05-11 00:34:28,737 - sending data back to the client
INFO 2015-05-11 00:34:30,738 - Client disconnected
INFO 2015-05-11 00:34:31,140 - sending data back to the client
INFO 2015-05-11 00:34:31,542 - sending data back to the client
INFO 2015-05-11 00:34:33,544 - Client disconnected
INFO 2015-05-11 00:34:35,547 - sending data back to the client
ok
INFO 2015-05-11 00:34:37,557 - Client disconnected
INFO 2015-05-11 00:34:37,811 - Environment prefix is /tmp/tsqa.env.9O27zL
INFO 2015-05-11 00:34:39,552 - Client disconnected
Verify that we get 502s from an origin which just did a bind ... ok
Verify that we get 200s from origins that delayed_accept_after_connect ... ok
Verify that we get 504s from origins that die_on_connect ... ok
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... /usr/lib64/python2.7/unittest/case.py:380: RuntimeWarning: TestResult has no addExpectedFailure method, reporting as passes
  RuntimeWarning)
ok
Verify that we get 200s from origins that reset_after_accept ... ok
INFO 2015-05-11 00:34:48,193 - Environment prefix is /tmp/tsqa.env.ZJkD40
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-05-11 00:35:18,082 - 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-05-11 00:38:21,920 - 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-05-11 00:38:22,026 - Environment prefix is /tmp/tsqa.env.ZxKOnE
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-05-11 00:38:25,501 - Environment prefix is /tmp/tsqa.env.axoGPR
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [11/May/2015 00:38:28] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-05-11 00:38:28,978 - Environment prefix is /tmp/tsqa.env.RI2a1G
test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [11/May/2015 00:38:32] "GET / HTTP/1.1" 404 0
ok
INFO 2015-05-11 00:38:42,503 - Environment prefix is /tmp/tsqa.env.hJj_JC
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-05-11 00:38:56,017 - Environment prefix is /tmp/tsqa.env.nkDgEm
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:38:59] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-11 00:38:59,551 - Environment prefix is /tmp/tsqa.env.enpJeT
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-05-11 00:39:05,076 - Environment prefix is /tmp/tsqa.env.FOL9IP
test_working (test_hostdb.TestHostDBPartiallyFailedDNS) ... FAIL
INFO 2015-05-11 00:39:12,334 - Environment prefix is /tmp/tsqa.env.WNKsYy
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-05-11 00:39:16,213 - Environment prefix is /tmp/tsqa.env.Wqldta
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-05-11 00:39:20,091 - Environment prefix is /tmp/tsqa.env.WiADsc
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-05-11 00:39:23,979 - Environment prefix is /tmp/tsqa.env.eG3NeN
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 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:39:27] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-05-11 00:39:27,470 - Environment prefix is /tmp/tsqa.env._CZ7Xr
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-05-11 00:39:33,947 - Environment prefix is /tmp/tsqa.env.EWemOF
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-05-11 00:39:40,429 - Environment prefix is /tmp/tsqa.env.pDGyXu
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-05-11 00:40:01,966 - Environment prefix is /tmp/tsqa.env.ZZWhNt
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-05-11 00:40:23,513 - Environment prefix is /tmp/tsqa.env.aCLw_g
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-11 00:40:27,030 - Environment prefix is /tmp/tsqa.env.9Qf5Jc
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-11 00:40:30,527 - Environment prefix is /tmp/tsqa.env.p2JN3v
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/1 HTTP/1.1" 302 215
127.0.0.1 - - [11/May/2015 00:40:33] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/10 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [11/May/2015 00:40:33] "GET /redirect/1 HTTP/1.1" 302 215
ok
INFO 2015-05-11 00:40:34,043 - Environment prefix is /tmp/tsqa.env.RwWCyk
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-05-11 00:43:06,761 - 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-05-11 00:43:16,070 - Environment prefix is /tmp/tsqa.env.psaUS1
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [11/May/2015 00:43:19] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:43:19] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:43:19] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-11 00:43:19,580 - Environment prefix is /tmp/tsqa.env.pqAVMp
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [11/May/2015 00:43:22] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:43:22] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [11/May/2015 00:43:22] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-05-11 00:43:23,070 - Environment prefix is /tmp/tsqa.env.jOb71c
INFO 2015-05-11 00:43:23,072 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-11 00:43:23,072 - spdy server port = 60412
INFO 2015-05-11 00:43:23,072 - http server port = 50930
Test that the origin does in fact support spdy 3 ... INFO 2015-05-11 00:43:26,298 - full args = ['/usr/local/bin/spdycat', 'https://localhost:60412/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-11 00:43:27,766 - full args = ['/usr/local/bin/spdycat', 'https://localhost:50930/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-05-11 00:43:28,017 - Environment prefix is /tmp/tsqa.env.DM3BVn
INFO 2015-05-11 00:43:28,019 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-11 00:43:28,019 - spdy server port = 38801
INFO 2015-05-11 00:43:28,019 - http server port = 35941
Test that the origin does in fact support spdy 3.1 ... INFO 2015-05-11 00:43:31,244 - full args = ['/usr/local/bin/spdycat', 'https://localhost:38801/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-11 00:43:32,270 - full args = ['/usr/local/bin/spdycat', 'https://localhost:35941/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok

======================================================================
FAIL: test_working (test_hostdb.TestHostDBPartiallyFailedDNS)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_hostdb.py",> line 53, in test_working
    self.assertLess(time.time() - start, self.configs['records.config']['CONFIG']['proxy.config.hostdb.lookup_timeout'])
AssertionError: 2.9018330574035645 not less than 2
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.FOL9IP
--------------------- >> end captured logging << ---------------------

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

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

Build failed in Jenkins: tsqa-master #385

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

Changes:

[James Peach] Apply clang-format to apidefs.h.in

[James Peach] Various TSQA build improvements

[James Peach] Undo some clang-format changes in M4 substitutions

------------------------------------------
[...truncated 431 lines...]
    warning: no previously-included files matching 'lextab.*' found under directory 'tests'
    warning: no previously-included files matching 'yacctab.*' found under directory 'examples'
    warning: no previously-included files matching 'lextab.*' found under directory 'examples'
    Build the lexing/parsing tables
Successfully installed pyyaml pyOpenSSL nose unittest2 requests flask httpbin tsqa cryptography six argparse traceback2 Werkzeug Jinja2 itsdangerous MarkupSafe decorator pyasn1 setuptools enum34 cffi linecache2 pycparser
Cleaning up...
make[1]: Leaving directory `<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa'>
Virtualenv ready!
INFO 2015-05-10 21:26:25,358 - Starting build (83bc57dfabbecdb7dad3cc9d369ad5a1): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-10 21:28:39,454 - Build completed (83bc57dfabbecdb7dad3cc9d369ad5a1): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-10 21:28:39,535 - Environment prefix is /tmp/tsqa.env.oGsId4
test_buildoption (test_buildoptions.TestBuildOption) ... ok
INFO 2015-05-10 21:29:09,319 - Starting build (7dba272c0c908d553a85bbb1f1dd1117): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'disable-diags': None, 'enable-test-tools': None}
INFO 2015-05-10 21:31:22,491 - Build completed (7dba272c0c908d553a85bbb1f1dd1117): configure {'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'disable-diags': None, 'enable-test-tools': None}
INFO 2015-05-10 21:31:22,571 - Environment prefix is /tmp/tsqa.env.0v5czZ
test_buildoption (test_buildoptions.TestBuildOptionDisableDiags) ... ok
INFO 2015-05-10 21:31:52,301 - Starting build (3d4e877d3eb6194898fdd22677308eb5): configure {'disable-tests': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-10 21:34:03,765 - Build completed (3d4e877d3eb6194898fdd22677308eb5): configure {'disable-tests': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-10 21:34:03,844 - Environment prefix is /tmp/tsqa.env.LAB1xK
test_buildoption (test_buildoptions.TestBuildOptionDisableTests) ... ok
SKIP: Skip until atscppapi supports out of tree builds
SKIP: Skip until TS-3577 is resolved
INFO 2015-05-10 21:34:33,779 - Starting build (0666afd87688fb9ccdd366d3c0cd6cea): configure {'enable-fast-sdk': None, 'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-10 21:36:46,300 - Build completed (0666afd87688fb9ccdd366d3c0cd6cea): configure {'enable-fast-sdk': None, 'enable-example-plugins': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None}
INFO 2015-05-10 21:36:46,382 - Environment prefix is /tmp/tsqa.env.B5yE9k
test_buildoption (test_buildoptions.TestBuildOptionFastSDK) ... ok
INFO 2015-05-10 21:36:49,828 - Environment prefix is /tmp/tsqa.env.kwjjLW
test_chunked_bad_close (test_chunked.TestChunked) ... INFO 2015-05-10 21:36:53,070 - sending data back to the client
INFO 2015-05-10 21:36:55,574 - Client disconnected
ok
test_chunked_basic (test_chunked.TestChunked) ... INFO 2015-05-10 21:36:55,578 - sending data back to the client
ok
test_chunked_keepalive_client (test_chunked.TestChunked) ... INFO 2015-05-10 21:36:58,582 - sending data back to the client
INFO 2015-05-10 21:37:01,586 - sending data back to the client
INFO 2015-05-10 21:37:04,591 - sending data back to the client
INFO 2015-05-10 21:37:06,992 - sending data back to the client
INFO 2015-05-10 21:37:09,396 - sending data back to the client
INFO 2015-05-10 21:37:13,401 - sending data back to the client
ok
test_chunked_keepalive_server (test_chunked.TestChunked) ... INFO 2015-05-10 21:37:17,406 - sending data back to the client
INFO 2015-05-10 21:37:20,410 - sending data back to the client
ok
Test that the origin does in fact support keepalive ... INFO 2015-05-10 21:37:21,415 - sending data back to the client
INFO 2015-05-10 21:37:24,419 - sending data back to the client
INFO 2015-05-10 21:37:25,422 - sending data back to the client
INFO 2015-05-10 21:37:27,423 - Client disconnected
INFO 2015-05-10 21:37:27,826 - sending data back to the client
INFO 2015-05-10 21:37:28,228 - sending data back to the client
INFO 2015-05-10 21:37:30,228 - Client disconnected
INFO 2015-05-10 21:37:32,232 - sending data back to the client
ok
INFO 2015-05-10 21:37:34,242 - Client disconnected
INFO 2015-05-10 21:37:34,460 - Environment prefix is /tmp/tsqa.env.9jqE_4
INFO 2015-05-10 21:37:36,237 - Client disconnected
Verify that we get 502s from an origin which just did a bind ... ok
Verify that we get 200s from origins that delayed_accept_after_connect ... ok
Verify that we get 504s from origins that die_on_connect ... ok
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... /usr/lib64/python2.7/unittest/case.py:380: RuntimeWarning: TestResult has no addExpectedFailure method, reporting as passes
  RuntimeWarning)
ok
Verify that we get 200s from origins that reset_after_accept ... ok
INFO 2015-05-10 21:37:44,813 - Environment prefix is /tmp/tsqa.env.KQ1CPn
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-05-10 21:38:14,588 - Starting build (ca28e008a0d82d366ffca5e49bcecfeb): configure {'enable-spdy': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-10 21:40:29,054 - Build completed (ca28e008a0d82d366ffca5e49bcecfeb): configure {'enable-spdy': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
INFO 2015-05-10 21:40:29,136 - Environment prefix is /tmp/tsqa.env.qOD0ze
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-05-10 21:40:32,583 - Environment prefix is /tmp/tsqa.env.CZyfG_
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [10/May/2015 21:40:35] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-05-10 21:40:36,034 - Environment prefix is /tmp/tsqa.env.c7KENT
test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 21:40:39] "GET / HTTP/1.1" 404 0
ok
INFO 2015-05-10 21:40:49,527 - Environment prefix is /tmp/tsqa.env.gTJB8T
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-05-10 21:41:03,022 - Environment prefix is /tmp/tsqa.env.Ok9CO0
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:06] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-10 21:41:06,517 - Environment prefix is /tmp/tsqa.env.TY16vn
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-05-10 21:41:12,006 - Environment prefix is /tmp/tsqa.env._riHcz
test_working (test_hostdb.TestHostDBPartiallyFailedDNS) ... FAIL
INFO 2015-05-10 21:41:18,889 - Environment prefix is /tmp/tsqa.env.iU8NpT
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-05-10 21:41:22,759 - Environment prefix is /tmp/tsqa.env.v2Fw_J
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-05-10 21:41:26,621 - Environment prefix is /tmp/tsqa.env.nH_v7G
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-05-10 21:41:30,482 - Environment prefix is /tmp/tsqa.env.CLwm2u
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 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:41:33] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-05-10 21:41:33,955 - Environment prefix is /tmp/tsqa.env.zXXp0m
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-05-10 21:41:40,418 - Environment prefix is /tmp/tsqa.env.RLOtEG
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-05-10 21:41:46,886 - Environment prefix is /tmp/tsqa.env.MXFXxk
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-05-10 21:42:08,398 - Environment prefix is /tmp/tsqa.env.hAvNUX
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-05-10 21:42:29,916 - Environment prefix is /tmp/tsqa.env.SEBkMU
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-10 21:42:33,397 - Environment prefix is /tmp/tsqa.env.2L_WSD
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-10 21:42:36,856 - Environment prefix is /tmp/tsqa.env.4VzgOu
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/1 HTTP/1.1" 302 215
127.0.0.1 - - [10/May/2015 21:42:40] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/10 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 21:42:40] "GET /redirect/1 HTTP/1.1" 302 215
ok
INFO 2015-05-10 21:42:40,340 - Environment prefix is /tmp/tsqa.env.H1W0d3
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-05-10 21:45:12,112 - Starting build (0e8896ffbce6c0f5bb700ce7b6950538): configure {'enable-linux-native-aio': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-linux-native-aio', '--disable-dependency-tracking', '--enable-ccache', '--enable-test-tools', '--enable-example-plugins']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (0e8896ffbce6c0f5bb700ce7b6950538): configure {'enable-linux-native-aio': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-05-10 21:45:21,449 - Environment prefix is /tmp/tsqa.env.d6yYlV
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [10/May/2015 21:45:24] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:45:24] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:45:24] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-10 21:45:24,911 - Environment prefix is /tmp/tsqa.env.ozv5DB
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [10/May/2015 21:45:28] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:45:28] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 21:45:28] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-05-10 21:45:28,371 - Environment prefix is /tmp/tsqa.env.4ALAwm
INFO 2015-05-10 21:45:28,373 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-10 21:45:28,373 - spdy server port = 54102
INFO 2015-05-10 21:45:28,373 - http server port = 41067
Test that the origin does in fact support spdy 3 ... INFO 2015-05-10 21:45:31,602 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54102/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-10 21:45:32,767 - full args = ['/usr/local/bin/spdycat', 'https://localhost:41067/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-05-10 21:45:32,986 - Environment prefix is /tmp/tsqa.env.J_5QcB
INFO 2015-05-10 21:45:32,988 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-10 21:45:32,988 - spdy server port = 41414
INFO 2015-05-10 21:45:32,989 - http server port = 32776
Test that the origin does in fact support spdy 3.1 ... INFO 2015-05-10 21:45:36,214 - full args = ['/usr/local/bin/spdycat', 'https://localhost:41414/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-10 21:45:37,305 - full args = ['/usr/local/bin/spdycat', 'https://localhost:32776/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok

======================================================================
FAIL: test_working (test_hostdb.TestHostDBPartiallyFailedDNS)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_hostdb.py",> line 53, in test_working
    self.assertLess(time.time() - start, self.configs['records.config']['CONFIG']['proxy.config.hostdb.lookup_timeout'])
AssertionError: 2.607318878173828 not less than 2
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env._riHcz
--------------------- >> end captured logging << ---------------------

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

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

Build failed in Jenkins: tsqa-master #384

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

------------------------------------------
[...truncated 414 lines...]
  Running setup.py install for linecache2
    [pbr] Reusing existing SOURCES.txt
      File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/linecache2/tests/inspect_fodder2.py",> line 102
        def keyworded(*arg1, arg2=1):
                                ^
    SyntaxError: invalid syntax
    
  Running setup.py install for pycparser
    
    warning: no previously-included files matching 'yacctab.*' found under directory 'tests'
    warning: no previously-included files matching 'lextab.*' found under directory 'tests'
    warning: no previously-included files matching 'yacctab.*' found under directory 'examples'
    warning: no previously-included files matching 'lextab.*' found under directory 'examples'
    Build the lexing/parsing tables
Successfully installed pyyaml pyOpenSSL nose unittest2 requests flask httpbin tsqa cryptography six argparse traceback2 Werkzeug Jinja2 itsdangerous MarkupSafe decorator pyasn1 enum34 cffi linecache2 pycparser
Cleaning up...
Virtualenv ready!
virtualenv/bin/nosetests --with-xunit -sv --logging-level=INFO
INFO 2015-05-10 04:57:21,148 - Environment prefix is /tmp/tsqa.env.zSMmYd
test_buildoption (test_buildoptions.TestBuildOption) ... ok
INFO 2015-05-10 04:57:24,591 - Environment prefix is /tmp/tsqa.env.IwnZD3
test_buildoption (test_buildoptions.TestBuildOptionDisableDiags) ... ok
INFO 2015-05-10 04:57:28,030 - Environment prefix is /tmp/tsqa.env.BmzDd9
test_buildoption (test_buildoptions.TestBuildOptionDisableTests) ... ok
SKIP: Skip until atscppapi supports out of tree builds
SKIP: Skip until TS-3577 is resolved
INFO 2015-05-10 04:57:31,475 - Environment prefix is /tmp/tsqa.env.0Ck9Ec
test_buildoption (test_buildoptions.TestBuildOptionFastSDK) ... ok
INFO 2015-05-10 04:57:34,918 - Environment prefix is /tmp/tsqa.env.LAlWel
test_chunked_bad_close (test_chunked.TestChunked) ... INFO 2015-05-10 04:57:38,163 - sending data back to the client
INFO 2015-05-10 04:57:40,667 - Client disconnected
ok
test_chunked_basic (test_chunked.TestChunked) ... INFO 2015-05-10 04:57:40,671 - sending data back to the client
ok
test_chunked_keepalive_client (test_chunked.TestChunked) ... INFO 2015-05-10 04:57:43,675 - sending data back to the client
INFO 2015-05-10 04:57:46,680 - sending data back to the client
INFO 2015-05-10 04:57:49,684 - sending data back to the client
INFO 2015-05-10 04:57:52,088 - sending data back to the client
INFO 2015-05-10 04:57:54,491 - sending data back to the client
INFO 2015-05-10 04:57:58,495 - sending data back to the client
ok
test_chunked_keepalive_server (test_chunked.TestChunked) ... INFO 2015-05-10 04:58:02,500 - sending data back to the client
INFO 2015-05-10 04:58:05,505 - sending data back to the client
ok
Test that the origin does in fact support keepalive ... INFO 2015-05-10 04:58:06,509 - sending data back to the client
INFO 2015-05-10 04:58:09,513 - sending data back to the client
INFO 2015-05-10 04:58:10,516 - sending data back to the client
INFO 2015-05-10 04:58:12,518 - Client disconnected
INFO 2015-05-10 04:58:12,920 - sending data back to the client
INFO 2015-05-10 04:58:13,322 - sending data back to the client
INFO 2015-05-10 04:58:15,323 - Client disconnected
INFO 2015-05-10 04:58:17,326 - sending data back to the client
ok
INFO 2015-05-10 04:58:19,339 - Client disconnected
INFO 2015-05-10 04:58:19,553 - Environment prefix is /tmp/tsqa.env.TUJGdR
INFO 2015-05-10 04:58:21,332 - Client disconnected
Verify that we get 502s from an origin which just did a bind ... ok
Verify that we get 200s from origins that delayed_accept_after_connect ... ok
Verify that we get 504s from origins that die_on_connect ... ok
Verify that we get 502s from origins that bind + listen ... ok
Verify that we get 504s from origins that return a partial_response ... /usr/lib64/python2.7/unittest/case.py:380: RuntimeWarning: TestResult has no addExpectedFailure method, reporting as passes
  RuntimeWarning)
ok
Verify that we get 200s from origins that reset_after_accept ... ok
INFO 2015-05-10 04:58:29,904 - Environment prefix is /tmp/tsqa.env.MjhS8B
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-05-10 04:58:33,366 - Environment prefix is /tmp/tsqa.env.ZNSI52
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-05-10 04:58:36,814 - Environment prefix is /tmp/tsqa.env.kzOwqj
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [10/May/2015 04:58:40] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-05-10 04:58:40,274 - Environment prefix is /tmp/tsqa.env.u5DMBD
test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [10/May/2015 04:58:43] "GET / HTTP/1.1" 404 0
ok
INFO 2015-05-10 04:58:53,769 - Environment prefix is /tmp/tsqa.env.MEQbPY
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-05-10 04:59:07,243 - Environment prefix is /tmp/tsqa.env.bpYOBH
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:10] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-10 04:59:10,745 - Environment prefix is /tmp/tsqa.env.POa48S
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-05-10 04:59:16,211 - Environment prefix is /tmp/tsqa.env.A7FSq8
test_working (test_hostdb.TestHostDBPartiallyFailedDNS) ... FAIL
INFO 2015-05-10 04:59:23,305 - Environment prefix is /tmp/tsqa.env.ORYBbd
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-05-10 04:59:27,177 - Environment prefix is /tmp/tsqa.env.5T7Xc5
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-05-10 04:59:31,035 - Environment prefix is /tmp/tsqa.env.s4Qa_h
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-05-10 04:59:34,894 - Environment prefix is /tmp/tsqa.env.l2LRLN
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 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 04:59:38] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-05-10 04:59:38,347 - Environment prefix is /tmp/tsqa.env.EeApdD
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-05-10 04:59:44,799 - Environment prefix is /tmp/tsqa.env.JUHroC
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-05-10 04:59:51,251 - Environment prefix is /tmp/tsqa.env.3WG4J0
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-05-10 05:00:12,763 - Environment prefix is /tmp/tsqa.env.uglm9C
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-05-10 05:00:34,302 - Environment prefix is /tmp/tsqa.env.J72NVl
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-10 05:00:37,779 - Environment prefix is /tmp/tsqa.env.OQuDmN
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-05-10 05:00:41,267 - Environment prefix is /tmp/tsqa.env.4B8O0M
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/1 HTTP/1.1" 302 215
127.0.0.1 - - [10/May/2015 05:00:44] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/10 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [10/May/2015 05:00:44] "GET /redirect/1 HTTP/1.1" 302 215
ok
INFO 2015-05-10 05:00:44,738 - Environment prefix is /tmp/tsqa.env.ctmM57
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-05-10 05:03:18,371 - Starting build (481f22ae002c99c89179cba0bbc2ef76): configure {'enable-linux-native-aio': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
SKIP: Error 1 running: ['<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/configure',> '--prefix=/', '--enable-linux-native-aio', '--disable-dependency-tracking', '--enable-ccache', '--enable-test-tools', '--enable-example-plugins']
configure: error: Linux native AIO requires libaio.h

-------------------- >> begin captured logging << --------------------
tsqa.environment: INFO: Starting build (481f22ae002c99c89179cba0bbc2ef76): configure {'enable-linux-native-aio': None, 'disable-dependency-tracking': None, 'enable-ccache': None, 'enable-test-tools': None, 'enable-example-plugins': None}
--------------------- >> end captured logging << ---------------------
INFO 2015-05-10 05:03:27,761 - Environment prefix is /tmp/tsqa.env.ZsF87S
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [10/May/2015 05:03:30] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 05:03:30] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 05:03:30] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-10 05:03:31,139 - Environment prefix is /tmp/tsqa.env.8XOCGE
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [10/May/2015 05:03:34] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 05:03:34] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [10/May/2015 05:03:34] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-05-10 05:03:34,611 - Environment prefix is /tmp/tsqa.env.9wx30n
INFO 2015-05-10 05:03:34,612 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-10 05:03:34,613 - spdy server port = 46176
INFO 2015-05-10 05:03:34,613 - http server port = 46807
Test that the origin does in fact support spdy 3 ... INFO 2015-05-10 05:03:37,838 - full args = ['/usr/local/bin/spdycat', 'https://localhost:46176/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-10 05:03:39,227 - full args = ['/usr/local/bin/spdycat', 'https://localhost:46807/', '-nv', '--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-05-10 05:03:39,445 - Environment prefix is /tmp/tsqa.env.X1EtME
INFO 2015-05-10 05:03:39,447 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-10 05:03:39,447 - spdy server port = 58950
INFO 2015-05-10 05:03:39,447 - http server port = 46268
Test that the origin does in fact support spdy 3.1 ... INFO 2015-05-10 05:03:42,672 - full args = ['/usr/local/bin/spdycat', 'https://localhost:58950/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-10 05:03:43,818 - full args = ['/usr/local/bin/spdycat', 'https://localhost:46268/', '-nv', '--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok

======================================================================
FAIL: test_working (test_hostdb.TestHostDBPartiallyFailedDNS)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_hostdb.py",> line 53, in test_working
    self.assertLess(time.time() - start, self.configs['records.config']['CONFIG']['proxy.config.hostdb.lookup_timeout'])
AssertionError: 2.84625506401062 not less than 2
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.A7FSq8
--------------------- >> end captured logging << ---------------------

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

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