See <https://ci.trafficserver.apache.org/job/tsqa-master/877/changes>
Changes:
[shinrich] [TS-3911] New log tag for proxy connection being over SSL, pqssl.
This closes #293.
------------------------------------------
[...truncated 490 lines...]
INFO 2015-09-21 19:42:52,724 - 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 ... ok
Verify that we get 502s from origins that reset_after_accept, once any bytes
are sent to origin we assume we cannot re-dispatch ... ok
INFO 2015-09-21 19:43:02,236 - Environment prefix is /tmp/tsqa.env.w6iPYC
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-09-21 19:44:45,804 - Environment prefix is /tmp/tsqa.env.zHt2GD
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-09-21 19:45:15,812 - 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-09-21 19:46:38,785 - 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-09-21 19:46:38,853 - Environment prefix is /tmp/tsqa.env.R8fCb2
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-09-21 19:46:42,278 - Environment prefix is /tmp/tsqa.env.OZvCGi
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - -
[21/Sep/2015 19:46:45] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-09-21 19:46:45,705 - Environment prefix is /tmp/tsqa.env.XxP5_w
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-09-21 19:46:59,207 - Environment prefix is /tmp/tsqa.env.svQLgV
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - -
[21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:02] "GET / HTTP/1.1" 200 5
ok
INFO 2015-09-21 19:47:02,679 - Environment prefix is /tmp/tsqa.env.XFtVxM
INFO 2015-09-21 19:47:02,681 - map / http://127.0.0.1:53681/
test_head_request_without_timout
(test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-09-21
19:47:05,907 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 21 Sep 2015 19:47:05 GMT
Age: 0
Connection: close
INFO 2015-09-21 19:47:05,907 - head request with case(TE) costs 0.003251
seconds while the timout is 5.000000 seconds.
INFO 2015-09-21 19:47:05,908 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 21 Sep 2015 19:47:05 GMT
Age: 0
Connection: close
INFO 2015-09-21 19:47:05,908 - head request with case(CL) costs 0.000766
seconds while the timout is 5.000000 seconds.
FAIL
INFO 2015-09-21 19:47:06,097 - Environment prefix is /tmp/tsqa.env.awq6ab
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-09-21 19:47:09,545 - Environment prefix is /tmp/tsqa.env.L4qcZV
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-09-21 19:47:14,975 - Environment prefix is /tmp/tsqa.env.vC1LQg
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-09-21 19:47:24,446 - Environment prefix is /tmp/tsqa.env.I6Jm84
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.I6Jm84
--------------------- >> end captured logging << ---------------------
Failure: SkipTest (Cannot import hyper, skipping tests for HTTP/2) ... SKIP:
Cannot import hyper, skipping tests for HTTP/2
INFO 2015-09-21 19:47:24,533 - Environment prefix is /tmp/tsqa.env.vBKnZa
SKIP: Cannot find h2spec. skipping test.
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.vBKnZa
--------------------- >> end captured logging << ---------------------
INFO 2015-09-21 19:47:24,632 - Environment prefix is /tmp/tsqa.env.GtRIfc
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-09-21 19:47:28,164 - Environment prefix is /tmp/tsqa.env.z1r0uK
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-09-21 19:47:31,707 - Environment prefix is /tmp/tsqa.env.Y855iA
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-09-21 19:47:35,332 - Environment prefix is /tmp/tsqa.env.dYnghd
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 - -
[21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:47:38] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-09-21 19:47:38,748 - Environment prefix is /tmp/tsqa.env.3QIHTe
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-09-21 19:47:45,178 - Environment prefix is /tmp/tsqa.env.HkKNL0
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-09-21 19:47:51,600 - Environment prefix is /tmp/tsqa.env.jky8Zc
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-09-21 19:48:13,078 - Environment prefix is /tmp/tsqa.env.aoBSpK
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-09-21 19:48:34,562 - Environment prefix is /tmp/tsqa.env.RFhB0B
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-09-21 19:48:38,038 - Environment prefix is /tmp/tsqa.env._A8fm_
Tests that keepalive works through ATS to origin via https. ... ok
Tests that keepalive works through ATS to origin via https. ... ok
INFO 2015-09-21 19:48:41,525 - Environment prefix is /tmp/tsqa.env.Z4rX9l
INFO 2015-09-21 19:48:41,527 - socket_server_port = 57318
INFO 2015-09-21 19:48:41,527 - starting the socket server
test_origin_min_connection
(test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections)
... INFO 2015-09-21 19:48:44,762 - Sending data back to the client:
5e0510827d344dcea87f27867092352e
INFO 2015-09-21 19:48:44,763 - 5e0510827d344dcea87f27867092352e
INFO 2015-09-21 19:48:49,773 - Sending data back to the client:
5e0510827d344dcea87f27867092352e
INFO 2015-09-21 19:48:49,773 - 5e0510827d344dcea87f27867092352e
ok
INFO 2015-09-21 19:48:49,960 - Environment prefix is /tmp/tsqa.env.vLR096
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - -
[21/Sep/2015 19:48:53] "GET /redirect/9 HTTP/1.1" 302 247
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/1 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /redirect/10 HTTP/1.1" 302 247
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/9 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/8 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/7 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/6 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/5 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/4 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/3 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/2 HTTP/1.1" 302 0
127.0.0.1 - - [21/Sep/2015 19:48:53] "GET /relative-redirect/1 HTTP/1.1" 302 0
ok
INFO 2015-09-21 19:48:53,415 - Environment prefix is /tmp/tsqa.env.8olgc8
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-09-21 19:51:24,887 - 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-09-21 19:51:34,087 - Environment prefix is /tmp/tsqa.env.q434KJ
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [21/Sep/2015
19:51:37] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:51:37] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:51:37] "GET / HTTP/1.1" 200 5
ok
INFO 2015-09-21 19:51:37,522 - Environment prefix is /tmp/tsqa.env.cLVzKf
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [21/Sep/2015
19:51:40] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:51:40] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [21/Sep/2015 19:51:40] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-09-21 19:51:40,975 - Environment prefix is /tmp/tsqa.env.cGlPKD
INFO 2015-09-21 19:51:40,977 - spdycat path = /usr/local/bin/spdycat
INFO 2015-09-21 19:51:40,978 - spdy server port = 54863
INFO 2015-09-21 19:51:40,978 - http server port = 60751
Test that the origin does in fact support spdy 3 ... INFO 2015-09-21
19:51:44,204 - full args = ['/usr/local/bin/spdycat',
'https://localhost:54863/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-09-21 19:51:45,333 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:60751/', '-nv',
'--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-09-21 19:51:45,539 - Environment prefix is /tmp/tsqa.env.6nAtix
INFO 2015-09-21 19:51:45,541 - spdycat path = /usr/local/bin/spdycat
INFO 2015-09-21 19:51:45,541 - spdy server port = 53956
INFO 2015-09-21 19:51:45,542 - http server port = 42537
Test that the origin does in fact support spdy 3.1 ... INFO 2015-09-21
19:51:48,772 - full args = ['/usr/local/bin/spdycat',
'https://localhost:53956/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-09-21 19:51:49,808 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:42537/', '-nv',
'--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-09-21 19:51:50,018 - Environment prefix is /tmp/tsqa.env.7xw5IU
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok
======================================================================
FAIL: test_head_request_without_timout
(test_headrequest.TestHeadRequestWithoutTimeout)
----------------------------------------------------------------------
Traceback (most recent call last):
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_headrequest.py";,>
line 115, in test_head_request_without_timout
cls.assertIn('Content-Length', response_content)
AssertionError: 'Content-Length' not found in 'HTTP/1.1 200 OK\r\nServer:
ATS/6.1.0\r\nVary: Accept-Encoding\r\nDate: Mon, 21 Sep 2015 19:47:05
GMT\r\nAge: 0\r\nConnection: close\r\n\r\n'
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.XFtVxM
test_headrequest: INFO: map / http://127.0.0.1:53681/
test_headrequest: INFO: HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 21 Sep 2015 19:47:05 GMT
Age: 0
Connection: close
test_headrequest: INFO: head request with case(TE) costs 0.003251 seconds while
the timout is 5.000000 seconds.
test_headrequest: INFO: HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Mon, 21 Sep 2015 19:47:05 GMT
Age: 0
Connection: close
test_headrequest: INFO: head request with case(CL) costs 0.000766 seconds while
the timout is 5.000000 seconds.
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML:
<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 83 tests in 1072.211s
FAILED (SKIP=8, failures=1)
2
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
