test 69
Start 69: system_tests_http1_over_tcp
69: Test command: /opt/python/3.6.8/bin/python3.6 "/home/travis/build/apache/qpid-dispatch/build/tests/run.py" "-m" "unittest" "-v" "system_tests_http1_over_tcp"
69: Test timeout computed to be: 1200
69: test_01_concurrent_requests (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest) ... TestClient: c470bf97aa5847beabbb59ee1c61e526
69: 2021-05-19 15:51:29.424833 TestClient connecting on 127.0.0.1:24777
69: 2021-05-19 15:51:29.424874 TestClient connected
69: 2021-05-19 15:51:29.424879 TestClient start request 0
69: 2021-05-19 15:51:29.424883 TestClient sending PUT /PUT/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:29.425373 TestClient getting PUT response
69: 2021-05-19 15:51:30.282139 TestClient response PUT received
69: 2021-05-19 15:51:30.282171 TestClient request PUT /PUT/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:30.282176 TestClient sending GET /GET/test_01_concurrent_requests_11_small request
69: 2021-05-19 15:51:30.282246 TestClient getting GET response
69: 2021-05-19 15:51:30.519709 TestClient response GET received
69: 2021-05-19 15:51:30.519810 TestClient request GET /GET/test_01_concurrent_requests_11_small completed!
69: 2021-05-19 15:51:30.519816 TestClient sending GET /GET/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:30.519905 TestClient getting GET response
69: 2021-05-19 15:51:31.219387 TestClient response GET received
69: 2021-05-19 15:51:31.690291 TestClient request GET /GET/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:31.690308 TestClient start request 1
69: 2021-05-19 15:51:31.690316 TestClient sending PUT /PUT/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:31.690553 TestClient getting PUT response
69: 2021-05-19 15:51:32.307847 TestClient response PUT received
69: 2021-05-19 15:51:32.307931 TestClient request PUT /PUT/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:32.307938 TestClient sending GET /GET/test_01_concurrent_requests_11_small request
69: 2021-05-19 15:51:32.308040 TestClient getting GET response
69: 2021-05-19 15:51:32.637675 TestClient response GET received
69: 2021-05-19 15:51:32.637710 TestClient request GET /GET/test_01_concurrent_requests_11_small completed!
69: 2021-05-19 15:51:32.637714 TestClient sending GET /GET/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:32.637780 TestClient getting GET response
69: 2021-05-19 15:51:33.067768 TestClient response GET received
69: 2021-05-19 15:51:33.740987 TestClient request GET /GET/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:33.740998 TestClient start request 2
69: 2021-05-19 15:51:33.741004 TestClient sending PUT /PUT/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:33.741220 TestClient getting PUT response
69: 2021-05-19 15:51:34.523878 TestClient response PUT received
69: 2021-05-19 15:51:34.523955 TestClient request PUT /PUT/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:34.523960 TestClient sending GET /GET/test_01_concurrent_requests_11_small request
69: 2021-05-19 15:51:34.524036 TestClient getting GET response
69: 2021-05-19 15:51:34.977493 TestClient response GET received
69: 2021-05-19 15:51:34.977546 TestClient request GET /GET/test_01_concurrent_requests_11_small completed!
69: 2021-05-19 15:51:34.977551 TestClient sending GET /GET/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:34.977662 TestClient getting GET response
69: 2021-05-19 15:51:35.886808 TestClient response GET received
69: 2021-05-19 15:51:36.406301 TestClient request GET /GET/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:36.406315 TestClient start request 3
69: 2021-05-19 15:51:36.406321 TestClient sending PUT /PUT/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:36.406545 TestClient getting PUT response
69: 2021-05-19 15:51:37.316868 TestClient response PUT received
69: 2021-05-19 15:51:37.316961 TestClient request PUT /PUT/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:37.316969 TestClient sending GET /GET/test_01_concurrent_requests_11_small request
69: 2021-05-19 15:51:37.317073 TestClient getting GET response
69: 2021-05-19 15:51:38.007026 TestClient response GET received
69: 2021-05-19 15:51:38.008632 TestClient request GET /GET/test_01_concurrent_requests_11_small completed!
69: 2021-05-19 15:51:38.008641 TestClient sending GET /GET/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:38.008743 TestClient getting GET response
69: 2021-05-19 15:51:38.399380 TestClient response GET received
69: 2021-05-19 15:51:38.800416 TestClient request GET /GET/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:38.800429 TestClient start request 4
69: 2021-05-19 15:51:38.800436 TestClient sending PUT /PUT/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:38.800696 TestClient getting PUT response
69: 2021-05-19 15:51:39.641893 TestClient response PUT received
69: 2021-05-19 15:51:39.641988 TestClient request PUT /PUT/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:39.642005 TestClient sending GET /GET/test_01_concurrent_requests_11_small request
69: 2021-05-19 15:51:39.642113 TestClient getting GET response
69: 2021-05-19 15:51:40.317618 TestClient response GET received
69: 2021-05-19 15:51:40.320628 TestClient request GET /GET/test_01_concurrent_requests_11_small completed!
69: 2021-05-19 15:51:40.320640 TestClient sending GET /GET/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:40.320751 TestClient getting GET response
69: 2021-05-19 15:51:40.891824 TestClient response GET received
69: 2021-05-19 15:51:41.362729 TestClient request GET /GET/test_01_concurrent_requests_11 completed!
69: 2021-05-19 15:51:41.362743 TestClient start request 5
69: 2021-05-19 15:51:41.362751 TestClient sending PUT /PUT/test_01_concurrent_requests_11 request
69: 2021-05-19 15:51:41.362981 TestClient getting PUT response
69: 2021-05-19 15:55:29.483987 TestClient response PUT received
69: 2021-05-19 15:55:29.484008 TestClient response invalid: Bad response code, expected 201 got 503
69: 2021-05-19 15:55:29.488685 TestClient 127.0.0.1:24777 shut down
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=10, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 53792), raddr=('127.0.0.1', 24777)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=13, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 56870), raddr=('127.0.0.1', 24779)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=12, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 53796), raddr=('127.0.0.1', 24777)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=11, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 56874), raddr=('127.0.0.1', 24779)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=14, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 53800), raddr=('127.0.0.1', 24777)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=17, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 56844), raddr=('127.0.0.1', 24779)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=16, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 53804), raddr=('127.0.0.1', 24777)>
69: self._sock = None
69: /opt/python/3.6.8/lib/python3.6/socket.py:657: ResourceWarning: unclosed <socket.socket fd=15, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('127.0.0.1', 56912), raddr=('127.0.0.1', 24779)>
69: self._sock = None
69: FAIL
69: test_02_credit_replenish (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest) ... FAIL
69: test_03_server_reconnect (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest) ... FAIL
69: test_04_server_pining_for_the_fjords (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest) ... FAIL
69: test_05_large_streaming_msg (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest) ... FAIL
69:
69: Router EA2 output file:
69: >>>>
69: -----------------------------------------------------
69: Suppressions used:
69: count bytes template
69: 1 56 ^IoAdapter_init$
69: 2 48 ^pn_condition$
69: 2 3072 ^pn_raw_connection$
69: 10 496 ^pn_object_new$
69: 2 256 ^pn_list$
69: 4 96 ^pni_record_create$
69: 574 879980 /libpython3.*.so
69: -----------------------------------------------------
69:
69:
69: <<<<
69:
69: Router EA2 debug dump file:
69: >>>>
69: alloc.c: Items of type 'qd_buffer_t' remain allocated at shutdown: 17 (SUPPRESSED)
69:
69: <<<<
69:
69: Router EA1 output file:
69: >>>>
69: -----------------------------------------------------
69: Suppressions used:
69: count bytes template
69: 1 56 ^IoAdapter_init$
69: 574 879980 /libpython3.*.so
69: -----------------------------------------------------
69: <<<<
69: test_001_get (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69: test_002_head (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69: test_003_post (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69: test_004_put (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69: test_006_head_10 (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69: test_007_post_10 (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69: test_008_put_10 (system_tests_http1_over_tcp.Http1OverTcpOneRouterTest) ... ok
69:
69: Router INT.A output file:
69: >>>>
69: -----------------------------------------------------
69: Suppressions used:
69: count bytes template
69: 1 56 ^IoAdapter_init$
69: 571 877716 /libpython3.*.so
69: -----------------------------------------------------
69:
69:
69: <<<<
69:
69: ======================================================================
69: FAIL: test_01_concurrent_requests (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest)
69: ----------------------------------------------------------------------
69: Traceback (most recent call last):
69: File "/home/travis/build/apache/qpid-dispatch/tests/http1_tests.py", line 501, in test_01_concurrent_requests
69: self.assertIsNone(client.error)
69: AssertionError: 'client failed: Bad response code, expected 201 got 503' is not None
69:
69: ======================================================================
69: FAIL: test_02_credit_replenish (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest)
69: ----------------------------------------------------------------------
69: Traceback (most recent call last):
69: File "/home/travis/build/apache/qpid-dispatch/tests/http1_tests.py", line 533, in test_02_credit_replenish
69: self.assertIsNotNone(server, TEST_SERVER_ERROR % self.http_server11_port)
69: AssertionError: unexpectedly None : TestServer failed to start due to port 24776 already in use issue
69:
69: ======================================================================
69: FAIL: test_03_server_reconnect (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest)
69: ----------------------------------------------------------------------
69: Traceback (most recent call last):
69: File "/home/travis/build/apache/qpid-dispatch/tests/http1_tests.py", line 565, in test_03_server_reconnect
69: self.assertIsNotNone(server, TEST_SERVER_ERROR % self.http_server11_port)
69: AssertionError: unexpectedly None : TestServer failed to start due to port 24776 already in use issue
69:
69: ======================================================================
69: FAIL: test_04_server_pining_for_the_fjords (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest)
69: ----------------------------------------------------------------------
69: Traceback (most recent call last):
69: File "/home/travis/build/apache/qpid-dispatch/tests/http1_tests.py", line 615, in test_04_server_pining_for_the_fjords
69: self.assertIsNotNone(server, TEST_SERVER_ERROR % self.http_server11_port)
69: AssertionError: unexpectedly None : TestServer failed to start due to port 24776 already in use issue
69:
69: ======================================================================
69: FAIL: test_05_large_streaming_msg (system_tests_http1_over_tcp.Http1OverTcpEdge2EdgeTest)
69: ----------------------------------------------------------------------
69: Traceback (most recent call last):
69: File "/home/travis/build/apache/qpid-dispatch/tests/http1_tests.py", line 722, in test_05_large_streaming_msg
69: self.assertIsNotNone(server11, TEST_SERVER_ERROR % self.http_server11_port)
69: AssertionError: unexpectedly None : TestServer failed to start due to port 24776 already in use issue
69:
69: ----------------------------------------------------------------------
69: Ran 12 tests in 258.477s
69:
69: FAILED (failures=5)
69/72 Test #69: system_tests_http1_over_tcp .......................***Failed 258.63 sec
Test
Major