Thomas, Can you explain what happened here? It also looks like it fixed itself in build #879 but I don't see what fixed it.
Thanks. ---------- Forwarded message --------- From: <jenk...@ci.trafficserver.apache.org> Date: Mon, Sep 21, 2015 at 2:32 PM Subject: Build failed in Jenkins: tsqa-master #878 To: <iss...@trafficserver.apache.org>, <sor...@apache.org>, < shinr...@network-geographics.com> See <https://ci.trafficserver.apache.org/job/tsqa-master/878/changes> Changes: [Phil Sorber] TS-3928: Make TSRemapNewInstance not required in remap plugins ------------------------------------------ [...truncated 478 lines...] INFO 2015-09-21 20:24:24,917 - sending data back to the client INFO 2015-09-21 20:24:26,918 - Client disconnected INFO 2015-09-21 20:24:27,320 - sending data back to the client INFO 2015-09-21 20:24:27,722 - sending data back to the client INFO 2015-09-21 20:24:29,723 - Client disconnected INFO 2015-09-21 20:24:31,727 - sending data back to the client ok INFO 2015-09-21 20:24:33,737 - Client disconnected INFO 2015-09-21 20:24:33,930 - Environment prefix is /tmp/tsqa.env.QW_l59 INFO 2015-09-21 20:24:35,732 - 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 20:24:45,267 - Environment prefix is /tmp/tsqa.env.Ibjc4G test_log_field (test_custom_log.TestCustomLogField) ... FAIL INFO 2015-09-21 20:24:58,705 - Environment prefix is /tmp/tsqa.env.r9hn3s 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 20:25:28,583 - 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 20:26:23,133 - 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 20:26:23,198 - Environment prefix is /tmp/tsqa.env.E9aKc3 test_spdy (test_example.TestConfigureFlags) ... ok INFO 2015-09-21 20:26:26,600 - Environment prefix is /tmp/tsqa.env.TUVQR1 test_basic_proxy (test_example.TestDynamicHTTPEndpointCase) ... 127.0.0.1 - - [21/Sep/2015 20:26:29] "GET /test HTTP/1.1" 404 0 ok INFO 2015-09-21 20:26:30,019 - Environment prefix is /tmp/tsqa.env.h1qVod test_logs_exist (test_example.TestLogs) ... ok SKIP: Skip the entire class INFO 2015-09-21 20:26:43,578 - Environment prefix is /tmp/tsqa.env.c2sjuI test_basic_intercept (test_example.TestServerIntercept) ... 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:26:46] "GET / HTTP/1.1" 200 5 ok INFO 2015-09-21 20:26:47,126 - Environment prefix is /tmp/tsqa.env.bNtjVc INFO 2015-09-21 20:26:47,129 - map / http://127.0.0.1:44193/ test_head_request_without_timout (test_headrequest.TestHeadRequestWithoutTimeout) ... INFO 2015-09-21 20:26:50,454 - HTTP/1.1 200 OK Server: ATS/6.1.0 Vary: Accept-Encoding Date: Mon, 21 Sep 2015 20:26:50 GMT Age: 0 Connection: close INFO 2015-09-21 20:26:50,454 - head request with case(TE) costs 0.002146 seconds while the timout is 5.000000 seconds. INFO 2015-09-21 20:26:50,456 - HTTP/1.1 200 OK Server: ATS/6.1.0 Content-Length: 123 Vary: Accept-Encoding Date: Mon, 21 Sep 2015 20:26:50 GMT Age: 0 Connection: close INFO 2015-09-21 20:26:50,456 - head request with case(CL) costs 0.001383 seconds while the timout is 5.000000 seconds. INFO 2015-09-21 20:26:50,467 - HTTP/1.1 200 OK Server: ATS/6.1.0 Vary: Accept-Encoding Date: Mon, 21 Sep 2015 20:26:50 GMT Age: 0 Connection: close INFO 2015-09-21 20:26:50,467 - head request with case() costs 0.011164 seconds while the timout is 5.000000 seconds. ok INFO 2015-09-21 20:26:50,652 - Environment prefix is /tmp/tsqa.env.wXhIx9 test_working (test_hostdb.TestHostDBBadResolvConf) ... ok INFO 2015-09-21 20:26:54,201 - Environment prefix is /tmp/tsqa.env.TiGmGS test_lookup_timeout (test_hostdb.TestHostDBFailedDNS) ... ok INFO 2015-09-21 20:26:59,645 - Environment prefix is /tmp/tsqa.env.tzYmXB Test basic fnctionality of hosts files ... ok Test that changes to hosts file get loaded within host_file.interval ... ok INFO 2015-09-21 20:27:09,127 - Environment prefix is /tmp/tsqa.env.bplwI6 SKIP: -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.bplwI6 --------------------- >> 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 20:27:09,211 - Environment prefix is /tmp/tsqa.env.TyvpYz SKIP: Cannot find h2spec. skipping test. -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.TyvpYz --------------------- >> end captured logging << --------------------- INFO 2015-09-21 20:27:09,306 - Environment prefix is /tmp/tsqa.env._1afIS 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 20:27:12,819 - Environment prefix is /tmp/tsqa.env.YoZLRc 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 20:27:16,347 - Environment prefix is /tmp/tsqa.env.iTdesP 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 20:27:19,979 - Environment prefix is /tmp/tsqa.env._SzFzE 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 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:27:23] "GET /exists/ HTTP/1.1" 200 5 ok INFO 2015-09-21 20:27:23,400 - Environment prefix is /tmp/tsqa.env.bY7_Cz Tests that origin_min_keep_alive_connections is honored via http. ... ok INFO 2015-09-21 20:27:29,809 - Environment prefix is /tmp/tsqa.env.kq98xh Tests that origin_min_keep_alive_connections is honored via https. ... ok INFO 2015-09-21 20:27:36,234 - Environment prefix is /tmp/tsqa.env.1fLrLK 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 20:27:57,708 - Environment prefix is /tmp/tsqa.env.Urs8GO 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 20:28:19,220 - Environment prefix is /tmp/tsqa.env.RQG02M 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 20:28:22,714 - Environment prefix is /tmp/tsqa.env.ctIJgf 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 20:28:26,148 - Environment prefix is /tmp/tsqa.env.RH7bZz INFO 2015-09-21 20:28:26,150 - socket_server_port = 42658 INFO 2015-09-21 20:28:26,150 - starting the socket server test_origin_min_connection (test_origin_min_keep_alive_connection.TestKeepAlive_Origin_Min_connections) ... INFO 2015-09-21 20:28:29,390 - Sending data back to the client: 2c9231fe48eb436fa0430ee0aa401a35 INFO 2015-09-21 20:28:29,399 - 2c9231fe48eb436fa0430ee0aa401a35 INFO 2015-09-21 20:28:34,403 - Sending data back to the client: 2c9231fe48eb436fa0430ee0aa401a35 INFO 2015-09-21 20:28:34,404 - 2c9231fe48eb436fa0430ee0aa401a35 ok INFO 2015-09-21 20:28:34,593 - Environment prefix is /tmp/tsqa.env.VvxUjL test_redirection (test_redirection.TestRedirection) ... 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /redirect/9 HTTP/1.1" 302 247 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/8 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/7 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/6 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/5 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/4 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/3 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/2 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/1 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /get HTTP/1.1" 200 437 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /redirect/10 HTTP/1.1" 302 247 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/9 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/8 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/7 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/6 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/5 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/4 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/3 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/2 HTTP/1.1" 302 0 127.0.0.1 - - [21/Sep/2015 20:28:37] "GET /relative-redirect/1 HTTP/1.1" 302 0 ok INFO 2015-09-21 20:28:38,052 - Environment prefix is /tmp/tsqa.env.Xjc66l test_regressions (test_regressions.TestRegressions) ... ok INFO 2015-09-21 20:31:10,620 - 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 20:31:20,480 - Environment prefix is /tmp/tsqa.env.sU7owp test_remap_http (test_remap.TestRemapHTTP) ... 127.0.0.1 - - [21/Sep/2015 20:31:23] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:31:23] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:31:23] "GET / HTTP/1.1" 200 5 ok INFO 2015-09-21 20:31:23,922 - Environment prefix is /tmp/tsqa.env.aPn0lf test_remap_https (test_remap.TestRemapHTTPS) ... 127.0.0.1 - - [21/Sep/2015 20:31:27] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:31:27] "GET / HTTP/1.1" 200 5 127.0.0.1 - - [21/Sep/2015 20:31:27] "GET / HTTP/1.1" 200 5 ok SKIP: Skipping spdy/2 tests INFO 2015-09-21 20:31:27,437 - Environment prefix is /tmp/tsqa.env.tQgLPq INFO 2015-09-21 20:31:27,439 - spdycat path = /usr/local/bin/spdycat INFO 2015-09-21 20:31:27,440 - spdy server port = 53032 INFO 2015-09-21 20:31:27,440 - http server port = 53208 Test that the origin does in fact support spdy 3 ... INFO 2015-09-21 20:31:30,671 - full args = ['/usr/local/bin/spdycat', ' https://localhost:53032/', '-nv', '--spdy3'] ok Test that non spdy port won't advertise spdy ... INFO 2015-09-21 20:31:31,851 - full args = ['/usr/local/bin/spdycat', ' https://localhost:53208/', '-nv', '--spdy3'] Fatal: EOF reqnum=1, completed=0 Some requests were not processed. total=1, processed=0 ok INFO 2015-09-21 20:31:32,058 - Environment prefix is /tmp/tsqa.env.tuLB5y INFO 2015-09-21 20:31:32,059 - spdycat path = /usr/local/bin/spdycat INFO 2015-09-21 20:31:32,060 - spdy server port = 41208 INFO 2015-09-21 20:31:32,060 - http server port = 48835 Test that the origin does in fact support spdy 3.1 ... INFO 2015-09-21 20:31:35,289 - full args = ['/usr/local/bin/spdycat', ' https://localhost:41208/', '-nv', '--spdy3-1'] ok Test that non spdy port won't advertise spdy ... INFO 2015-09-21 20:31:36,419 - full args = ['/usr/local/bin/spdycat', ' https://localhost:48835/', '-nv', '--spdy3-1'] Fatal: EOF reqnum=1, completed=0 Some requests were not processed. total=1, processed=0 ok INFO 2015-09-21 20:31:36,637 - Environment prefix is /tmp/tsqa.env.1ZF7Xb Make sure the new ticket key is loaded ... ok Make sure the new ticket key is loaded ... ok ====================================================================== FAIL: test_log_field (test_custom_log.TestCustomLogField) ---------------------------------------------------------------------- Traceback (most recent call last): File "< https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/tests/test_custom_log.py";,> line 70, in test_log_field self.assertEqual(line, expected_line) AssertionError: '127.0.0.1 7F000001\n' != '127.240.190.207 7FF0BECF\n' -------------------- >> begin captured logging << -------------------- root: INFO: Environment prefix is /tmp/tsqa.env.Ibjc4G --------------------- >> end captured logging << --------------------- ---------------------------------------------------------------------- XML: < https://ci.trafficserver.apache.org/job/tsqa-master/ws/src/ci/tsqa/nosetests.xml > ---------------------------------------------------------------------- Ran 83 tests in 838.521s FAILED (SKIP=8, failures=1) 2 make: *** [test] Error 1 Build step 'Execute shell' marked build as failure Recording test results
