Closed Bug 1677218 Opened 4 years ago Closed 4 years ago

Intermittent /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt_twice[<random>] - 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)

References

Details

(Keywords: intermittent-failure)

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


[task 2020-11-13T20:32:22.219Z] 20:32:22     INFO - STDOUT: PASSED
[task 2020-11-13T20:32:22.219Z] 20:32:22     INFO - PID 12301 | 1605299542209	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.220Z] 20:32:22     INFO - PID 12301 | 1605299542210	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/timeouts {"implicit": 0}
[task 2020-11-13T20:32:22.220Z] 20:32:22     INFO - PID 12301 | 1605299542211	Marionette	DEBUG	0 -> [0,326,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-11-13T20:32:22.221Z] 20:32:22     INFO - PID 12301 | 1605299542211	Marionette	DEBUG	0 <- [1,326,null,{"value":null}]
[task 2020-11-13T20:32:22.221Z] 20:32:22     INFO - PID 12301 | 1605299542212	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.221Z] 20:32:22     INFO - PID 12301 | 1605299542213	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/timeouts {"pageLoad": 300000}
[task 2020-11-13T20:32:22.223Z] 20:32:22     INFO - PID 12301 | 1605299542213	Marionette	DEBUG	0 -> [0,327,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-11-13T20:32:22.223Z] 20:32:22     INFO - PID 12301 | 1605299542214	Marionette	DEBUG	0 <- [1,327,null,{"value":null}]
[task 2020-11-13T20:32:22.223Z] 20:32:22     INFO - PID 12301 | 1605299542214	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.224Z] 20:32:22     INFO - PID 12301 | 1605299542215	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/timeouts {"script": 30000}
[task 2020-11-13T20:32:22.224Z] 20:32:22     INFO - PID 12301 | 1605299542215	Marionette	DEBUG	0 -> [0,328,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-11-13T20:32:22.224Z] 20:32:22     INFO - PID 12301 | 1605299542216	Marionette	DEBUG	0 <- [1,328,null,{"value":null}]
[task 2020-11-13T20:32:22.224Z] 20:32:22     INFO - PID 12301 | 1605299542216	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.224Z] 20:32:22     INFO - PID 12301 | 1605299542217	webdriver::server	DEBUG	-> GET /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window
[task 2020-11-13T20:32:22.225Z] 20:32:22     INFO - PID 12301 | 1605299542217	Marionette	DEBUG	0 -> [0,329,"WebDriver:GetWindowHandle",{}]
[task 2020-11-13T20:32:22.225Z] 20:32:22     INFO - PID 12301 | 1605299542218	Marionette	DEBUG	0 <- [1,329,null,{"value":"17"}]
[task 2020-11-13T20:32:22.225Z] 20:32:22     INFO - PID 12301 | 1605299542218	webdriver::server	DEBUG	<- 200 OK {"value":"17"}
[task 2020-11-13T20:32:22.225Z] 20:32:22     INFO - PID 12301 | 1605299542219	webdriver::server	DEBUG	-> GET /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window
[task 2020-11-13T20:32:22.225Z] 20:32:22     INFO - PID 12301 | 1605299542221	Marionette	DEBUG	0 -> [0,330,"WebDriver:GetWindowHandle",{}]
[task 2020-11-13T20:32:22.228Z] 20:32:22     INFO - PID 12301 | 1605299542222	Marionette	DEBUG	0 <- [1,330,null,{"value":"17"}]
[task 2020-11-13T20:32:22.229Z] 20:32:22     INFO - PID 12301 | 1605299542222	webdriver::server	DEBUG	<- 200 OK {"value":"17"}
[task 2020-11-13T20:32:22.229Z] 20:32:22     INFO - PID 12301 | 1605299542223	webdriver::server	DEBUG	-> GET /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window/handles
[task 2020-11-13T20:32:22.230Z] 20:32:22     INFO - PID 12301 | 1605299542223	Marionette	DEBUG	0 -> [0,331,"WebDriver:GetWindowHandles",{}]
[task 2020-11-13T20:32:22.231Z] 20:32:22     INFO - PID 12301 | 1605299542224	Marionette	DEBUG	0 <- [1,331,null,["17"]]
[task 2020-11-13T20:32:22.231Z] 20:32:22     INFO - PID 12301 | 1605299542225	webdriver::server	DEBUG	<- 200 OK {"value":["17"]}
[task 2020-11-13T20:32:22.234Z] 20:32:22     INFO - PID 12301 | 1605299542226	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window {"handle": "17"}
[task 2020-11-13T20:32:22.234Z] 20:32:22     INFO - PID 12301 | 1605299542227	Marionette	DEBUG	0 -> [0,332,"WebDriver:SwitchToWindow",{"handle":"17","name":"17"}]
[task 2020-11-13T20:32:22.234Z] 20:32:22     INFO - PID 12301 | 1605299542229	Marionette	DEBUG	0 <- [1,332,null,{"value":null}]
[task 2020-11-13T20:32:22.234Z] 20:32:22     INFO - PID 12301 | 1605299542229	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.234Z] 20:32:22     INFO - PID 12301 | 1605299542230	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/alert/dismiss {}
[task 2020-11-13T20:32:22.235Z] 20:32:22     INFO - PID 12301 | 1605299542231	Marionette	DEBUG	0 -> [0,333,"WebDriver:DismissAlert",{}]
[task 2020-11-13T20:32:22.236Z] 20:32:22     INFO - PID 12301 | 1605299542232	Marionette	DEBUG	0 <- [1,333,{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchA ... t@chrome://marionette/content/server.js:241:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"},null]
[task 2020-11-13T20:32:22.237Z] 20:32:22     INFO - PID 12301 | 1605299542232	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"WebDriverError@chrome://marionette/content/error.js:181:5\nNoSuchAlertError@chrome://marionette/content/error.js:382:5\nGeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3476:11\nGeckoDriver.prototype.dismissDialog@chrome://marionette/content/driver.js:3379:8\ndespatch@chrome://marionette/content/server.js:297:40\nexecute@chrome://marionette/content/server.js:267:16\nonPacket/<@chrome://marionette/content/server.js:240:20\nonPacket@chrome://marionette/content/server.js:241:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20\n"}}
[task 2020-11-13T20:32:22.237Z] 20:32:22     INFO - PID 12301 | 1605299542234	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window {"handle": "17"}
[task 2020-11-13T20:32:22.238Z] 20:32:22     INFO - PID 12301 | 1605299542235	Marionette	DEBUG	0 -> [0,334,"WebDriver:SwitchToWindow",{"handle":"17","name":"17"}]
[task 2020-11-13T20:32:22.241Z] 20:32:22     INFO - PID 12301 | 1605299542237	Marionette	DEBUG	0 <- [1,334,null,{"value":null}]
[task 2020-11-13T20:32:22.241Z] 20:32:22     INFO - PID 12301 | 1605299542238	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.242Z] 20:32:22     INFO - PID 12301 | 1605299542238	webdriver::server	DEBUG	-> GET /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window
[task 2020-11-13T20:32:22.243Z] 20:32:22     INFO - PID 12301 | 1605299542239	Marionette	DEBUG	0 -> [0,335,"WebDriver:GetWindowHandle",{}]
[task 2020-11-13T20:32:22.243Z] 20:32:22     INFO - PID 12301 | 1605299542239	Marionette	DEBUG	0 <- [1,335,null,{"value":"17"}]
[task 2020-11-13T20:32:22.244Z] 20:32:22     INFO - PID 12301 | 1605299542240	webdriver::server	DEBUG	<- 200 OK {"value":"17"}
[task 2020-11-13T20:32:22.245Z] 20:32:22     INFO - PID 12301 | 1605299542241	webdriver::server	DEBUG	-> GET /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window/handles
[task 2020-11-13T20:32:22.246Z] 20:32:22     INFO - PID 12301 | 1605299542242	Marionette	DEBUG	0 -> [0,336,"WebDriver:GetWindowHandles",{}]
[task 2020-11-13T20:32:22.247Z] 20:32:22     INFO - PID 12301 | 1605299542242	Marionette	DEBUG	0 <- [1,336,null,["17"]]
[task 2020-11-13T20:32:22.247Z] 20:32:22     INFO - PID 12301 | 1605299542243	webdriver::server	DEBUG	<- 200 OK {"value":["17"]}
[task 2020-11-13T20:32:22.248Z] 20:32:22     INFO - PID 12301 | 1605299542244	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window {"handle": "17"}
[task 2020-11-13T20:32:22.248Z] 20:32:22     INFO - PID 12301 | 1605299542245	Marionette	DEBUG	0 -> [0,337,"WebDriver:SwitchToWindow",{"handle":"17","name":"17"}]
[task 2020-11-13T20:32:22.250Z] 20:32:22     INFO - PID 12301 | 1605299542247	Marionette	DEBUG	0 <- [1,337,null,{"value":null}]
[task 2020-11-13T20:32:22.251Z] 20:32:22     INFO - PID 12301 | 1605299542247	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.251Z] 20:32:22     INFO - PID 12301 | 1605299542249	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/window/rect {"width": 800, "height": 600}
[task 2020-11-13T20:32:22.253Z] 20:32:22     INFO - PID 12301 | 1605299542249	Marionette	DEBUG	0 -> [0,338,"WebDriver:SetWindowRect",{"height":600,"width":800}]
[task 2020-11-13T20:32:22.254Z] 20:32:22     INFO - PID 12301 | 1605299542251	Marionette	DEBUG	0 <- [1,338,null,{"x":100,"y":100,"width":800,"height":600}]
[task 2020-11-13T20:32:22.256Z] 20:32:22     INFO - PID 12301 | 1605299542252	webdriver::server	DEBUG	<- 200 OK {"value":{"x":100,"y":100,"width":800,"height":600}}
[task 2020-11-13T20:32:22.256Z] 20:32:22     INFO - PID 12301 | 1605299542253	webdriver::server	DEBUG	-> POST /session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/frame {"id": null}
[task 2020-11-13T20:32:22.256Z] 20:32:22     INFO - PID 12301 | 1605299542254	Marionette	DEBUG	0 -> [0,339,"WebDriver:SwitchToFrame",{"id":null}]
[task 2020-11-13T20:32:22.260Z] 20:32:22     INFO - PID 12301 | 1605299542257	Marionette	DEBUG	0 <- [1,339,null,{"value":null}]
[task 2020-11-13T20:32:22.261Z] 20:32:22     INFO - PID 12301 | 1605299542257	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2020-11-13T20:32:22.261Z] 20:32:22     INFO - STDOUT: =================================== FAILURES ===================================
[task 2020-11-13T20:32:22.265Z] 20:32:22     INFO - STDOUT: ___________________ test_abort_by_user_prompt_twice[confirm] ___________________
[task 2020-11-13T20:32:22.265Z] 20:32:22     INFO - STDOUT: session = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>
[task 2020-11-13T20:32:22.266Z] 20:32:22     INFO - STDOUT: dialog_type = 'confirm'
[task 2020-11-13T20:32:22.267Z] 20:32:22     INFO - STDOUT:     @pytest.mark.parametrize("dialog_type", ["alert", "confirm", "prompt"])
[task 2020-11-13T20:32:22.267Z] 20:32:22     INFO - STDOUT:     def test_abort_by_user_prompt_twice(session, dialog_type):
[task 2020-11-13T20:32:22.267Z] 20:32:22     INFO - STDOUT:         response = execute_async_script(
[task 2020-11-13T20:32:22.268Z] 20:32:22     INFO - STDOUT:             session,
[task 2020-11-13T20:32:22.268Z] 20:32:22     INFO - STDOUT:             "window.{0}('Hello'); window.{0}('Bye'); arguments[0](1);".format(dialog_type))
[task 2020-11-13T20:32:22.270Z] 20:32:22     INFO - STDOUT:         assert_success(response, None)
[task 2020-11-13T20:32:22.271Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.271Z] 20:32:22     INFO - STDOUT:         session.alert.accept()
[task 2020-11-13T20:32:22.271Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.272Z] 20:32:22     INFO - STDOUT:         # The first alert has been accepted by the user prompt handler, the second one remains.
[task 2020-11-13T20:32:22.272Z] 20:32:22     INFO - STDOUT:         # FIXME: this is how browsers currently work, but the spec should clarify if this is the
[task 2020-11-13T20:32:22.272Z] 20:32:22     INFO - STDOUT:         #        expected behavior, see https://github.com/w3c/webdriver/issues/1153.
[task 2020-11-13T20:32:22.274Z] 20:32:22     INFO - STDOUT: >       assert session.alert.text == "Bye"
[task 2020-11-13T20:32:22.274Z] 20:32:22     INFO - STDOUT: dialog_type = 'confirm'
[task 2020-11-13T20:32:22.275Z] 20:32:22     INFO - STDOUT: response   = <Responsetatus=200 body={"value": null}>
[task 2020-11-13T20:32:22.275Z] 20:32:22     INFO - STDOUT: session    = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>
[task 2020-11-13T20:32:22.275Z] 20:32:22     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/execute_async_script/execute_async.py
[task 2020-11-13T20:32:22.275Z] 20:32:22     INFO - STDOUT: :56: 
[task 2020-11-13T20:32:22.276Z] 20:32:22     INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-11-13T20:32:22.276Z] 20:32:22     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-11-13T20:32:22.277Z] 20:32:22     INFO - STDOUT: :19: in inner
[task 2020-11-13T20:32:22.277Z] 20:32:22     INFO - STDOUT:     return func(self, *args, **kwargs)
[task 2020-11-13T20:32:22.278Z] 20:32:22     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-11-13T20:32:22.278Z] 20:32:22     INFO - STDOUT: :424: in text
[task 2020-11-13T20:32:22.278Z] 20:32:22     INFO - STDOUT:     return self.session.send_session_command("GET", "alert/text")
[task 2020-11-13T20:32:22.278Z] 20:32:22     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-11-13T20:32:22.279Z] 20:32:22     INFO - STDOUT: :580: in send_session_command
[task 2020-11-13T20:32:22.280Z] 20:32:22     INFO - STDOUT:     return self.send_command(method, url, body, timeout)
[task 2020-11-13T20:32:22.280Z] 20:32:22     INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-11-13T20:32:22.280Z] 20:32:22     INFO - STDOUT: self = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>, method = 'GET'
[task 2020-11-13T20:32:22.280Z] 20:32:22     INFO - STDOUT: url = 'session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/alert/text', body = None
[task 2020-11-13T20:32:22.281Z] 20:32:22     INFO - STDOUT: timeout = None
[task 2020-11-13T20:32:22.282Z] 20:32:22     INFO - STDOUT:     def send_command(self, method, url, body=None, timeout=None):
[task 2020-11-13T20:32:22.282Z] 20:32:22     INFO - STDOUT:         """
[task 2020-11-13T20:32:22.282Z] 20:32:22     INFO - STDOUT:             Send a command to the remote end and validate its success.
[task 2020-11-13T20:32:22.282Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.283Z] 20:32:22     INFO - STDOUT:             :param method: HTTP method to use in request.
[task 2020-11-13T20:32:22.284Z] 20:32:22     INFO - STDOUT:             :param uri: "Command part" of the HTTP request URL,
[task 2020-11-13T20:32:22.285Z] 20:32:22     INFO - STDOUT:                 e.g. `window/rect`.
[task 2020-11-13T20:32:22.285Z] 20:32:22     INFO - STDOUT:             :param body: Optional body of the HTTP request.
[task 2020-11-13T20:32:22.285Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.285Z] 20:32:22     INFO - STDOUT:             :return: `None` if the HTTP response body was empty, otherwise
[task 2020-11-13T20:32:22.286Z] 20:32:22     INFO - STDOUT:                 the `value` field returned after parsing the response
[task 2020-11-13T20:32:22.287Z] 20:32:22     INFO - STDOUT:                 body as JSON.
[task 2020-11-13T20:32:22.287Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.287Z] 20:32:22     INFO - STDOUT:             :raises error.WebDriverException: If the remote end returns
[task 2020-11-13T20:32:22.287Z] 20:32:22     INFO - STDOUT:                 an error.
[task 2020-11-13T20:32:22.288Z] 20:32:22     INFO - STDOUT:             :raises ValueError: If the response body does not contain a
[task 2020-11-13T20:32:22.289Z] 20:32:22     INFO - STDOUT:                 `value` key.
[task 2020-11-13T20:32:22.289Z] 20:32:22     INFO - STDOUT:             """
[task 2020-11-13T20:32:22.289Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.290Z] 20:32:22     INFO - STDOUT:         response = self.transport.send(
[task 2020-11-13T20:32:22.290Z] 20:32:22     INFO - STDOUT:             method, url, body,
[task 2020-11-13T20:32:22.291Z] 20:32:22     INFO - STDOUT:             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2020-11-13T20:32:22.291Z] 20:32:22     INFO - STDOUT:             session=self, timeout=timeout)
[task 2020-11-13T20:32:22.292Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.292Z] 20:32:22     INFO - STDOUT:         if response.status != 200:
[task 2020-11-13T20:32:22.292Z] 20:32:22     INFO - STDOUT:             err = error.from_response(response)
[task 2020-11-13T20:32:22.292Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.292Z] 20:32:22     INFO - STDOUT:             if isinstance(err, error.InvalidSessionIdException):
[task 2020-11-13T20:32:22.294Z] 20:32:22     INFO - STDOUT:                 # The driver could have already been deleted the session.
[task 2020-11-13T20:32:22.294Z] 20:32:22     INFO - STDOUT:                 self.session_id = None
[task 2020-11-13T20:32:22.294Z] 20:32:22     INFO - STDOUT:     
[task 2020-11-13T20:32:22.295Z] 20:32:22     INFO - STDOUT: >           raise err
[task 2020-11-13T20:32:22.295Z] 20:32:22     INFO - STDOUT: E           NoSuchAlertException: no such alert (404): 
[task 2020-11-13T20:32:22.295Z] 20:32:22     INFO - STDOUT: E           
[task 2020-11-13T20:32:22.296Z] 20:32:22     INFO - STDOUT: E           Remote-end stacktrace:
[task 2020-11-13T20:32:22.297Z] 20:32:22     INFO - STDOUT: E           
[task 2020-11-13T20:32:22.297Z] 20:32:22     INFO - STDOUT: E           WebDriverError@chrome://marionette/content/error.js:181:5
[task 2020-11-13T20:32:22.297Z] 20:32:22     INFO - STDOUT: E           NoSuchAlertError@chrome://marionette/content/error.js:382:5
[task 2020-11-13T20:32:22.298Z] 20:32:22     INFO - STDOUT: E           GeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3476:11
[task 2020-11-13T20:32:22.298Z] 20:32:22     INFO - STDOUT: E           GeckoDriver.prototype.getTextFromDialog@chrome://marionette/content/driver.js:3421:8
[task 2020-11-13T20:32:22.299Z] 20:32:22     INFO - STDOUT: E           despatch@chrome://marionette/content/server.js:297:40
[task 2020-11-13T20:32:22.299Z] 20:32:22     INFO - STDOUT: E           execute@chrome://marionette/content/server.js:267:16
[task 2020-11-13T20:32:22.299Z] 20:32:22     INFO - STDOUT: E           onPacket/<@chrome://marionette/content/server.js:240:20
[task 2020-11-13T20:32:22.300Z] 20:32:22     INFO - STDOUT: E           onPacket@chrome://marionette/content/server.js:241:9
[task 2020-11-13T20:32:22.301Z] 20:32:22     INFO - STDOUT: E           _onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2020-11-13T20:32:22.301Z] 20:32:22     INFO - STDOUT: body       = None
[task 2020-11-13T20:32:22.301Z] 20:32:22     INFO - STDOUT: err        = <NoSuchAlertException http_status=404>
[task 2020-11-13T20:32:22.301Z] 20:32:22     INFO - STDOUT: method     = 'GET'
[task 2020-11-13T20:32:22.301Z] 20:32:22     INFO - STDOUT: response   = <Response status=404 error=<NoSuchAlertException http_status=404>>
[task 2020-11-13T20:32:22.302Z] 20:32:22     INFO - STDOUT: self       = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>
[task 2020-11-13T20:32:22.303Z] 20:32:22     INFO - STDOUT: timeout    = None
[task 2020-11-13T20:32:22.303Z] 20:32:22     INFO - STDOUT: url        = 'session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/alert/text'
[task 2020-11-13T20:32:22.303Z] 20:32:22     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2020-11-13T20:32:22.304Z] 20:32:22     INFO - STDOUT: :544: NoSuchAlertException
[task 2020-11-13T20:32:22.304Z] 20:32:22     INFO - STDOUT: =============================== warnings summary ===============================
[task 2020-11-13T20:32:22.306Z] 20:32:22     INFO - STDOUT: <undetermined location>
[task 2020-11-13T20:32:22.307Z] 20:32:22     INFO - STDOUT:   Module already imported so cannot be rewritten: tests.support.fixtures
[task 2020-11-13T20:32:22.307Z] 20:32:22     INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html
[task 2020-11-13T20:32:22.307Z] 20:32:22     INFO - STDOUT: ================ 1 failed, 8 passed, 1 warnings in 2.31 seconds ================
[task 2020-11-13T20:32:22.315Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.315Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_null_parameter_value 
[task 2020-11-13T20:32:22.316Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_no_top_browsing_context 
[task 2020-11-13T20:32:22.316Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_no_browsing_context 
[task 2020-11-13T20:32:22.316Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt[alert] 
[task 2020-11-13T20:32:22.316Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt[confirm] 
[task 2020-11-13T20:32:22.316Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt[prompt] 
[task 2020-11-13T20:32:22.316Z] 20:32:22     INFO - TEST-PASS | /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt_twice[alert] 
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt_twice[confirm] - NoSuchAlertException: no such alert (404):
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - session = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - dialog_type = 'confirm'
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -     @pytest.mark.parametrize("dialog_type", ["alert", "confirm", "prompt"])
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -     def test_abort_by_user_prompt_twice(session, dialog_type):
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -         response = execute_async_script(
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -             session,
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -             "window.{0}('Hello'); window.{0}('Bye'); arguments[0](1);".format(dialog_type))
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -         assert_success(response, None)
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -         session.alert.accept()
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -         # The first alert has been accepted by the user prompt handler, the second one remains.
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -         # FIXME: this is how browsers currently work, but the spec should clarify if this is the
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -         #        expected behavior, see https://github.com/w3c/webdriver/issues/1153.
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - >       assert session.alert.text == "Bye"
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - dialog_type = 'confirm'
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - response   = <Responsetatus=200 body={"value": null}>
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - session    = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - tests/web-platform/tests/webdriver/tests/execute_async_script/execute_async.py:56: 
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:19: in inner
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -     return func(self, *args, **kwargs)
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:424: in text
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO -     return self.session.send_session_command("GET", "alert/text")
[task 2020-11-13T20:32:22.317Z] 20:32:22     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:580: in send_session_command
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -     return self.send_command(method, url, body, timeout)
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO - self = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>, method = 'GET'
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO - url = 'session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/alert/text', body = None
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO - timeout = None
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -     def send_command(self, method, url, body=None, timeout=None):
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -         """
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -             Send a command to the remote end and validate its success.
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -             :param method: HTTP method to use in request.
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -             :param uri: "Command part" of the HTTP request URL,
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -                 e.g. `window/rect`.
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -             :param body: Optional body of the HTTP request.
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -             :return: `None` if the HTTP response body was empty, otherwise
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -                 the `value` field returned after parsing the response
[task 2020-11-13T20:32:22.318Z] 20:32:22     INFO -                 body as JSON.
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             :raises error.WebDriverException: If the remote end returns
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -                 an error.
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             :raises ValueError: If the response body does not contain a
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -                 `value` key.
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             """
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -         response = self.transport.send(
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             method, url, body,
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             session=self, timeout=timeout)
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -         if response.status != 200:
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             err = error.from_response(response)
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -             if isinstance(err, error.InvalidSessionIdException):
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -                 # The driver could have already been deleted the session.
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -                 self.session_id = None
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO -     
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO - >           raise err
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO - E           NoSuchAlertException: no such alert (404): 
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO - E           
[task 2020-11-13T20:32:22.319Z] 20:32:22     INFO - E           Remote-end stacktrace:
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           WebDriverError@chrome://marionette/content/error.js:181:5
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           NoSuchAlertError@chrome://marionette/content/error.js:382:5
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           GeckoDriver.prototype._checkIfAlertIsPresent@chrome://marionette/content/driver.js:3476:11
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           GeckoDriver.prototype.getTextFromDialog@chrome://marionette/content/driver.js:3421:8
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           despatch@chrome://marionette/content/server.js:297:40
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           execute@chrome://marionette/content/server.js:267:16
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           onPacket/<@chrome://marionette/content/server.js:240:20
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           onPacket@chrome://marionette/content/server.js:241:9
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - E           _onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - body       = None
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - err        = <NoSuchAlertException http_status=404>
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - method     = 'GET'
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - response   = <Response status=404 error=<NoSuchAlertException http_status=404>>
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - self       = <Session 6d3555e8-58e8-4602-86e9-15fa7fd2065f>
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - timeout    = None
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - url        = 'session/6d3555e8-58e8-4602-86e9-15fa7fd2065f/alert/text'
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - 
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:544: NoSuchAlertException
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - .
[task 2020-11-13T20:32:22.320Z] 20:32:22     INFO - TEST-OK | /webdriver/tests/execute_async_script/execute_async.py | took 2376ms
[task 2020-11-13T20:32:22.376Z] 20:32:22     INFO - Closing logging queue
[task 2020-11-13T20:32:22.376Z] 20:32:22     INFO - queue closed
[task 2020-11-13T20:32:22.377Z] 20:32:22     INFO - Starting runner
[task 2020-11-13T20:32:22.403Z] 20:32:22     INFO - PID 12780 | 1605299542395	geckodriver	INFO	Listening on 127.0.0.1:52709
[task 2020-11-13T20:32:22.404Z] 20:32:22     INFO - WebDriver HTTP server listening at http://127.0.0.1:52709/
[task 2020-11-13T20:32:22.404Z] 20:32:22     INFO - TEST-START | /webdriver/tests/execute_async_script/promise.py```
Summary: Intermittent /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt_twice[confirm] - NoSuchAlertException: no such alert (404): → Intermittent /webdriver/tests/execute_async_script/execute_async.py | test_abort_by_user_prompt_twice[<random>] - NoSuchAlertException: no such alert (404):

Those failures are appearing now because on bug 1459118 we re-enabled these tests. Lets see how often they are failing, and if we have to partially skip them again. We will prioritize the work on bug 1560015, which should get this fixed.

It fails more often as I thought. As such I requested a backout of the causing patch over on bug 1459118.

The backout happened and I'm going to dupe this bug now given that bug 1459118 covers all scenarios now.

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.