See <https://ci.trafficserver.apache.org/job/tsqa-master/746/changes>
Changes: [Leif Hedstrom] TS-3766 Ignore unknown frame in debugging [Leif Hedstrom] TS-3492 Only send SETTINGS which are different than protocol defaults ------------------------------------------ [...truncated 540 lines...] ERROR INFO 2015-07-30 20:39:07,886 - Environment prefix is /tmp/tsqa.env.ayNq02 test_default_404 (test_example.TestBootstrap) ... ok Test that traffic_line works, and verify that the values for proxy.config ... ok INFO 2015-07-30 20:39:37,772 - 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-07-30 20:40:35,289 - 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-07-30 20:40:35,357 - Environment prefix is /tmp/tsqa.env.mGEZmY test_spdy (test_example.TestConfigureFlags) ... ok INFO 2015-07-30 20:40:38,776 - Environment prefix is /tmp/tsqa.env._Y3hOB test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [30/Jul/2015 20:40:42] "GET /test HTTP/1.1" 404 0 ok INFO 2015-07-30 20:40:42,188 - Environment prefix is /tmp/tsqa.env.iOAWiu test_logs_exist (test_example.TestLogRefCounting) ... 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 127.0.0.1 - - [30/Jul/2015 20:40:45] "GET / HTTP/1.1" 404 0 FAIL INFO 2015-07-30 20:40:55,670 - Environment prefix is /tmp/tsqa.env.1I2_mD test_logs_exist (test_example.TestLogs) ... FAIL SKIP: Skip the entire class INFO 2015-07-30 20:41:09,127 - Environment prefix is /tmp/tsqa.env.zaqiKT test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:12] "GET / HTTP/1.1" 200 5 ok INFO 2015-07-30 20:41:12,590 - Environment prefix is /tmp/tsqa.env.5ymS3w test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok INFO 2015-07-30 20:41:18,014 - Environment prefix is /tmp/tsqa.env.zTSRDy Test basic fnctionality of hosts files ... ok Test that changes to hosts file get loaded within host_file.interval ... ok INFO 2015-07-30 20:41:27,496 - Environment prefix is /tmp/tsqa.env.WUlUBP SKIP: -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.WUlUBP --------------------- >> end captured logging << --------------------- INFO 2015-07-30 20:41:28,523 - Environment prefix is /tmp/tsqa.env.wEtbY6 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-07-30 20:41:32,056 - Environment prefix is /tmp/tsqa.env.Yi65jt 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-07-30 20:41:35,607 - Environment prefix is /tmp/tsqa.env.8Kxxzi 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-07-30 20:41:39,144 - Environment prefix is /tmp/tsqa.env.PG78uD 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 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:41:42] "GET /exists/ HTTP/1.1" 200 5 ok INFO 2015-07-30 20:41:42,559 - Environment prefix is /tmp/tsqa.env.bva4UV Tests that origin_min_keep_alive_connections is honored via http. ... ok INFO 2015-07-30 20:41:48,986 - Environment prefix is /tmp/tsqa.env.vjKv3U Tests that origin_min_keep_alive_connections is honored via https. ... ok INFO 2015-07-30 20:41:55,423 - Environment prefix is /tmp/tsqa.env.kmvadA 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-07-30 20:42:16,909 - Environment prefix is /tmp/tsqa.env.MUKsuR 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-07-30 20:42:38,399 - Environment prefix is /tmp/tsqa.env.PZ38id Tests that keepalive works through ATS to origin via https. ... ok Tests that keepalive works through ATS to origin via https. ... ok INFO 2015-07-30 20:42:41,852 - Environment prefix is /tmp/tsqa.env.ymp0Lf Tests that keepalive works through ATS to origin via https. ... ok Tests that keepalive works through ATS to origin via https. ... ok INFO 2015-07-30 20:42:45,292 - Environment prefix is /tmp/tsqa.env.NSzevs test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/9 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/8 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/7 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/6 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/5 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/4 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/3 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/2 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/1 HTTP/1.1" 302 215 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /get HTTP/1.1" 200 437 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/10 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/9 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/8 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/7 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/6 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/5 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/4 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/3 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/2 HTTP/1.1" 302 229 127.0.0.1 - - [30/Jul/2015 20:42:48] "GET /redirect/1 HTTP/1.1" 302 215 ok INFO 2015-07-30 20:42:48,746 - Environment prefix is /tmp/tsqa.env.TOLnQs test_regressions (test_regressions.TestRegressions) ... ok INFO 2015-07-30 20:45:21,635 - 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-07-30 20:45:30,719 - Environment prefix is /tmp/tsqa.env.d6GYYg test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [30/Jul/2015 20:45:33] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:45:33] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:45:33] "GET / HTTP/1.1" 200 5 ok INFO 2015-07-30 20:45:34,160 - Environment prefix is /tmp/tsqa.env.i2qHgm test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [30/Jul/2015 20:45:37] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:45:37] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [30/Jul/2015 20:45:37] "GET / HTTP/1.1" 200 5 ok SKIP: Skipping spdy/2 tests INFO 2015-07-30 20:45:37,632 - Environment prefix is /tmp/tsqa.env.UuAuhy INFO 2015-07-30 20:45:37,634 - spdycat path = /usr/local/bin/spdycat INFO 2015-07-30 20:45:37,634 - spdy server port = 45184 INFO 2015-07-30 20:45:37,635 - http server port = 48404 Test that the origin does in fact support spdy 3 ... INFO 2015-07-30 20:45:40,860 - full args = ['/usr/local/bin/spdycat', 'https://localhost:45184/', '-nv', '--spdy3'] ok Test that non spdy port won't advertise spdy ... INFO 2015-07-30 20:45:42,247 - full args = ['/usr/local/bin/spdycat', 'https://localhost:48404/', '-nv', '--spdy3'] Fatal: EOF reqnum=1, completed=0 Some requests were not processed. total=1, processed=0 ok INFO 2015-07-30 20:45:42,454 - Environment prefix is /tmp/tsqa.env.S1iv9Y INFO 2015-07-30 20:45:42,456 - spdycat path = /usr/local/bin/spdycat INFO 2015-07-30 20:45:42,456 - spdy server port = 59605 INFO 2015-07-30 20:45:42,456 - http server port = 52478 Test that the origin does in fact support spdy 3.1 ... INFO 2015-07-30 20:45:45,679 - full args = ['/usr/local/bin/spdycat', 'https://localhost:59605/', '-nv', '--spdy3-1'] ok Test that non spdy port won't advertise spdy ... INFO 2015-07-30 20:45:46,859 - full args = ['/usr/local/bin/spdycat', 'https://localhost:52478/', '-nv', '--spdy3-1'] Fatal: EOF reqnum=1, completed=0 Some requests were not processed. total=1, processed=0 ok INFO 2015-07-30 20:45:47,067 - Environment prefix is /tmp/tsqa.env.AVSwI1 Make sure the new ticket key is loaded ... ok Make sure the new ticket key is loaded ... ok ====================================================================== ERROR: test suite for <class 'test_custom_log.TestCustomLogField'> ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 209, in run self.setUp() File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 292, in setUp self.setupContext(ancestor) File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/suite.py",> line 315, in setupContext try_run(context, names) File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/nose/util.py",> line 471, in try_run return func() File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/test_cases.py",> line 86, in setUpClass cls.environment.start() File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 447, in start self.__exec_cop() File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/environment.py",> line 297, in __exec_cop tsqa.utils.poll_interfaces(self.hostports) File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/virtualenv/lib/python2.7/site-packages/tsqa/utils.py",> line 73, in poll_interfaces reduce(lambda x, y: str(x) + ',' + str(y), hostports))) Exception: Timeout waiting for interfaces: ('127.0.0.1', 36501) -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env._Cz50M --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: Verify that we get 504s from origins that return a partial_response ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_connect_attempts.py",> line 195, in test_partial_response_origin self.assertEqual(ret.status_code, 502) AssertionError: 200 != 502 ====================================================================== FAIL: Verify that we get 200s from origins that reset_after_accept ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_connect_attempts.py",> line 201, in test_reset_after_accept_origin self.assertEqual(ret.status_code, 200) AssertionError: 502 != 200 ====================================================================== 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.iOAWiu/var/log/squid.log -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.iOAWiu --------------------- >> end captured logging << --------------------- ====================================================================== FAIL: test_logs_exist (test_example.TestLogs) ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_example.py",> line 172, in test_logs_exist self.assertTrue(os.path.isfile(logfile_path), logfile_path) AssertionError: /tmp/tsqa.env.1I2_mD/var/log/error.log -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.1I2_mD --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- XML: <https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml> ---------------------------------------------------------------------- Ran 79 tests in 844.800s FAILED (SKIP=6, errors=1, failures=4) 1 make: *** [test] Error 1 Build step 'Execute shell' marked build as failure Recording test results