Closed Bug 1682905 Opened 4 years ago Closed 4 years ago

Intermittent /webdriver/tests/execute_script/execute.py | test_abort_by_user_prompt_twice[alert] - webdriver.error.NoSuchAlertException: no such alert (404):

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1459118

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=324717588&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XBoLuC0DR42W1JNcoYzQvA/runs/0/artifacts/public/logs/live_backing.log


[task 2020-12-16T16:46:36.236Z] 16:46:36     INFO - TEST-START | /webdriver/tests/execute_script/execute.py
[task 2020-12-16T16:46:36.252Z] 16:46:36     INFO - STDOUT: ============================= test session starts ==============================
[task 2020-12-16T16:46:36.253Z] 16:46:36     INFO - STDOUT: platform darwin -- Python 3.7.4, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- /Users/cltbld/tasks/task_1608136831/build/venv/bin/python
[task 2020-12-16T16:46:36.254Z] 16:46:36     INFO - STDOUT: rootdir: /Users/cltbld/tasks/task_1608136831/build, inifile:
[task 2020-12-16T16:46:36.255Z] 16:46:36     INFO - STDOUT: collecting ... 
[task 2020-12-16T16:46:36.290Z] 16:46:36     INFO - STDOUT: collected 12 items
[task 2020-12-16T16:46:36.291Z] 16:46:36     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/execute_script/execute.py::test_null_parameter_value 
[task 2020-12-16T16:46:36.292Z] 16:46:36     INFO - PID 1222 | 1608137196289	webdriver::server	DEBUG	-> POST /session/9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc/window/rect {"width": 800, "height": 600}
[task 2020-12-16T16:46:36.293Z] 16:46:36     INFO - PID 1222 | 1608137196290	Marionette	DEBUG	0 -> [0,262,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-12-16T16:46:36.294Z] 16:46:36     INFO - PID 1222 | 1608137196291	Marionette	DEBUG	0 <- [1,262,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-12-16T16:46:36.296Z] 16:46:36     INFO - PID 1222 | 1608137196291	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-12-16T16:46:36.296Z] 16:46:36     INFO - PID 1222 | 1608137196293	webdriver::server	DEBUG	-> POST /session/9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc/window/rect {"x": 100, "y": 100}
[task 2020-12-16T16:46:36.297Z] 16:46:36     INFO - PID 1222 | 1608137196294	Marionette	DEBUG	0 -> [0,263,"WebDriver:SetWindowRect",{"x":100,"y":100}]
[task 2020-12-16T16:46:36.298Z] 16:46:36     INFO - PID 1222 | 1608137196295	Marionette	DEBUG	0 <- [1,263,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-12-16T16:46:36.299Z] 16:46:36     INFO - PID 1222 | 1608137196295	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-12-16T16:46:36.300Z] 16:46:36     INFO - STDOUT: PASSED

[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_null_parameter_value 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_no_top_browsing_context 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_no_browsing_context 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_opening_new_window_keeps_current_window_handle 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_ending_comment 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_override_listeners 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_abort_by_user_prompt[alert] 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_abort_by_user_prompt[confirm] 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-PASS | /webdriver/tests/execute_script/execute.py | test_abort_by_user_prompt[prompt] 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/execute_script/execute.py | test_abort_by_user_prompt_twice[alert] - webdriver.error.NoSuchAlertException: no such alert (404):
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - session = <Session 9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc>, dialog_type = 'alert'
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO -     @pytest.mark.parametrize("dialog_type", ["alert", "confirm", "prompt"])
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO -     def test_abort_by_user_prompt_twice(session, dialog_type):
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO -         response = execute_script(
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO -             session, "window.{0}('Hello'); window.{0}('Bye'); return 1;".format(dialog_type))
[task 2020-12-16T16:46:40.111Z] 16:46:40     INFO -         assert_success(response, None)
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -         session.alert.accept()
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -         # The first alert has been accepted by the user prompt handler, the second one remains.
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -         # FIXME: this is how browsers currently work, but the spec should clarify if this is the
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -         #        expected behavior, see https://github.com/w3c/webdriver/issues/1153.
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - >       assert session.alert.text == "Bye"
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - dialog_type = 'alert'
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - response   = <Responsetatus=200 body={"value": null}>
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - session    = <Session 9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc>
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - tests/web-platform/tests/webdriver/tests/execute_script/execute.py:94: 
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:19: in inner
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -     return func(self, *args, **kwargs)
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:424: in text
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -     return self.session.send_session_command("GET", "alert/text")
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:580: in send_session_command
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO -     return self.send_command(method, url, body, timeout)
[task 2020-12-16T16:46:40.112Z] 16:46:40     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO - self = <Session 9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc>, method = 'GET'
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO - url = 'session/9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc/alert/text', body = None
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO - timeout = None
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -     def send_command(self, method, url, body=None, timeout=None):
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -         """
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             Send a command to the remote end and validate its success.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             :param method: HTTP method to use in request.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             :param uri: "Command part" of the HTTP request URL,
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -                 e.g. `window/rect`.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             :param body: Optional body of the HTTP request.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             :return: `None` if the HTTP response body was empty, otherwise
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -                 the `value` field returned after parsing the response
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -                 body as JSON.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             :raises error.WebDriverException: If the remote end returns
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -                 an error.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             :raises ValueError: If the response body does not contain a
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -                 `value` key.
[task 2020-12-16T16:46:40.113Z] 16:46:40     INFO -             """
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -         response = self.transport.send(
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -             method, url, body,
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -             session=self, timeout=timeout)
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -         if response.status != 200:
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -             err = error.from_response(response)
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -             if isinstance(err, error.InvalidSessionIdException):
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -                 # The driver could have already been deleted the session.
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -                 self.session_id = None
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO -     
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - >           raise err
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           webdriver.error.NoSuchAlertException: no such alert (404): 
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           Remote-end stacktrace:
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           WebDriverError@chrome://marionette/content/error.js:181:5
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           NoSuchAlertError@chrome://marionette/content/error.js:382:5
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           GeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3535:11
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           GeckoDriver.prototype.getTextFromDialog@chrome://marionette/content/driver.js:3480:8
[task 2020-12-16T16:46:40.114Z] 16:46:40     INFO - E           despatch@chrome://marionette/content/server.js:297:40
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - E           execute@chrome://marionette/content/server.js:267:16
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - E           onPacket/<@chrome://marionette/content/server.js:240:20
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - E           onPacket@chrome://marionette/content/server.js:241:9
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - E           _onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - body       = None
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - err        = <NoSuchAlertException http_status=404>
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - method     = 'GET'
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - response   = <Response status=404 error=<NoSuchAlertException http_status=404>>
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - self       = <Session 9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc>
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - timeout    = None
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - url        = 'session/9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc/alert/text'
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - 
[task 2020-12-16T16:46:40.115Z] 16:46:40     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:544: NoSuchAlertException
[task 2020-12-16T16:46:40.116Z] 16:46:40     INFO - ..
[task 2020-12-16T16:46:40.116Z] 16:46:40     INFO - TEST-OK | /webdriver/tests/execute_script/execute.py | took 3815ms
[task 2020-12-16T16:46:40.268Z] 16:46:40     INFO - STDOUT: E           Remote-end stacktrace:
[task 2020-12-16T16:46:40.269Z] 16:46:40     INFO - STDOUT: E           
[task 2020-12-16T16:46:40.269Z] 16:46:40     INFO - STDOUT: E           WebDriverError@chrome://marionette/content/error.js:181:5
[task 2020-12-16T16:46:40.270Z] 16:46:40     INFO - STDOUT: E           NoSuchAlertError@chrome://marionette/content/error.js:382:5
[task 2020-12-16T16:46:40.271Z] 16:46:40     INFO - STDOUT: E           GeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3535:11
[task 2020-12-16T16:46:40.271Z] 16:46:40     INFO - STDOUT: E           GeckoDriver.prototype.getTextFromDialog@chrome://marionette/content/driver.js:3480:8
[task 2020-12-16T16:46:40.272Z] 16:46:40     INFO - STDOUT: E           despatch@chrome://marionette/content/server.js:297:40
[task 2020-12-16T16:46:40.272Z] 16:46:40     INFO - STDOUT: E           execute@chrome://marionette/content/server.js:267:16
[task 2020-12-16T16:46:40.273Z] 16:46:40     INFO - STDOUT: E           onPacket/<@chrome://marionette/content/server.js:240:20
[task 2020-12-16T16:46:40.273Z] 16:46:40     INFO - STDOUT: E           onPacket@chrome://marionette/content/server.js:241:9
[task 2020-12-16T16:46:40.274Z] 16:46:40     INFO - STDOUT: E           _onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2020-12-16T16:46:40.276Z] 16:46:40     INFO - STDOUT: body       = None
[task 2020-12-16T16:46:40.276Z] 16:46:40     INFO - STDOUT: err        = <NoSuchAlertException http_status=404>
[task 2020-12-16T16:46:40.276Z] 16:46:40     INFO - STDOUT: method     = 'GET'
[task 2020-12-16T16:46:40.276Z] 16:46:40     INFO - STDOUT: response   = <Response status=404 error=<NoSuchAlertException http_status=404>>
[task 2020-12-16T16:46:40.276Z] 16:46:40     INFO - STDOUT: self       = <Session 9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc>
[task 2020-12-16T16:46:40.277Z] 16:46:40     INFO - STDOUT: timeout    = None
[task 2020-12-16T16:46:40.277Z] 16:46:40     INFO - STDOUT: url        = 'session/9fc82dcf-ebe2-cd4c-a8e3-704c7d26eecc/alert/text'
[task 2020-12-16T16:46:40.278Z] 16:46:40     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-12-16T16:46:40.278Z] 16:46:40     INFO - STDOUT: :544: NoSuchAlertException
[task 2020-12-16T16:46:40.278Z] 16:46:40     INFO - STDOUT: =============================== warnings summary ===============================
[task 2020-12-16T16:46:40.278Z] 16:46:40     INFO - STDOUT: <undetermined location>
[task 2020-12-16T16:46:40.279Z] 16:46:40     INFO - STDOUT:   Module already imported so cannot be rewritten: tests.support.fixtures
[task 2020-12-16T16:46:40.279Z] 16:46:40     INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html
[task 2020-12-16T16:46:40.279Z] 16:46:40     INFO - STDOUT: =============== 1 failed, 11 passed, 1 warnings in 3.77 seconds ================
[task 2020-12-16T16:46:40.280Z] 16:46:40     INFO - Closing logging queue
[task 2020-12-16T16:46:40.280Z] 16:46:40     INFO - queue closed
[task 2020-12-16T16:46:40.282Z] 16:46:40     INFO - Starting runner
[task 2020-12-16T16:46:40.559Z] 16:46:40     INFO - PID 1234 | 1608137200557	geckodriver	INFO	Listening on 127.0.0.1:54736
[task 2020-12-16T16:46:41.057Z] 16:46:41     INFO - WebDriver HTTP server listening at http://127.0.0.1:54736/```
Status: NEW → RESOLVED
Closed: 4 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.