<div dir="ltr">I got this test failing on one of the builds:<br>(<a href="http://jenkins.ovirt.org/job/vdsm_master_check-patch-el7-x86_64/5266/console">http://jenkins.ovirt.org/job/vdsm_master_check-patch-el7-x86_64/5266/console</a>)<br><pre class="gmail-console-output"><span class="gmail-timestamp"><b>09:39:16</b> </span>======================================================================
<span class="gmail-timestamp"><b>09:39:16</b> </span>FAIL: testTimeout (storage_iscsi_test.RescanTimeoutTests)
<span class="gmail-timestamp"><b>09:39:16</b> </span>----------------------------------------------------------------------
<span class="gmail-timestamp"><b>09:39:16</b> </span>Traceback (most recent call last):
<span class="gmail-timestamp"><b>09:39:16</b> </span> File "/home/jenkins/workspace/vdsm_master_check-patch-el7-x86_64/vdsm/tests/monkeypatch.py", line 133, in wrapper
<span class="gmail-timestamp"><b>09:39:16</b> </span> return f(*args, **kw)
<span class="gmail-timestamp"><b>09:39:16</b> </span> File "/home/jenkins/workspace/vdsm_master_check-patch-el7-x86_64/vdsm/tests/monkeypatch.py", line 133, in wrapper
<span class="gmail-timestamp"><b>09:39:16</b> </span> return f(*args, **kw)
<span class="gmail-timestamp"><b>09:39:16</b> </span> File "/home/jenkins/workspace/vdsm_master_check-patch-el7-x86_64/vdsm/tests/storage_iscsi_test.py", line 45, in testTimeout
<span class="gmail-timestamp"><b>09:39:16</b> </span> iscsi.rescan()
<span class="gmail-timestamp"><b>09:39:16</b> </span> File "/usr/lib64/python2.7/contextlib.py", line 24, in __exit__
<span class="gmail-timestamp"><b>09:39:16</b> </span> self.gen.next()
<span class="gmail-timestamp"><b>09:39:16</b> </span> File "/home/jenkins/workspace/vdsm_master_check-patch-el7-x86_64/vdsm/tests/storage_iscsi_test.py", line 33, in assertMaxDuration
<span class="gmail-timestamp"><b>09:39:16</b> </span> (elapsed, maxtime))
<span class="gmail-timestamp"><b>09:39:16</b> </span>AssertionError: Operation was too slow 1.32s > 1.20s
<span class="gmail-timestamp"><b>09:39:16</b> </span>-------------------- >> begin captured logging << --------------------
<span class="gmail-timestamp"><b>09:39:16</b> </span>2016-12-26 09:36:46,857 DEBUG (MainThread) [storage.SamplingMethod] Trying to enter sampling method (vdsm.storage.iscsi.rescan) (misc:467)
<span class="gmail-timestamp"><b>09:39:16</b> </span>2016-12-26 09:36:46,857 DEBUG (MainThread) [storage.SamplingMethod] Got in to sampling method (misc:470)
<span class="gmail-timestamp"><b>09:39:16</b> </span>2016-12-26 09:36:46,857 DEBUG (MainThread) [storage.ISCSI] Performing SCSI scan, this will take up to 1 seconds (iscsi:457)
<span class="gmail-timestamp"><b>09:39:16</b> </span>2016-12-26 09:36:46,858 DEBUG (MainThread) [root] /usr/bin/taskset --cpu-list 0-3 sleep 2 (cwd None) (commands:69)
<span class="gmail-timestamp"><b>09:39:16</b> </span>2016-12-26 09:36:48,183 DEBUG (MainThread) [storage.SamplingMethod] Returning last result (misc:477)
<span class="gmail-timestamp"><b>09:39:16</b> </span>--------------------- >> end captured logging << ---------------------<br><br><span style="font-family:arial,helvetica,sans-serif"><br></span></pre><pre class="gmail-console-output"><span style="font-family:arial,helvetica,sans-serif">I was surprised that it uses a time measurement to determine success.<br></span></pre><pre class="gmail-console-output"><span style="font-family:arial,helvetica,sans-serif">Edy.<br></span></pre></div>