See <https://ci.trafficserver.apache.org/job/tsqa-master/833/changes>
Changes: [solidwallofcode] TS-3848: Extend wait_for_cache to specify dependency on cache initialization. [solidwallofcode] TS-3848: Documentation update. ------------------------------------------ [...truncated 491 lines...] INFO 2015-09-03 14:21:06,960 - Client disconnected INFO 2015-09-03 14:21:07,160 - Environment prefix is /tmp/tsqa.env.t6XHFX INFO 2015-09-03 14:21:08,955 - 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-03 14:21:18,474 - Environment prefix is /tmp/tsqa.env.mah2Qp test_log_field (test_custom_log.TestCustomLogField) ... ok INFO 2015-09-03 14:23:02,068 - Environment prefix is /tmp/tsqa.env.Vr1pWx test_default_404 (test_example.TestBootstrap) ... ok Test that traffic_line works, and verify that the values for proxy.config ... ok INFO 2015-09-03 14:23:31,877 - 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-03 14:25:24,366 - 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-03 14:25:24,433 - Environment prefix is /tmp/tsqa.env.N2EYLd test_spdy (test_example.TestConfigureFlags) ... ok INFO 2015-09-03 14:25:27,860 - Environment prefix is /tmp/tsqa.env.5T_Ce2 test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [03/Sep/2015 14:25:31] "GET /test HTTP/1.1" 404 0 ok INFO 2015-09-03 14:25:31,291 - Environment prefix is /tmp/tsqa.env.Dgvb5v test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [03/Sep/2015 14:25:34] "GET / HTTP/1.1" 404 0 FAIL INFO 2015-09-03 14:25:44,839 - Environment prefix is /tmp/tsqa.env.q33BH3 test_logs_exist (test_example.TestLogs) ... ok SKIP: Skip the entire class INFO 2015-09-03 14:25:58,318 - Environment prefix is /tmp/tsqa.env.Erfqq9 test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:01] "GET / HTTP/1.1" 200 5 ok INFO 2015-09-03 14:26:01,782 - Environment prefix is /tmp/tsqa.env.a_DyR1 INFO 2015-09-03 14:26:01,784 - map / http://127.0.0.1:42929/ test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-09-03 14:26:05,024 - HTTP/1.1 200 OK Server: ATS/6.1.0 Vary: Accept-Encoding Date: Thu, 03 Sep 2015 14:26:05 GMT Age: 0 Connection: close INFO 2015-09-03 14:26:05,024 - head request with case(TE) costs 0.014487 seconds while the timout is 5.000000 seconds. INFO 2015-09-03 14:26:05,026 - HTTP/1.1 200 OK Server: ATS/6.1.0 Content-Length: 123 Vary: Accept-Encoding Date: Thu, 03 Sep 2015 14:26:05 GMT Age: 0 Connection: close INFO 2015-09-03 14:26:05,026 - head request with case(CL) costs 0.001596 seconds while the timout is 5.000000 seconds. INFO 2015-09-03 14:26:05,027 - HTTP/1.1 200 OK Server: ATS/6.1.0 Vary: Accept-Encoding Date: Thu, 03 Sep 2015 14:26:05 GMT Age: 0 Connection: close INFO 2015-09-03 14:26:05,027 - head request with case() costs 0.001026 seconds while the timout is 5.000000 seconds. ok INFO 2015-09-03 14:26:05,210 - Environment prefix is /tmp/tsqa.env.w0uUAb test_working (test_hostdb.TestHostDBBadResolvConf) ... ok INFO 2015-09-03 14:26:08,661 - Environment prefix is /tmp/tsqa.env.2oNp7p test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok INFO 2015-09-03 14:26:14,090 - Environment prefix is /tmp/tsqa.env.n3xb83 Test basic fnctionality of hosts files ... ok Test that changes to hosts file get loaded within host_file.interval ... ok INFO 2015-09-03 14:26:23,582 - Environment prefix is /tmp/tsqa.env.9TlKSK SKIP: -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.9TlKSK --------------------- >> 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-03 14:26:23,688 - Environment prefix is /tmp/tsqa.env.jndSa_ 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-03 14:26:27,204 - Environment prefix is /tmp/tsqa.env.P46fx4 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-03 14:26:30,859 - Environment prefix is /tmp/tsqa.env.ZeMPb8 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-03 14:26:34,495 - Environment prefix is /tmp/tsqa.env.PdWbBJ 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 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:26:37] "GET /exists/ HTTP/1.1" 200 5 ok INFO 2015-09-03 14:26:37,920 - Environment prefix is /tmp/tsqa.env.8KH5eD Tests that origin_min_keep_alive_connections is honored via http. ... ok INFO 2015-09-03 14:26:44,372 - Environment prefix is /tmp/tsqa.env.zDkii1 Tests that origin_min_keep_alive_connections is honored via https. ... ok INFO 2015-09-03 14:26:50,814 - Environment prefix is /tmp/tsqa.env.Ck4bfu 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-03 14:27:12,290 - Environment prefix is /tmp/tsqa.env.R2LUpe 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-03 14:27:33,785 - Environment prefix is /tmp/tsqa.env.xIoTzI 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-03 14:27:37,278 - Environment prefix is /tmp/tsqa.env.dFCSod 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-03 14:27:40,747 - Environment prefix is /tmp/tsqa.env.qHvdt3 INFO 2015-09-03 14:27:40,749 - socket_server_port = 46645 INFO 2015-09-03 14:27:40,749 - starting the socket server test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-09-03 14:27:43,994 - Sending data back to the client: 4c52ebcdda5a4fe8866acfa9b8cdf270 INFO 2015-09-03 14:27:43,996 - 4c52ebcdda5a4fe8866acfa9b8cdf270 INFO 2015-09-03 14:27:49,001 - Sending data back to the client: 4c52ebcdda5a4fe8866acfa9b8cdf270 INFO 2015-09-03 14:27:49,001 - 4c52ebcdda5a4fe8866acfa9b8cdf270 ok INFO 2015-09-03 14:27:49,186 - Environment prefix is /tmp/tsqa.env.KLOAb0 test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/9 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/8 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/7 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/6 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/5 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/4 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/3 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/2 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/1 HTTP/1.1" 302 215 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /get HTTP/1.1" 200 437 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/10 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/9 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/8 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/7 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/6 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/5 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/4 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/3 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/2 HTTP/1.1" 302 229 127.0.0.1 - - [03/Sep/2015 14:27:52] "GET /redirect/1 HTTP/1.1" 302 215 ok INFO 2015-09-03 14:27:52,634 - Environment prefix is /tmp/tsqa.env.ZQHcBw test_regressions (test_regressions.TestRegressions) ... ok INFO 2015-09-03 14:30:25,916 - 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-03 14:30:34,943 - Environment prefix is /tmp/tsqa.env.sSQeuk test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [03/Sep/2015 14:30:38] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:30:38] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:30:38] "GET / HTTP/1.1" 200 5 ok INFO 2015-09-03 14:30:38,491 - Environment prefix is /tmp/tsqa.env.wmLPr3 test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [03/Sep/2015 14:30:41] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:30:41] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [03/Sep/2015 14:30:41] "GET / HTTP/1.1" 200 5 ok SKIP: Skipping spdy/2 tests INFO 2015-09-03 14:30:42,049 - Environment prefix is /tmp/tsqa.env.285WGb INFO 2015-09-03 14:30:42,051 - spdycat path = /usr/local/bin/spdycat INFO 2015-09-03 14:30:42,051 - spdy server port = 37326 INFO 2015-09-03 14:30:42,051 - http server port = 50487 Test that the origin does in fact support spdy 3 ... INFO 2015-09-03 14:30:45,275 - full args = ['/usr/local/bin/spdycat', 'https://localhost:37326/', '-nv', '--spdy3'] ok Test that non spdy port won't advertise spdy ... INFO 2015-09-03 14:30:46,733 - full args = ['/usr/local/bin/spdycat', 'https://localhost:50487/', '-nv', '--spdy3'] Fatal: EOF reqnum=1, completed=0 Some requests were not processed. total=1, processed=0 ok INFO 2015-09-03 14:30:46,932 - Environment prefix is /tmp/tsqa.env.F_pVuw INFO 2015-09-03 14:30:46,934 - spdycat path = /usr/local/bin/spdycat INFO 2015-09-03 14:30:46,934 - spdy server port = 54759 INFO 2015-09-03 14:30:46,935 - http server port = 45803 Test that the origin does in fact support spdy 3.1 ... INFO 2015-09-03 14:30:50,161 - full args = ['/usr/local/bin/spdycat', 'https://localhost:54759/', '-nv', '--spdy3-1'] ok Test that non spdy port won't advertise spdy ... INFO 2015-09-03 14:30:51,565 - full args = ['/usr/local/bin/spdycat', 'https://localhost:45803/', '-nv', '--spdy3-1'] Fatal: EOF reqnum=1, completed=0 Some requests were not processed. total=1, processed=0 ok INFO 2015-09-03 14:30:51,770 - Environment prefix is /tmp/tsqa.env.T_qH21 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.Dgvb5v/var/log/squid.log -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.Dgvb5v --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml> ---------------------------------------------------------------------- Ran 84 tests in 1219.798s FAILED (SKIP=7, failures=1) 1 make: *** [test] Error 1 Build step 'Execute shell' marked build as failure Recording test results