See <https://ci.trafficserver.apache.org/job/tsqa-master/377/changes>
Changes:
[Sudheer Vinukonda] [TS-3586]: Add redirection_tries to the slow log to indicate
------------------------------------------
[...truncated 424 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-06 23:12:16,880 - Starting build
(3375ae446536ef83975ccaf91d67fcdc): configure {'enable-example-plugins': None,
'disable-dependency-tracking': None, 'enable-ccache': None,
'enable-test-tools': None}
INFO 2015-05-06 23:13:07,292 - Build completed
(3375ae446536ef83975ccaf91d67fcdc): configure {'enable-example-plugins': None,
'disable-dependency-tracking': None, 'enable-ccache': None,
'enable-test-tools': None}
INFO 2015-05-06 23:13:07,374 - Environment prefix is /tmp/tsqa.env.L3A2NP
test_buildoption (test_buildoptions.TestBuildOption) ... ok
INFO 2015-05-06 23:13:36,840 - 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-06 23:14:27,437 - 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-06 23:14:27,518 - Environment prefix is /tmp/tsqa.env.Uq8Tlp
test_buildoption (test_buildoptions.TestBuildOptionDisableDiags) ... ok
INFO 2015-05-06 23:14:57,068 - 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-06 23:15:47,213 - 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-06 23:15:47,293 - Environment prefix is /tmp/tsqa.env.dzZeBZ
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-06 23:16:16,709 - 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-06 23:17:07,179 - 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-06 23:17:07,259 - Environment prefix is /tmp/tsqa.env.U9BRVX
test_buildoption (test_buildoptions.TestBuildOptionFastSDK) ... ok
INFO 2015-05-06 23:17:10,702 - Environment prefix is /tmp/tsqa.env.FarccD
test_chunked_bad_close (test_chunked.TestChunked) ... INFO 2015-05-06
23:17:13,962 - sending data back to the client
INFO 2015-05-06 23:17:16,466 - Client disconnected
ok
test_chunked_basic (test_chunked.TestChunked) ... INFO 2015-05-06 23:17:16,470
- sending data back to the client
ok
test_chunked_keepalive_client (test_chunked.TestChunked) ... INFO 2015-05-06
23:17:19,473 - sending data back to the client
INFO 2015-05-06 23:17:22,478 - sending data back to the client
INFO 2015-05-06 23:17:25,482 - sending data back to the client
INFO 2015-05-06 23:17:27,885 - sending data back to the client
INFO 2015-05-06 23:17:30,289 - sending data back to the client
INFO 2015-05-06 23:17:34,293 - sending data back to the client
ok
test_chunked_keepalive_server (test_chunked.TestChunked) ... INFO 2015-05-06
23:17:38,298 - sending data back to the client
INFO 2015-05-06 23:17:41,302 - sending data back to the client
ok
Test that the origin does in fact support keepalive ... INFO 2015-05-06
23:17:42,306 - sending data back to the client
INFO 2015-05-06 23:17:45,311 - sending data back to the client
INFO 2015-05-06 23:17:46,314 - sending data back to the client
INFO 2015-05-06 23:17:48,315 - Client disconnected
INFO 2015-05-06 23:17:48,718 - sending data back to the client
INFO 2015-05-06 23:17:49,119 - sending data back to the client
INFO 2015-05-06 23:17:51,121 - Client disconnected
INFO 2015-05-06 23:17:53,123 - sending data back to the client
ok
INFO 2015-05-06 23:17:55,134 - Client disconnected
INFO 2015-05-06 23:17:55,350 - Environment prefix is /tmp/tsqa.env.UCH25E
INFO 2015-05-06 23:17:57,128 - 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-06 23:18:05,688 - Environment prefix is /tmp/tsqa.env.1SPvH2
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-05-06 23:18:35,224 - 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-06 23:19:26,409 - 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-06 23:19:26,490 - Environment prefix is /tmp/tsqa.env.AvTv20
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-05-06 23:19:29,939 - Environment prefix is /tmp/tsqa.env.bSuXAy
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - -
[06/May/2015 23:19:33] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-05-06 23:19:33,383 - Environment prefix is /tmp/tsqa.env.O3VCak
test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - -
[06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [06/May/2015 23:19:36] "GET / HTTP/1.1" 404 0
ok
INFO 2015-05-06 23:19:46,869 - Environment prefix is /tmp/tsqa.env.VMXe7A
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-05-06 23:20:00,351 - Environment prefix is /tmp/tsqa.env.ixuzZR
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - -
[06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:03] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-06 23:20:03,842 - Environment prefix is /tmp/tsqa.env.TVDtvr
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-05-06 23:20:09,313 - Environment prefix is /tmp/tsqa.env.RRXKBR
test_working (test_hostdb.TestHostDBPartiallyFailedDNS) ... FAIL
INFO 2015-05-06 23:20:16,260 - Environment prefix is /tmp/tsqa.env.gIzugE
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-06 23:20:20,116 - Environment prefix is /tmp/tsqa.env.ZGhSHS
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-06 23:20:23,966 - Environment prefix is /tmp/tsqa.env.J7HyQf
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-06 23:20:27,839 - Environment prefix is /tmp/tsqa.env.aEd4tI
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 - -
[06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:20:31] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-05-06 23:20:31,337 - Environment prefix is /tmp/tsqa.env.lHClAL
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-05-06 23:20:37,786 - Environment prefix is /tmp/tsqa.env.MRqGAM
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-05-06 23:20:44,246 - Environment prefix is /tmp/tsqa.env.KSne1H
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-06 23:21:05,745 - Environment prefix is /tmp/tsqa.env.XMJHjk
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-06 23:21:27,275 - Environment prefix is /tmp/tsqa.env.5CtfbN
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-06 23:21:30,764 - Environment prefix is /tmp/tsqa.env.bv6hau
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-06 23:21:34,230 - Environment prefix is /tmp/tsqa.env.oi1TYQ
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - -
[06/May/2015 23:21:37] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/1 HTTP/1.1" 302 215
127.0.0.1 - - [06/May/2015 23:21:37] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/10 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [06/May/2015 23:21:37] "GET /redirect/1 HTTP/1.1" 302 215
ok
INFO 2015-05-06 23:21:37,708 - Environment prefix is /tmp/tsqa.env.UaiXj9
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-05-06 23:24:10,643 - 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-06 23:24:20,005 - Environment prefix is /tmp/tsqa.env.AOH1Ew
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [06/May/2015
23:24:23] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:24:23] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:24:23] "GET / HTTP/1.1" 200 5
ok
INFO 2015-05-06 23:24:23,470 - Environment prefix is /tmp/tsqa.env.Yb32Cj
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [06/May/2015
23:24:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:24:26] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [06/May/2015 23:24:26] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-05-06 23:24:26,939 - Environment prefix is /tmp/tsqa.env.GN1hLe
INFO 2015-05-06 23:24:26,941 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-06 23:24:26,941 - spdy server port = 33665
INFO 2015-05-06 23:24:26,941 - http server port = 33870
Test that the origin does in fact support spdy 3 ... INFO 2015-05-06
23:24:30,162 - full args = ['/usr/local/bin/spdycat',
'https://localhost:33665/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-06 23:24:31,493 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:33870/', '-nv',
'--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-05-06 23:24:31,714 - Environment prefix is /tmp/tsqa.env.kQiKCC
INFO 2015-05-06 23:24:31,715 - spdycat path = /usr/local/bin/spdycat
INFO 2015-05-06 23:24:31,716 - spdy server port = 50058
INFO 2015-05-06 23:24:31,716 - http server port = 54404
Test that the origin does in fact support spdy 3.1 ... INFO 2015-05-06
23:24:34,938 - full args = ['/usr/local/bin/spdycat',
'https://localhost:50058/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-05-06 23:24:35,981 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:54404/', '-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.691890001296997 not less than 2
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.RRXKBR
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML:
<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 73 tests in 766.446s
FAILED (SKIP=5, failures=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
