Closed Bug 1721204 Opened 3 years ago Closed 3 years ago

Intermittent /webdriver/tests/add_cookie/user_prompts.py | test_dismiss[capabilities0-alert-None] - setup error

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1720748

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2021-07-19T11:22:43.043Z] 11:22:43     INFO - TEST-START | /webdriver/tests/add_cookie/user_prompts.py
[task 2021-07-19T11:22:43.044Z] 11:22:43     INFO - STDOUT: ============================== 24 passed in 5.03s ==============================
[task 2021-07-19T11:22:43.104Z] 11:22:43     INFO - STDOUT: ============================= test session starts ==============================
[task 2021-07-19T11:22:43.105Z] 11:22:43     INFO - STDOUT: platform linux -- Python 3.6.9, pytest-6.1.1, py-1.5.2, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2021-07-19T11:22:43.106Z] 11:22:43     INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2021-07-19T11:22:43.107Z] 11:22:43     INFO - STDOUT: plugins: asyncio-0.14.0
[task 2021-07-19T11:22:43.108Z] 11:22:43     INFO - STDOUT: collecting ... 
[task 2021-07-19T11:22:43.127Z] 11:22:43     INFO - STDOUT: collected 18 items
[task 2021-07-19T11:22:43.133Z] 11:22:43     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/add_cookie/user_prompts.py::test_accept[capabilities0-alert-None] 

[task 2021-07-19T11:22:58.084Z] 11:22:58     INFO - STDOUT: ==================================== ERRORS ====================================
[task 2021-07-19T11:22:58.084Z] 11:22:58     INFO - STDOUT: ___________ ERROR at setup of test_dismiss[capabilities0-alert-None] ___________
[task 2021-07-19T11:22:58.084Z] 11:22:58     INFO - STDOUT: args = ()
[task 2021-07-19T11:22:58.085Z] 11:22:58     INFO - STDOUT: kwargs = {'capabilities': {'unhandledPromptBehavior': 'dismiss'}, 'configuration': {'capabilities': {'moz:firefoxOptions': {'ar...: '127.0.0.1', 'port': 40275}, 'request': <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>}
[task 2021-07-19T11:22:58.085Z] 11:22:58     INFO - STDOUT: request = <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>
[task 2021-07-19T11:22:58.085Z] 11:22:58     INFO - STDOUT: setup = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7f8290e67f28>
[task 2021-07-19T11:22:58.087Z] 11:22:58     INFO - STDOUT: finalizer = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7f8284372730>
[task 2021-07-19T11:22:58.088Z] 11:22:58     INFO - STDOUT:     def wrapper(*args, **kwargs):
[task 2021-07-19T11:22:58.089Z] 11:22:58     INFO - STDOUT:         loop = fixture_stripper.get_and_strip_from(FixtureStripper.EVENT_LOOP, kwargs)
[task 2021-07-19T11:22:58.089Z] 11:22:58     INFO - STDOUT:         request = fixture_stripper.get_and_strip_from(FixtureStripper.REQUEST, kwargs)
[task 2021-07-19T11:22:58.104Z] 11:22:58     INFO - STDOUT:     
[task 2021-07-19T11:22:58.105Z] 11:22:58     INFO - STDOUT:         gen_obj = generator(*args, **kwargs)
[task 2021-07-19T11:22:58.105Z] 11:22:58     INFO - STDOUT:     
[task 2021-07-19T11:22:58.105Z] 11:22:58     INFO - STDOUT:         async def setup():
[task 2021-07-19T11:22:58.105Z] 11:22:58     INFO - STDOUT:             res = await gen_obj.__anext__()
[task 2021-07-19T11:22:58.105Z] 11:22:58     INFO - STDOUT:             return res
[task 2021-07-19T11:22:58.106Z] 11:22:58     INFO - STDOUT:     
[task 2021-07-19T11:22:58.106Z] 11:22:58     INFO - STDOUT:         def finalizer():
[task 2021-07-19T11:22:58.106Z] 11:22:58     INFO - STDOUT:             """Yield again, to finalize."""
[task 2021-07-19T11:22:58.106Z] 11:22:58     INFO - STDOUT:             async def async_finalizer():
[task 2021-07-19T11:22:58.107Z] 11:22:58     INFO - STDOUT:                 try:
[task 2021-07-19T11:22:58.107Z] 11:22:58     INFO - STDOUT:                     await gen_obj.__anext__()
[task 2021-07-19T11:22:58.107Z] 11:22:58     INFO - STDOUT:                 except StopAsyncIteration:
[task 2021-07-19T11:22:58.114Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.114Z] 11:22:58     INFO - TEST-PASS | /webdriver/tests/add_cookie/user_prompts.py | test_accept[capabilities0-alert-None] 
[task 2021-07-19T11:22:58.114Z] 11:22:58     INFO - TEST-PASS | /webdriver/tests/add_cookie/user_prompts.py | test_accept[capabilities0-confirm-True] 
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - TEST-PASS | /webdriver/tests/add_cookie/user_prompts.py | test_accept[capabilities0-prompt-] 
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - TEST-PASS | /webdriver/tests/add_cookie/user_prompts.py | test_accept_and_notify[capabilities0-alert-None] 
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - TEST-PASS | /webdriver/tests/add_cookie/user_prompts.py | test_accept_and_notify[capabilities0-confirm-True] 
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - TEST-PASS | /webdriver/tests/add_cookie/user_prompts.py | test_accept_and_notify[capabilities0-prompt-] 
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/add_cookie/user_prompts.py | test_dismiss[capabilities0-alert-None] - setup error
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - args = ()
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - kwargs = {'capabilities': {'unhandledPromptBehavior': 'dismiss'}, 'configuration': {'capabilities': {'moz:firefoxOptions': {'ar...: '127.0.0.1', 'port': 40275}, 'request': <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>}
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - request = <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>
[task 2021-07-19T11:22:58.116Z] 11:22:58     INFO - setup = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7f8290e67f28>
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO - finalizer = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7f8284372730>
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -     def wrapper(*args, **kwargs):
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -         loop = fixture_stripper.get_and_strip_from(FixtureStripper.EVENT_LOOP, kwargs)
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -         request = fixture_stripper.get_and_strip_from(FixtureStripper.REQUEST, kwargs)
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -         gen_obj = generator(*args, **kwargs)
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -         async def setup():
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -             res = await gen_obj.__anext__()
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -             return res
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -         def finalizer():
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -             """Yield again, to finalize."""
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -             async def async_finalizer():
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -                 try:
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -                     await gen_obj.__anext__()
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -                 except StopAsyncIteration:
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -                     pass
[task 2021-07-19T11:22:58.117Z] 11:22:58     INFO -                 else:
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -                     msg = "Async generator fixture didn't stop."
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -                     msg += "Yield only once."
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -                     raise ValueError(msg)
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -             loop.run_until_complete(async_finalizer())
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -         request.addfinalizer(finalizer)
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - >       return loop.run_until_complete(setup())
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - args       = ()
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - finalizer  = <function pytest_fixture_setup.<locals>.wrapper.<locals>.finalizer at 0x7f8284372730>
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - fixture_stripper = <pytest_asyncio.plugin.FixtureStripper object at 0x7f82843290b8>
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - gen_obj    = <async_generator object session at 0x7f82842c4e18>
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - generator  = <function session at 0x7f8284c5d620>
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO - kwargs     = {'capabilities': {'unhandledPromptBehavior': 'dismiss'},
[task 2021-07-19T11:22:58.118Z] 11:22:58     INFO -  'configuration': {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -                                                                     '/tmp/tmpm91o6quh.mozrunner'],
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -                                                            'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -                    'host': '127.0.0.1',
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -                    'port': 40275},
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -  'request': <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>}
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - loop       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - request    = <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - setup      = <function pytest_fixture_setup.<locals>.wrapper.<locals>.setup at 0x7f8290e67f28>
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:127: 
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO - /usr/lib/python3.6/asyncio/base_events.py:484: in run_until_complete
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -     return future.result()
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -         future     = <Task finished coro=<pytest_fixture_setup.<locals>.wrapper.<locals>.setup() done, defined at /builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:109> exception=<UnknownErrorException http_status=500>>
[task 2021-07-19T11:22:58.119Z] 11:22:58     INFO -         new_task   = True
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -         self       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO - tests/web-platform/tests/tools/third_party/pytest-asyncio/pytest_asyncio/plugin.py:110: in setup
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -     res = await gen_obj.__anext__()
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -         gen_obj    = <async_generator object session at 0x7f82842c4e18>
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures.py:164: in session
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -     _current_session.start()
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -         capabilities = {'unhandledPromptBehavior': 'dismiss'}
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -         caps       = {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -                                                  '/tmp/tmpm91o6quh.mozrunner'],
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -                                         'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -                  'unhandledPromptBehavior': 'dismiss'}}
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -         configuration = {'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -                                                   '/tmp/tmpm91o6quh.mozrunner'],
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -                                          'binary': '/builds/worker/workspace/build/application/firefox/firefox'}},
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -  'host': '127.0.0.1',
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -  'port': 40275}
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO -         request    = <SubRequest 'session' for <Function test_dismiss[capabilities0-alert-None]>>
[task 2021-07-19T11:22:58.120Z] 11:22:58     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:543: in start
[task 2021-07-19T11:22:58.121Z] 11:22:58     INFO -     value = self.send_command("POST", "session", body=body)
[task 2021-07-19T11:22:58.121Z] 11:22:58     INFO -         body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-07-19T11:22:58.121Z] 11:22:58     INFO -                                                                   '/tmp/tmpm91o6quh.mozrunner'],
[task 2021-07-19T11:22:58.121Z] 11:22:58     INFO -                                                          'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2021-07-19T11:22:58.121Z] 11:22:58     INFO -                                   'unhandledPromptBehavior': 'dismiss'}}}
[task 2021-07-19T11:22:58.121Z] 11:22:58     INFO -         self       = <Session (disconnected)>
[task 2021-07-19T11:22:58.122Z] 11:22:58     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2021-07-19T11:22:58.122Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.122Z] 11:22:58     INFO - self = <Session (disconnected)>, method = 'POST', url = 'session'
[task 2021-07-19T11:22:58.122Z] 11:22:58     INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile', '/tmp/tmpm91o6quh.mozrunner'], 'binary': '/builds/worker/workspace/build/application/firefox/firefox'}, 'unhandledPromptBehavior': 'dismiss'}}}
[task 2021-07-19T11:22:58.122Z] 11:22:58     INFO - timeout = None
[task 2021-07-19T11:22:58.123Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.123Z] 11:22:58     INFO -     def send_command(self, method, url, body=None, timeout=None):
[task 2021-07-19T11:22:58.123Z] 11:22:58     INFO -         """
[task 2021-07-19T11:22:58.123Z] 11:22:58     INFO -         Send a command to the remote end and validate its success.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         :param method: HTTP method to use in request.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         :param uri: "Command part" of the HTTP request URL,
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -             e.g. `window/rect`.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         :param body: Optional body of the HTTP request.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         :return: `None` if the HTTP response body was empty, otherwise
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -             the `value` field returned after parsing the response
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -             body as JSON.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         :raises error.WebDriverException: If the remote end returns
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -             an error.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         :raises ValueError: If the response body does not contain a
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -             `value` key.
[task 2021-07-19T11:22:58.124Z] 11:22:58     INFO -         """
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -         response = self.transport.send(
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -             method, url, body,
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -             session=self, timeout=timeout)
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -         if response.status != 200:
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -             err = error.from_response(response)
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -             if isinstance(err, error.InvalidSessionIdException):
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -                 # The driver could have already been deleted the session.
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -                 self.session_id = None
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO -     
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO - >           raise err
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO - E           webdriver.error.UnknownErrorException: unknown error (500): Process unexpectedly closed with status 11
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.125Z] 11:22:58     INFO - body       = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'args': ['--profile',
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO -                                                                   '/tmp/tmpm91o6quh.mozrunner'],
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO -                                                          'binary': '/builds/worker/workspace/build/application/firefox/firefox'},
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO -                                   'unhandledPromptBehavior': 'dismiss'}}}
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - err        = <UnknownErrorException http_status=500>
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - method     = 'POST'
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - self       = <Session (disconnected)>
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - timeout    = None
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - url        = 'session'
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - 
[task 2021-07-19T11:22:58.126Z] 11:22:58     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:604: UnknownErrorException
[task 2021-07-19T11:22:58.133Z] 11:22:58     INFO - ...........
[task 2021-07-19T11:22:58.133Z] 11:22:58     INFO - TEST-OK | /webdriver/tests/add_cookie/user_prompts.py | took 15081ms
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
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.