11:15 p.m.
Recently this test cause too many random failures. I guess this is
the known issue with handling SUBSCRIBE in protocol detector.
I posted https://gerrit.ovirt.org/c/97099/
To mark it as broken until we have time to dig deeper
======================================================================
FAIL: test_detect_slow_client_concurrency(True)
(protocoldetector_test.AcceptorTests)
----------------------------------------------------------------------
Traceback (most recent call last):
File
"/home/jenkins/workspace/vdsm_standard-check-patch/vdsm/tests/testlib.py",
line 142, in wrapper
return f(self, *args)
File
"/home/jenkins/workspace/vdsm_standard-check-patch/vdsm/tests/protocoldetector_test.py",
line 167, in test_detect_slow_client_concurrency
self.check_concurrently(self.check_slow_client, use_ssl)
File
"/home/jenkins/workspace/vdsm_standard-check-patch/vdsm/tests/protocoldetector_test.py",
line 266, in check_concurrently
self.assertTrue(all(done))
AssertionError: False is not true
-------------------- >> begin captured logging << --------------------
2019-01-19 20:47:16,021 DEBUG (MainThread) [vds.MultiProtocolAcceptor]
Creating socket (host='127.0.0.1', port=0, family=2, socketype=1,
proto=6) (protocoldetector:225)
2019-01-19 20:47:16,022 INFO (MainThread) [vds.MultiProtocolAcceptor]
Listening at 127.0.0.1:46009 (protocoldetector:183)
2019-01-19 20:47:16,023 DEBUG (MainThread) [vds.MultiProtocolAcceptor]
Adding detector <protocoldetector_test.Echo object at 0x7f1bb77f8dd0>
(protocoldetector:210)
2019-01-19 20:47:16,024 DEBUG (MainThread) [vds.MultiProtocolAcceptor]
Adding detector <protocoldetector_test.Uppercase object at
0x7f1bb77f8c90> (protocoldetector:210)
2019-01-19 20:47:16,031 INFO (Thread-61)
[ProtocolDetector.AcceptorImpl] Accepted connection from
127.0.0.1:51950 (protocoldetector:61)
2019-01-19 20:47:16,047 INFO (Thread-61)
[ProtocolDetector.AcceptorImpl] Accepted connection from
127.0.0.1:51952 (protocoldetector:61)
2019-01-19 20:47:16,048 INFO (Thread-61)
[ProtocolDetector.AcceptorImpl] Accepted connection from
127.0.0.1:51954 (protocoldetector:61)
2019-01-19 20:47:16,065 INFO (Thread-61)
[ProtocolDetector.AcceptorImpl] Accepted connection from
127.0.0.1:51956 (protocoldetector:61)
2019-01-19 20:47:16,068 INFO (Thread-61)
[ProtocolDetector.AcceptorImpl] Accepted connection from
127.0.0.1:51958 (protocoldetector:61)
2019-01-19 20:47:16,069 DEBUG (Thread-61) [ProtocolDetector.Detector]
Using required_size=9 (protocoldetector:89)
2019-01-19 20:47:16,097 DEBUG (Thread-61) [ProtocolDetector.Detector]
Using required_size=9 (protocoldetector:89)
2019-01-19 20:47:16,099 DEBUG (Thread-61) [ProtocolDetector.Detector]
Using required_size=9 (protocoldetector:89)
2019-01-19 20:47:16,107 DEBUG (Thread-61) [ProtocolDetector.Detector]
Using required_size=9 (protocoldetector:89)
2019-01-19 20:47:16,109 DEBUG (Thread-61) [ProtocolDetector.Detector]
Using required_size=9 (protocoldetector:89)
2019-01-19 20:47:16,574 INFO (Thread-61) [ProtocolDetector.Detector]
Detected protocol echo from 127.0.0.1:51950 (protocoldetector:125)
2019-01-19 20:47:17,570 DEBUG (Thread-61) [ProtocolDetector.Detector]
Timed out while waiting for data (protocoldetector:94)
2019-01-19 20:47:17,571 DEBUG (Thread-61) [ProtocolDetector.Detector]
Timed out while waiting for data (protocoldetector:94)
2019-01-19 20:47:17,576 DEBUG (Thread-61) [ProtocolDetector.Detector]
Timed out while waiting for data (protocoldetector:94)
2019-01-19 20:47:17,578 DEBUG (Thread-61) [ProtocolDetector.Detector]
Timed out while waiting for data (protocoldetector:94)
--------------------- >> end captured logging << ---------------------