Closed Bug 1730234 Opened 4 years ago Closed 4 years ago

Intermittent /webdriver/tests/get_window_handles/user_prompts.py | test_default[alert] - setup error: webdriver.error.UnknownErrorException: unknown error (500): Process unexpectedly closed with status 1

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - TEST-PASS | /webdriver/tests/get_window_handles/user_prompts.py | test_ignore[capabilities0-prompt] 
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/get_window_handles/user_prompts.py | test_default[alert] - setup error: webdriver.error.UnknownErrorException: unknown error (500): Process unexpectedly closed with status 1
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - args = ()
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - kwargs = {'capabilities': {}, 'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile', '/var/folders/p4/...k_163129821495864/build/application/Firefox Nightly.app/Contents/MacOS/firefox'}}, 'host': '127.0.0.1', 'port': 49556}}
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - request = <SubRequest 'session' for <Function test_default[alert]>>
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - setup = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7fe99a0dfdd0>
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - finalizer = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7fe99a0dfe60>
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -     def wrapper(*args, **kwargs):
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -         loop = fixture_stripper.get_and_strip_from(FixtureStripper.EVENT_LOOP, kwargs)
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -         request = fixture_stripper.get_and_strip_from(FixtureStripper.REQUEST, kwargs)
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -         gen_obj = generator(*args, **kwargs)
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -         async def setup():
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -             res = await gen_obj.__anext__()
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -             return res
[task 2021-09-10T18:31:25.240Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -         def finalizer():
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -             """Yield again, to finalize."""
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -             async def async_finalizer():
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                 try:
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                     await gen_obj.__anext__()
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                 except StopAsyncIteration:
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                     pass
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                 else:
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                     msg = "Async generator fixture didn't stop."
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                     msg += "Yield only once."
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -                     raise ValueError(msg)
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -             loop.run_until_complete(async_finalizer())
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO -         request.addfinalizer(finalizer)
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - >       return loop.run_until_complete(setup())
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - args       = ()
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - finalizer  = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7fe99a0dfe60>
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - fixture_stripper = <pytest_asyncio.plugin.FixtureStripper object at 0x7fe99a539dd0>
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - gen_obj    = <async_generator object session at 0x7fe99a0df3b0>
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - generator  = <function session at 0x7fe99a039a70>
[task 2021-09-10T18:31:25.241Z] 18:31:25     INFO - kwargs     = {'capabilities': {},
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -  'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -                                                                     '/var/folders/p4/_gm58y794639164h1xnkpyyw000014/T/tmphpbx1olq.mozrunner'],
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -                                                            'binary': '/opt/worker/tasks/task_163129821495864/build/application/Firefox '
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -                                                                      'Nightly.app/Contents/MacOS/firefox'}},
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -                    'host': '127.0.0.1',
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -                    'port': 49556}}
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - loop       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - request    = <SubRequest 'session' for <Function test_default[alert]>>
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - setup      = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7fe99a0dfdd0>
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:127: 
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - /Library/Frameworks/Python.framework/Versions/3.7/lib/python3.7/asyncio/base_events.py:587: in run_until_complete
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -     return future.result()
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -         future     = <Task finished coro=<pytest_fixture_setup.<locals>.wrapper.<locals>.setup() done, defined at /opt/worker/tasks/task_163129821495864/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:109> exception=<UnknownErrorException http_status=500>>
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -         new_task   = True
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -         self       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:110: in setup
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -     res = await gen_obj.__anext__()
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO -         gen_obj    = <async_generator object session at 0x7fe99a0df3b0>
[task 2021-09-10T18:31:25.242Z] 18:31:25     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:121: in session
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -     _current_session.start()
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -         capabilities = {}
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -         caps       = {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                                  '/var/folders/p4/_gm58y794639164h1xnkpyyw000014/T/tmphpbx1olq.mozrunner'],
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                         'binary': '/opt/worker/tasks/task_163129821495864/build/application/Firefox '
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                                   'Nightly.app/Contents/MacOS/firefox'}}}
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -         configuration = {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                                   '/var/folders/p4/_gm58y794639164h1xnkpyyw000014/T/tmphpbx1olq.mozrunner'],
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                          'binary': '/opt/worker/tasks/task_163129821495864/build/application/Firefox '
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                                    'Nightly.app/Contents/MacOS/firefox'}},
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -  'host': '127.0.0.1',
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -  'port': 49556}
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:554: in start
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -     value = self.send_command("POST", "session", body=body)
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -         body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                                                   '/var/folders/p4/_gm58y794639164h1xnkpyyw000014/T/tmphpbx1olq.mozrunner'],
[task 2021-09-10T18:31:25.243Z] 18:31:25     INFO -                                                          'binary': '/opt/worker/tasks/task_163129821495864/build/application/Firefox '
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -                                                                    'Nightly.app/Contents/MacOS/firefox'}}}}
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -         self       = <Session (disconnected)>
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO - self = <Session (disconnected)>, method = 'POST', url = 'session'
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile', '/var/folders/p4/_gm58y794639164h1xnkpy...'], 'binary': '/opt/worker/tasks/task_163129821495864/build/application/Firefox Nightly.app/Contents/MacOS/firefox'}}}}
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO - timeout = None
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -     def send_command(self, method, url, body=None, timeout=None):
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -         """
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -         Send a command to the remote end and validate its success.
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -         :param method: HTTP method to use in request.
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -         :param uri: "Command part" of the HTTP request URL,
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -             e.g. `window/rect`.
[task 2021-09-10T18:31:25.244Z] 18:31:25     INFO -         :param body: Optional body of the HTTP request.
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -         :return: `None` if the HTTP response body was empty, otherwise
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             the `value` field returned after parsing the response
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             body as JSON.
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -         :raises error.WebDriverException: If the remote end returns
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             an error.
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -         :raises ValueError: If the response body does not contain a
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             `value` key.
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -         """
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -         response = self.transport.send(
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             method, url, body,
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             session=self, timeout=timeout)
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -         if response.status != 200:
[task 2021-09-10T18:31:25.245Z] 18:31:25     INFO -             err = error.from_response(response)
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -             if isinstance(err, error.InvalidSessionIdException):
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -                 # The driver could have already been deleted the session.
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -                 self.session_id = None
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -     
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - >           raise err
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - E           webdriver.error.UnknownErrorException: unknown error (500): Process unexpectedly closed with status 1
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -                                                                   '/var/folders/p4/_gm58y794639164h1xnkpyyw000014/T/tmphpbx1olq.mozrunner'],
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -                                                          'binary': '/opt/worker/tasks/task_163129821495864/build/application/Firefox '
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO -                                                                    'Nightly.app/Contents/MacOS/firefox'}}}}
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - err        = <UnknownErrorException http_status=500>
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - method     = 'POST'
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - self       = <Session (disconnected)>
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - timeout    = None
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - url        = 'session'
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - 
[task 2021-09-10T18:31:25.246Z] 18:31:25     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:618: UnknownErrorException
[task 2021-09-10T18:31:25.248Z] 18:31:25     INFO - ..
[task 2021-09-10T18:31:25.248Z] 18:31:25     INFO - TEST-OK | /webdriver/tests/get_window_handles/user_prompts.py | took 13097ms

That's the real failure:

[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - STDERR: Future exception was never retrieved
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - future: <Future finished exception=UnknownErrorException(unknown error, Error: Cannot forward command to "ROOT" from "ROOT"., forwardCommand@chrome://remote/content/shared/messagehandler/RootMessageHandler.jsm:80:15
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - handleCommand@chrome://remote/content/shared/messagehandler/MessageHandler.jsm:155:17
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - execute@chrome://remote/content/shared/webdriver/Session.jsm:219:32
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - onPacket@chrome://remote/content/webdriver-bidi/WebDriverBiDiConnection.jsm:156:37
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - onMessage@chrome://remote/content/server/WebSocketTransport.jsm:89:18
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - handleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - )>
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - webdriver.bidi.error.UnknownErrorException: unknown error (Error: Cannot forward command to "ROOT" from "ROOT".)
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - 
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - Remote-end stacktrace:
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - 
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - forwardCommand@chrome://remote/content/shared/messagehandler/RootMessageHandler.jsm:80:15
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - handleCommand@chrome://remote/content/shared/messagehandler/MessageHandler.jsm:155:17
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - execute@chrome://remote/content/shared/webdriver/Session.jsm:219:32
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - onPacket@chrome://remote/content/webdriver-bidi/WebDriverBiDiConnection.jsm:156:37
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - onMessage@chrome://remote/content/server/WebSocketTransport.jsm:89:18
[task 2021-09-10T18:25:59.571Z] 18:25:59     INFO - handleEvent@chrome://remote/content/server/WebSocketTransport.jsm:71:14

This might be related to bug 1694143, which added basic support for log.entryAdded and modified the event loop for Wdspec tests. But not sure why it fails for non BiDi tests.

Julian, any idea why we do not see the real WebDriver Error Cannot forward command to "ROOT" from "ROOT" here in the log but only the Process unexpectedly closed with status 1?

Flags: needinfo?(jdescottes)

We are seeing the same failure nearly permanently over on bug 1729539, after its patch got landed. Julian, any idea what this could be?

As mentioned on Element, the errors from MessageHandler.jsm occur during a test which is expected to fail test_params_events_value_invalid_event_name in https://searchfox.org/mozilla-central/source/testing/web-platform/tests/webdriver/tests/bidi/session_subscribe/subscribe.py#34.

When this test attempts to test the invalid event value foo.bar it fails because it can't find a foo module. The error is not great, but this happens in successful runs as well.

So typically here I think the real error is the one from the summary. It's possible that Bug 1694143 made the MessageHandler failure appear differently, which makes the harness pick it up now.

Flags: needinfo?(jdescottes)

I filed bug 1731576 to improve the handling of this.

The actual problem here is that Firefox doesn't fully startup but shuts down immediately during startup:
https://treeherder.mozilla.org/logviewer?job_id=351178075&repo=autoland&lineNumber=100720-100732

[task 2021-09-10T18:31:22.567Z] 18:31:22     INFO - PID 1868 | 1631298682566	Marionette	INFO	Marionette enabled
[task 2021-09-10T18:31:22.590Z] 18:31:22     INFO - PID 1868 | 1631298682588	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-09-10T18:31:22.600Z] 18:31:22     INFO - PID 1868 | DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2021-09-10T18:31:22.646Z] 18:31:22     INFO - PID 1868 | 1631298682645	geckodriver::marionette	TRACE	Connection refused (os error 61). Retrying in 100ms
[task 2021-09-10T18:31:22.648Z] 18:31:22     INFO - PID 1868 | DEBUG: Adding blocker MediaShutdownManager: shutdown for phase profile-before-change
[task 2021-09-10T18:31:22.658Z] 18:31:22     INFO - PID 1868 | DEBUG: Adding blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2021-09-10T18:31:22.660Z] 18:31:22     INFO - PID 1868 | DEBUG: Adding blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2021-09-10T18:31:22.687Z] 18:31:22     INFO - PID 1868 | DEBUG: Adding blocker ServiceWorkerShutdownBlocker: shutting down Service Workers for phase profile-change-teardown
[task 2021-09-10T18:31:22.814Z] 18:31:22     INFO - PID 1868 | 1631298682813	geckodriver::marionette	TRACE	Connection refused (os error 61). Retrying in 100ms
[task 2021-09-10T18:31:22.926Z] 18:31:22     INFO - PID 1868 | 1631298682925	geckodriver::marionette	TRACE	Connection refused (os error 61). Retrying in 100ms
[task 2021-09-10T18:31:23.139Z] 18:31:23     INFO - PID 1868 | 1631298683137	geckodriver::browser	DEBUG	Browser process stopped: exit status: 1
[task 2021-09-10T18:31:23.142Z] 18:31:23     INFO - PID 1868 | 1631298683138	webdriver::server	DEBUG	<- 500 Internal Server Error {"value":{"error":"unknown error","message":"Process unexpectedly closed with status 1","stacktrace":""}}

For more information please also see bug 1729539 comment 26.

Florian, any idea why Firefox shuts down during startup?

Flags: needinfo?(florian)

(In reply to Henrik Skupin (:whimboo) [⌚️UTC+1] from comment #7)

For more information please also see bug 1729539 comment 26.

Florian, any idea why Firefox shuts down during startup?

No clear idea, sorry. We had a few cases in the past where startup profiling crashed because there was an unexpected shutdown during startup. This happened when xpcom failed to initialize. I don't know if you have a similar situation here.

Flags: needinfo?(florian)

Interesting. I thought to remember that there might have been a XPCOM failed to initialize error visible in the output in these cases? That's something we do not see here. So the closest surrounding code that I have to narrow this down is:

So the GMPProvider for AddonManager is not getting added as blocker for shutdown. So something between ServiceWorkerShutdownBlocker and that method is triggering the shutdown.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.