Closed Bug 1921135 Opened 4 months ago Closed 3 months ago

Intermittent about_preferences_basic | Could not terminate process

Categories

(Testing :: Talos, defect, P5)

Product:
Testing
Component:
Talos
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=475834892&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/S7GrRAoWRDiBfIkYfEJREg/runs/0/artifacts/public/logs/live_backing.log

[task 2024-09-25T23:14:03.270Z] 23:14:03     INFO -  TEST-START | about_preferences_basic
[task 2024-09-25T23:14:03.270Z] 23:14:03     INFO -  Initialising browser for about_preferences_basic test...
[task 2024-09-25T23:14:03.270Z] 23:14:03     INFO -  Cloning profile located at C:\task_172729986144913\build\tests\talos\talos\base_profile
[task 2024-09-25T23:14:03.271Z] 23:14:03     INFO -  Merging profile: C:\task_172729986144913\build\tests\talos\talos\profile_data\base
[task 2024-09-25T23:14:03.273Z] 23:14:03     INFO -  Merging profile: C:\task_172729986144913\build\tests\talos\talos\profile_data\common
[task 2024-09-25T23:14:03.275Z] 23:14:03     INFO -  Merging profile: C:\task_172729986144913\build\tests\talos\talos\profile_data\perf
[task 2024-09-25T23:14:03.277Z] 23:14:03     INFO -  Installing Add-ons:
[task 2024-09-25T23:14:03.277Z] 23:14:03     INFO -  ['C:\\task_172729986144913\\build\\tests\\talos\\talos/talos-powers', 'C:\\task_172729986144913\\build\\tests\\talos\\talos/pageloader']
[task 2024-09-25T23:14:03.288Z] 23:14:03     INFO -  Installing Webextensions:
[task 2024-09-25T23:14:03.288Z] 23:14:03     INFO -  Application command: C:\task_172729986144913\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:61887/getInfo.html --wait-for-browser -profile C:\Users\task_172729986144913\AppData\Local\Temp\tmp5k45ni9t\profile
[task 2024-09-25T23:14:27.308Z] 23:14:27     INFO -  TEST-INFO | started process 10544 (C:\task_172729986144913\build\application\firefox\firefox -wait-for-browser -no-deelevate http://127.0.0.1:61887/getInfo.html)
[task 2024-09-25T23:15:15.381Z] 23:15:15    ERROR -  Traceback (most recent call last):
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\tests\talos\talos\ffsetup.py", line 204, in _run_profile
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      exit_code = proc.wait()
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -                  ^^^^^^^^^^^
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 963, in wait
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      self.returncode = self.proc.wait(timeout)
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -                        ^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 267, in wait
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      self.returncode = self._custom_wait(timeout=timeout)
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 647, in _custom_wait
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      returncode = winprocess.GetExitCodeProcess(self._handle)
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\winprocess.py", line 70, in ErrCheckBool
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      raise WinError()
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -  OSError: [WinError 6] The handle is invalid.
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -  During handling of the above exception, another exception occurred:
[task 2024-09-25T23:15:15.381Z] 23:15:15    ERROR -  Traceback (most recent call last):
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 185, in send_signal
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      winprocess.TerminateProcess(
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\winprocess.py", line 70, in ErrCheckBool
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -      raise WinError()
[task 2024-09-25T23:15:15.381Z] 23:15:15     INFO -  OSError: [WinError 6] The handle is invalid.
[task 2024-09-25T23:15:15.397Z] 23:15:15     INFO -  TEST-UNEXPECTED-ERROR | about_preferences_basic | Could not terminate process
[task 2024-09-25T23:15:15.398Z] 23:15:15    ERROR -  Traceback (most recent call last):
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\tests\talos\talos\ffsetup.py", line 204, in _run_profile
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -      exit_code = proc.wait()
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -                  ^^^^^^^^^^^
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 963, in wait
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -      self.returncode = self.proc.wait(timeout)
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -                        ^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-09-25T23:15:15.398Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 267, in wait
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -      self.returncode = self._custom_wait(timeout=timeout)
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -                        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 647, in _custom_wait
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -      returncode = winprocess.GetExitCodeProcess(self._handle)
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\winprocess.py", line 70, in ErrCheckBool
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -      raise WinError()
[task 2024-09-25T23:15:15.399Z] 23:15:15     INFO -  OSError: [WinError 6] The handle is invalid.
[task 2024-09-25T23:15:15.400Z] 23:15:15     INFO -  During handling of the above exception, another exception occurred:
[task 2024-09-25T23:15:15.400Z] 23:15:15    ERROR -  Traceback (most recent call last):
[task 2024-09-25T23:15:15.400Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 185, in send_signal
[task 2024-09-25T23:15:15.400Z] 23:15:15     INFO -      winprocess.TerminateProcess(
[task 2024-09-25T23:15:15.400Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\winprocess.py", line 70, in ErrCheckBool
[task 2024-09-25T23:15:15.400Z] 23:15:15     INFO -      raise WinError()
[task 2024-09-25T23:15:15.400Z] 23:15:15     INFO -  OSError: [WinError 6] The handle is invalid.
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -  During handling of the above exception, another exception occurred:
[task 2024-09-25T23:15:15.401Z] 23:15:15    ERROR -  Traceback (most recent call last):
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\tests\talos\talos\run_tests.py", line 351, in run_tests
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -      mytest.runTest(browser_config, test, utility_path=utility_path)
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\tests\talos\talos\ttest.py", line 60, in runTest
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -      with FFSetup(browser_config, test_config) as setup:
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\tests\talos\talos\ffsetup.py", line 335, in __enter__
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -      self._run_profile()
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\tests\talos\talos\ffsetup.py", line 206, in _run_profile
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -      proc.kill()
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 895, in kill
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -      self.proc.kill(sig=sig, timeout=timeout)
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 243, in kill
[task 2024-09-25T23:15:15.401Z] 23:15:15     INFO -      self.send_signal(sig)
[task 2024-09-25T23:15:15.402Z] 23:15:15     INFO -    File "C:\task_172729986144913\build\venv\Lib\site-packages\mozprocess\processhandler.py", line 192, in send_signal
[task 2024-09-25T23:15:15.402Z] 23:15:15     INFO -      raise OSError("Could not terminate process")
[task 2024-09-25T23:15:15.402Z] 23:15:15     INFO -  OSError: Could not terminate process
[task 2024-09-25T23:15:15.402Z] 23:15:15     INFO -  TEST-INFO took 72112ms
[task 2024-09-25T23:15:15.402Z] 23:15:15     INFO -  SUITE-END | took 72s
[task 2024-09-25T23:15:15.903Z] 23:15:15     INFO - Return code: 2
[task 2024-09-25T23:15:15.903Z] 23:15:15  WARNING - setting return code to 2
[task 2024-09-25T23:15:15.903Z] 23:15:15     INFO - Running post-action listener: _package_coverage_data
[task 2024-09-25T23:15:15.903Z] 23:15:15     INFO - Running post-action listener: _resource_record_post_action
[task 2024-09-25T23:15:15.903Z] 23:15:15     INFO - Running post-action listener: process_java_coverage_data
[task 2024-09-25T23:15:15.903Z] 23:15:15     INFO - [mozharness: 2024-09-25 23:15:15.903595Z] Finished run-tests step (success)
[task 2024-09-25T23:15:15.903Z] 23:15:15     INFO - Running post-run listener: _resource_record_post_run
[task 2024-09-25T23:15:15.982Z] 23:15:15     INFO - Total resource usage - Wall time: 108s; CPU: 0%; Read bytes: 12977152; Write bytes: 272859136; Read time: 0; Write time: 1
[task 2024-09-25T23:15:15.982Z] 23:15:15     INFO - TinderboxPrint: CPU usage<br/>0.2%
[task 2024-09-25T23:15:15.982Z] 23:15:15     INFO - TinderboxPrint: I/O read bytes / time<br/>12,977,152 / 0
[task 2024-09-25T23:15:15.982Z] 23:15:15     INFO - TinderboxPrint: I/O write bytes / time<br/>272,859,136 / 1
[task 2024-09-25T23:15:15.982Z] 23:15:15     INFO - TinderboxPrint: CPU idle<br/>1,330.8 (99.8%)
[task 2024-09-25T23:15:15.993Z] 23:15:15     INFO - install - Wall time: 26s; CPU: 4%; Read bytes: 3115008; Write bytes: 12288; Read time: 0; Write time: 0
[task 2024-09-25T23:15:15.997Z] 23:15:15     INFO - run-tests - Wall time: 82s; CPU: 0%; Read bytes: 9862144; Write bytes: 272846848; Read time: 0; Write time: 1
[task 2024-09-25T23:15:16.169Z] 23:15:16  WARNING - returning nonzero exit status 2
[taskcluster 2024-09-25T23:15:16.223Z]    Exit Code: 2

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED
Closed: 3 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.