You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@qpid.apache.org by "Ganesh Murthy (Jira)" <ji...@apache.org> on 2021/05/19 18:53:00 UTC
[jira] [Commented] (DISPATCH-2139) system_tests_http1_over_tcp
failing with HTTP resp code 503
[ https://issues.apache.org/jira/browse/DISPATCH-2139?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17347826#comment-17347826 ]
Ganesh Murthy commented on DISPATCH-2139:
-----------------------------------------
https://travis-ci.com/github/apache/qpid-dispatch/jobs/506390789#L9136
> system_tests_http1_over_tcp failing with HTTP resp code 503
> -----------------------------------------------------------
>
> Key: DISPATCH-2139
> URL: https://issues.apache.org/jira/browse/DISPATCH-2139
> Project: Qpid Dispatch
> Issue Type: Test
> Components: Protocol Adaptors
> Affects Versions: 1.16.0
> Reporter: Ganesh Murthy
> Assignee: Ken Giusti
> Priority: Major
>
> {noformat}
> 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 {noformat}
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@qpid.apache.org
For additional commands, e-mail: dev-help@qpid.apache.org