See <https://ci.trafficserver.apache.org/job/tsqa-master/831/changes>
Changes:
[Leif Hedstrom] Update header_rewrite docs to be closer to reality
[Bryan Call] TS-3879: Update headers for Google Profiler and add support for
the heap profiler
------------------------------------------
[...truncated 491 lines...]
INFO 2015-09-01 18:00:20,706 - Client disconnected
INFO 2015-09-01 18:00:20,907 - Environment prefix is /tmp/tsqa.env.FkwPvU
INFO 2015-09-01 18:00:22,698 - 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-01 18:00:33,232 - Environment prefix is /tmp/tsqa.env.9jTd8j
test_log_field (test_custom_log.TestCustomLogField) ... ok
INFO 2015-09-01 18:02:16,819 - Environment prefix is /tmp/tsqa.env.fNnVwP
test_default_404 (test_example.TestBootstrap) ... ok
Test that traffic_line works, and verify that the values for proxy.config ... ok
INFO 2015-09-01 18:02:46,724 - 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-01 18:03:38,564 - 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-01 18:03:38,632 - Environment prefix is /tmp/tsqa.env.VLanvt
test_spdy (test_example.TestConfigureFlags) ... ok
INFO 2015-09-01 18:03:42,053 - Environment prefix is /tmp/tsqa.env.4Hyutu
test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - -
[01/Sep/2015 18:03:45] "GET /test HTTP/1.1" 404 0
ok
INFO 2015-09-01 18:03:45,489 - Environment prefix is /tmp/tsqa.env.DDBBYF
test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - -
[01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
127.0.0.1 - - [01/Sep/2015 18:03:48] "GET / HTTP/1.1" 404 0
FAIL
INFO 2015-09-01 18:03:58,964 - Environment prefix is /tmp/tsqa.env.os7Rf8
test_logs_exist (test_example.TestLogs) ... ok
SKIP: Skip the entire class
INFO 2015-09-01 18:04:12,407 - Environment prefix is /tmp/tsqa.env.x8G9Xs
test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - -
[01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:15] "GET / HTTP/1.1" 200 5
ok
INFO 2015-09-01 18:04:15,890 - Environment prefix is /tmp/tsqa.env.2R7DW5
INFO 2015-09-01 18:04:15,893 - map / http://127.0.0.1:48627/
test_head_request_without_timout
(test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-09-01
18:04:19,121 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Tue, 01 Sep 2015 18:04:19 GMT
Age: 0
Connection: close
INFO 2015-09-01 18:04:19,121 - head request with case(TE) costs 0.002435
seconds while the timout is 5.000000 seconds.
INFO 2015-09-01 18:04:19,122 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Content-Length: 123
Vary: Accept-Encoding
Date: Tue, 01 Sep 2015 18:04:19 GMT
Age: 0
Connection: close
INFO 2015-09-01 18:04:19,123 - head request with case(CL) costs 0.001366
seconds while the timout is 5.000000 seconds.
INFO 2015-09-01 18:04:19,137 - HTTP/1.1 200 OK
Server: ATS/6.1.0
Vary: Accept-Encoding
Date: Tue, 01 Sep 2015 18:04:19 GMT
Age: 0
Connection: close
INFO 2015-09-01 18:04:19,137 - head request with case() costs 0.014737 seconds
while the timout is 5.000000 seconds.
ok
INFO 2015-09-01 18:04:19,327 - Environment prefix is /tmp/tsqa.env._KRSJy
test_working (test_hostdb.TestHostDBBadResolvConf) ... ok
INFO 2015-09-01 18:04:22,881 - Environment prefix is /tmp/tsqa.env.vjhESn
test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok
INFO 2015-09-01 18:04:28,323 - Environment prefix is /tmp/tsqa.env.bVM8hb
Test basic fnctionality of hosts files ... ok
Test that changes to hosts file get loaded within host_file.interval ... ok
INFO 2015-09-01 18:04:37,798 - Environment prefix is /tmp/tsqa.env.9SYOMD
SKIP:
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.9SYOMD
--------------------- >> 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-01 18:04:37,898 - Environment prefix is /tmp/tsqa.env.HudSgm
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-01 18:04:41,452 - Environment prefix is /tmp/tsqa.env.54CGPI
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-01 18:04:44,991 - Environment prefix is /tmp/tsqa.env.eHHJxe
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-01 18:04:48,515 - Environment prefix is /tmp/tsqa.env.Xxg0_2
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 - -
[01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:04:51] "GET /exists/ HTTP/1.1" 200 5
ok
INFO 2015-09-01 18:04:51,988 - Environment prefix is /tmp/tsqa.env.h07MB2
Tests that origin_min_keep_alive_connections is honored via http. ... ok
INFO 2015-09-01 18:04:58,407 - Environment prefix is /tmp/tsqa.env.GD5Eak
Tests that origin_min_keep_alive_connections is honored via https. ... ok
INFO 2015-09-01 18:05:04,878 - Environment prefix is /tmp/tsqa.env.ZukK86
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-01 18:05:26,361 - Environment prefix is /tmp/tsqa.env.wbMQ8m
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-01 18:05:47,883 - Environment prefix is /tmp/tsqa.env.e6c7wX
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-01 18:05:51,326 - Environment prefix is /tmp/tsqa.env.3rs2Et
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-01 18:05:54,760 - Environment prefix is /tmp/tsqa.env.0Gp7HH
INFO 2015-09-01 18:05:54,762 - socket_server_port = 46267
INFO 2015-09-01 18:05:54,762 - starting the socket server
test_origin_min_connection
(test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections)
... INFO 2015-09-01 18:05:57,995 - Sending data back to the client:
ec2fb613fe1745c6b51e2b7acaadb41f
INFO 2015-09-01 18:05:58,004 - ec2fb613fe1745c6b51e2b7acaadb41f
INFO 2015-09-01 18:06:03,011 - Sending data back to the client:
ec2fb613fe1745c6b51e2b7acaadb41f
INFO 2015-09-01 18:06:03,011 - ec2fb613fe1745c6b51e2b7acaadb41f
ok
INFO 2015-09-01 18:06:03,199 - Environment prefix is /tmp/tsqa.env.37H4k6
test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - -
[01/Sep/2015 18:06:06] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/1 HTTP/1.1" 302 215
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /get HTTP/1.1" 200 437
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/10 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/9 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/8 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/7 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/6 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/5 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/4 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/3 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/2 HTTP/1.1" 302 229
127.0.0.1 - - [01/Sep/2015 18:06:06] "GET /redirect/1 HTTP/1.1" 302 215
ok
INFO 2015-09-01 18:06:06,652 - Environment prefix is /tmp/tsqa.env.4doyCu
test_regressions (test_regressions.TestRegressions) ... ok
INFO 2015-09-01 18:08:39,743 - 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-01 18:08:48,733 - Environment prefix is /tmp/tsqa.env.EnN3qh
test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [01/Sep/2015
18:08:51] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:08:51] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:08:51] "GET / HTTP/1.1" 200 5
ok
INFO 2015-09-01 18:08:52,156 - Environment prefix is /tmp/tsqa.env.DeAxS9
test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [01/Sep/2015
18:08:55] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:08:55] "GET / HTTP/1.1" 200 5
127.0.0.1 - - [01/Sep/2015 18:08:55] "GET / HTTP/1.1" 200 5
ok
SKIP: Skipping spdy/2 tests
INFO 2015-09-01 18:08:55,598 - Environment prefix is /tmp/tsqa.env.ZY8RFK
INFO 2015-09-01 18:08:55,599 - spdycat path = /usr/local/bin/spdycat
INFO 2015-09-01 18:08:55,600 - spdy server port = 53569
INFO 2015-09-01 18:08:55,600 - http server port = 38462
Test that the origin does in fact support spdy 3 ... INFO 2015-09-01
18:08:58,826 - full args = ['/usr/local/bin/spdycat',
'https://localhost:53569/', '-nv', '--spdy3']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-09-01 18:09:00,043 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:38462/', '-nv',
'--spdy3']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-09-01 18:09:00,248 - Environment prefix is /tmp/tsqa.env.9n0s_k
INFO 2015-09-01 18:09:00,250 - spdycat path = /usr/local/bin/spdycat
INFO 2015-09-01 18:09:00,250 - spdy server port = 49397
INFO 2015-09-01 18:09:00,251 - http server port = 53136
Test that the origin does in fact support spdy 3.1 ... INFO 2015-09-01
18:09:03,476 - full args = ['/usr/local/bin/spdycat',
'https://localhost:49397/', '-nv', '--spdy3-1']
ok
Test that non spdy port won't advertise spdy ... INFO 2015-09-01 18:09:04,479 -
full args = ['/usr/local/bin/spdycat', 'https://localhost:53136/', '-nv',
'--spdy3-1']
Fatal: EOF
reqnum=1, completed=0
Some requests were not processed. total=1, processed=0
ok
INFO 2015-09-01 18:09:04,690 - Environment prefix is /tmp/tsqa.env.dbE_xu
Make sure the new ticket key is loaded ... ok
Make sure the new ticket key is loaded ... ok
======================================================================
FAIL: test_logs_exist (test_example.TestLogRefCounting)
----------------------------------------------------------------------
Traceback (most recent call last):
File
"<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_example.py";,>
line 215, in test_logs_exist
self.assertTrue(os.path.isfile(logfile_path), logfile_path)
AssertionError: /tmp/tsqa.env.DDBBYF/var/log/squid.log
-------------------- >> begin captured logging << --------------------
root: INFO: Environment prefix is /tmp/tsqa.env.DDBBYF
--------------------- >> end captured logging << ---------------------
----------------------------------------------------------------------
XML:
<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml>
----------------------------------------------------------------------
Ran 84 tests in 931.297s
FAILED (SKIP=7, failures=1)
1
make: *** [test] Error 1
Build step 'Execute shell' marked build as failure
Recording test results
