Closed
Bug 1784626
Opened 3 years ago
Closed 3 years ago
Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_crash.py TestCrash.test_crash_chrome_process | OSError: Process has been unexpectedly closed (Exit code: 259) (Reason: IO Completion Port failed to signal process shutdown)
Categories
(Testing :: Marionette Client and Harness, defect, P5)
Testing
Marionette Client and Harness
Tracking
(Not tracked)
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=387241251&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/N2ToFpM6QsGYKInhbQlrjw/runs/0/artifacts/public/logs/live_backing.log
[task 2022-08-13T11:57:14.169Z] 11:57:14 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_crash.py TestCrash.test_crash_chrome_process
[task 2022-08-13T11:57:14.169Z] 11:57:14 INFO - Suppressing GECKO output. To view, add `print(self.new_out.getvalue())` to the end of this test.
[task 2022-08-13T11:57:14.169Z] 11:57:14 INFO - 1660391834169 Marionette DEBUG Accepted connection 22 from 127.0.0.1:51730
[task 2022-08-13T11:57:14.170Z] 11:57:14 INFO - 1660391834170 Marionette DEBUG 22 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-08-13T11:57:14.170Z] 11:57:14 INFO - 1660391834170 Marionette DEBUG Waiting for initial application window
[task 2022-08-13T11:57:14.171Z] 11:57:14 INFO - 1660391834170 RemoteAgent TRACE [23] Document already finished loading: data:text/html;charset=utf-8,%3Cselect%20multiple%3E%20%3Coption%3Efirst%20%3C/select%3E
[task 2022-08-13T11:57:14.172Z] 11:57:14 INFO - 1660391834172 Marionette DEBUG 22 <- [1,1,null,{"sessionId":"0a54c51c-83f9-4382-afdd-d77003a4b5c2","capabilities":{"browserName":"firefox","browserVersion":"105.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-08-13T11:57:14.173Z] 11:57:14 INFO - 1660391834173 Marionette DEBUG 22 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-08-13T11:57:14.173Z] 11:57:14 INFO - 1660391834173 Marionette DEBUG 22 <- [1,2,null,{"value":null}]
[task 2022-08-13T11:57:14.173Z] 11:57:14 INFO - 1660391834173 Marionette DEBUG 22 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-08-13T11:57:14.174Z] 11:57:14 INFO - 1660391834173 Marionette DEBUG 22 <- [1,3,null,{"value":null}]
[task 2022-08-13T11:57:14.175Z] 11:57:14 INFO - 1660391834174 Marionette DEBUG 22 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-08-13T11:57:14.175Z] 11:57:14 INFO - 1660391834174 Marionette DEBUG 22 <- [1,4,null,{"value":null}]
[task 2022-08-13T11:57:14.176Z] 11:57:14 INFO - 1660391834175 Marionette DEBUG 22 -> [0,5,"Marionette:GetContext",{}]
[task 2022-08-13T11:57:14.176Z] 11:57:14 INFO - 1660391834175 Marionette DEBUG 22 <- [1,5,null,{"value":"content"}]
[task 2022-08-13T11:57:14.176Z] 11:57:14 INFO - 1660391834176 Marionette DEBUG 22 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-08-13T11:57:14.176Z] 11:57:14 INFO - 1660391834176 Marionette DEBUG 22 <- [1,6,null,{"value":null}]
[task 2022-08-13T11:57:14.178Z] 11:57:14 INFO - 1660391834177 Marionette DEBUG 22 -> [0,7,"WebDriver:ExecuteScript",{"script":"const { AppConstants } = ChromeUtils.import(\n \"resource://gre/mo ... 38593062229\\build\\tests\\marionette\\tests\\testing\\marionette\\harness\\marionette_harness\\tests\\unit\\test_crash.py"}]
[task 2022-08-13T11:57:14.178Z] 11:57:14 INFO - 1660391834178 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2022-08-13T11:57:14.179Z] 11:57:14 INFO - 1660391834179 Marionette DEBUG 22 <- [1,7,null,{"value":true}]
[task 2022-08-13T11:57:14.179Z] 11:57:14 INFO - 1660391834179 Marionette DEBUG 22 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2022-08-13T11:57:14.180Z] 11:57:14 INFO - 1660391834179 Marionette DEBUG 22 <- [1,8,null,{"value":null}]
[task 2022-08-13T11:57:14.180Z] 11:57:14 INFO - 1660391834180 Marionette DEBUG 22 -> [0,9,"Marionette:SetContext",{"value":"content"}]
[task 2022-08-13T11:57:14.180Z] 11:57:14 INFO - 1660391834180 Marionette DEBUG 22 <- [1,9,null,{"value":null}]
[task 2022-08-13T11:57:14.181Z] 11:57:14 INFO - 1660391834181 Marionette DEBUG 22 -> [0,10,"WebDriver:Navigate",{"url":"about:crashparent"}]
[task 2022-08-13T11:57:14.182Z] 11:57:14 INFO - 1660391834182 Marionette TRACE [23] Received event beforeunload for data:text/html;charset=utf-8,%3Cselect%20multiple%3E%20%3Coption%3Efirst%20%3C/select%3E
[task 2022-08-13T11:57:14.625Z] 11:57:14 INFO - [GFX1-]: Receive IPC close with reason=Abnorm[GFX1-]: Receive IPC close with reasoalShn=Abnutdown
[task 2022-08-13T11:57:14.625Z] 11:57:14 INFO - ormalShutdown
[task 2022-08-13T11:57:14.638Z] 11:57:14 INFO - Exiting due to channel error.
[task 2022-08-13T11:57:24.184Z] 11:57:24 INFO - Application command: Z:\task_166038593062229\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_166038593062229\AppData\Local\Temp\tmp0rvgabfe.mozrunner
[task 2022-08-13T12:04:40.082Z] 12:04:40 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_crash.py TestCrash.test_crash_chrome_process | OSError: Process has been unexpectedly closed (Exit code: 259) (Reason: IO Completion Port failed to signal process shutdown)
[task 2022-08-13T12:04:40.093Z] 12:04:40 INFO - Traceback (most recent call last):
[task 2022-08-13T12:04:40.094Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 202, in run
[task 2022-08-13T12:04:40.094Z] 12:04:40 INFO - testMethod()
[task 2022-08-13T12:04:40.095Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_crash.py", line 142, in test_crash_chrome_process
[task 2022-08-13T12:04:40.095Z] 12:04:40 INFO - self.marionette.start_session()
[task 2022-08-13T12:04:40.095Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-08-13T12:04:40.096Z] 12:04:40 INFO - m._handle_socket_failure()
[task 2022-08-13T12:04:40.096Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_driver\marionette.py", line 743, in _handle_socket_failure
[task 2022-08-13T12:04:40.096Z] 12:04:40 INFO - reraise(
[task 2022-08-13T12:04:40.097Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2022-08-13T12:04:40.097Z] 12:04:40 INFO - raise value.with_traceback(tb)
[task 2022-08-13T12:04:40.097Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-13T12:04:40.097Z] 12:04:40 INFO - return func(*args, **kwargs)
[task 2022-08-13T12:04:40.098Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1238, in start_session
[task 2022-08-13T12:04:40.098Z] 12:04:40 INFO - resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2022-08-13T12:04:40.098Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-08-13T12:04:40.099Z] 12:04:40 INFO - m._handle_socket_failure()
[task 2022-08-13T12:04:40.099Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\marionette_driver\marionette.py", line 715, in _handle_socket_failure
[task 2022-08-13T12:04:40.099Z] 12:04:40 INFO - returncode = self.instance.runner.wait(timeout=self.shutdown_timeout)
[task 2022-08-13T12:04:40.100Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\mozrunner\base\runner.py", line 174, in wait
[task 2022-08-13T12:04:40.100Z] 12:04:40 INFO - self.process_handler.wait(timeout)
[task 2022-08-13T12:04:40.101Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\mozprocess\processhandler.py", line 1054, in wait
[task 2022-08-13T12:04:40.101Z] 12:04:40 INFO - self.returncode = self.proc.wait(timeout)
[task 2022-08-13T12:04:40.101Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\mozprocess\processhandler.py", line 286, in wait
[task 2022-08-13T12:04:40.102Z] 12:04:40 INFO - self.returncode = self._custom_wait(timeout=timeout)
[task 2022-08-13T12:04:40.102Z] 12:04:40 INFO - File "Z:\task_166038593062229\build\venv\lib\site-packages\mozprocess\processhandler.py", line 716, in _custom_wait
[task 2022-08-13T12:04:40.102Z] 12:04:40 INFO - raise OSError(
[task 2022-08-13T12:04:40.102Z] 12:04:40 INFO - TEST-INFO took 445914ms
[task 2022-08-13T12:04:40.103Z] 12:04:40 INFO - Application command: Z:\task_166038593062229\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_166038593062229\AppData\Local\Temp\tmp0rvgabfe.mozrunner
[task 2022-08-13T12:04:42.188Z] 12:04:42 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_crash.py TestCrash.test_crash_content_process
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
|
||
Updated•2 years ago
|
Product: Testing → Remote Protocol
|
|
||
Comment 3•2 years ago
|
||
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in
before you can comment on or make changes to this bug.
Description
•