Closed Bug 1563829 Opened 5 months ago Closed 8 days ago

Intermittent 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_wait_after_external_kill TEST-UNEXPECTED-FAIL

Categories

(Testing :: Mozbase, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: egao [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=254977714&repo=try
Full log: https://queue.taskcluster.net/v1/task/GLIMR4WpSPagHnpliiLa4g/runs/0/artifacts/public/logs/live_backing.log


Platform: macosx1014-mojave
Test: py3-mb

Context:
[task 2019-07-05T19:29:34.809Z] 1:01.45 ============================= test session starts ==============================
[task 2019-07-05T19:29:34.809Z] 1:01.45 platform darwin -- Python 3.7.3, pytest-3.6.2, py-1.5.4, pluggy-0.6.0 -- /usr/local/bin/python3
[task 2019-07-05T19:29:34.809Z] 1:01.45 rootdir: /Users/cltbld/tasks/task_1562354704/checkouts/gecko, inifile: /Users/cltbld/tasks/task_1562354704/checkouts/gecko/config/mozunit/mozunit/pytest.ini
[task 2019-07-05T19:29:34.809Z] 1:01.45 collecting ... collected 7 items
[task 2019-07-05T19:29:34.809Z] 1:01.45
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_normal_finish PASSED
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_timeout PASSED
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_wait PASSED
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_wait_after_external_kill TEST-UNEXPECTED-FAIL
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_wait_twice_after_kill PASSED
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_waitnotimeout PASSED
[task 2019-07-05T19:29:34.810Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py::ProcTestWait::test_waittimeout PASSED
[task 2019-07-05T19:29:34.810Z] 1:01.45
[task 2019-07-05T19:29:34.810Z] 1:01.45 =================================== FAILURES ===================================
[task 2019-07-05T19:29:34.810Z] 1:01.45 __________________ ProcTestWait.test_wait_after_external_kill __________________
[task 2019-07-05T19:29:34.810Z] 1:01.45
[task 2019-07-05T19:29:34.810Z] 1:01.45 self = <test_wait.ProcTestWait testMethod=test_wait_after_external_kill>
[task 2019-07-05T19:29:34.811Z] 1:01.45
[task 2019-07-05T19:29:34.811Z] 1:01.45 def test_wait_after_external_kill(self):
[task 2019-07-05T19:29:34.811Z] 1:01.45 """Process is killed externally, and poll() is called."""
[task 2019-07-05T19:29:34.811Z] 1:01.45 p = processhandler.ProcessHandler([self.python, self.proclaunch,
[task 2019-07-05T19:29:34.811Z] 1:01.45 "process_normal_finish.ini"],
[task 2019-07-05T19:29:34.811Z] 1:01.45 cwd=here)
[task 2019-07-05T19:29:34.811Z] 1:01.45 p.run()
[task 2019-07-05T19:29:34.811Z] 1:01.45 os.kill(p.pid, signal.SIGTERM)
[task 2019-07-05T19:29:34.811Z] 1:01.45 returncode = p.wait()
[task 2019-07-05T19:29:34.811Z] 1:01.45
[task 2019-07-05T19:29:34.811Z] 1:01.45 # We killed the process, so the returncode should be non-zero
[task 2019-07-05T19:29:34.811Z] 1:01.45 if mozinfo.isWin:
[task 2019-07-05T19:29:34.811Z] 1:01.45 self.assertEqual(returncode, signal.SIGTERM,
[task 2019-07-05T19:29:34.811Z] 1:01.45 'Positive returncode expected, got "%s"' % returncode)
[task 2019-07-05T19:29:34.812Z] 1:01.45 else:
[task 2019-07-05T19:29:34.812Z] 1:01.45 self.assertEqual(returncode, -signal.SIGTERM,
[task 2019-07-05T19:29:34.812Z] 1:01.45 > '%s expected, got "%s"' % (-signal.SIGTERM, returncode))
[task 2019-07-05T19:29:34.812Z] 1:01.45 E AssertionError: -9 != -15 : -15 expected, got "-9"
[task 2019-07-05T19:29:34.812Z] 1:01.45
[task 2019-07-05T19:29:34.812Z] 1:01.45 testing/mozbase/mozprocess/tests/test_wait.py:128: AssertionError
[task 2019-07-05T19:29:34.812Z] 1:01.45 ===================== 1 failed, 6 passed in 11.35 seconds ======================
[task 2019-07-05T19:29:34.812Z] 1:01.45 Setting retcode to 1 from /Users/cltbld/tasks/task_1562354704/checkouts/gecko/testing/mozbase/mozprocess/tests/test_wait.py

Type: -- → defect

Instead of an expected SIGTERM the process has been killed with SIGKILL. Maybe a SIGTERM wasn't successful at the first place.

Status: NEW → RESOLVED
Closed: Last month
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: Last month8 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.